igt@gem_exec_schedule@in-order@vcs0 on shard-mtlp@CI_DRM

Detail	Value
Duration	0.10 seconds
Hostname	shard-mtlp-6
Igt-Version	IGT-Version: 2.1-gc4a9bee16 (x86_64) (Linux: 6.16.0-CI_DRM_16966-g922b0dea064e+ x86_64)
Out	Starting dynamic subtest: vcs0 Dynamic subtest vcs0: SUCCESS (0.099s)
Err	Starting dynamic subtest: vcs0 Dynamic subtest vcs0: SUCCESS (0.099s)
Dmesg Scroll to first warning	<6> [520.499168] [IGT] gem_exec_schedule: starting dynamic subtest vcs0 <7> [520.499308] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <7> [520.507459] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <3> [520.508303] guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id)) <0> [520.508548] Dumping ftrace buffer: <0> [520.508581] --------------------------------- <0> [520.509292] kworker/-170 21..... 49725322us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1a:2, current 2 <0> [520.509431] kworker/-170 21..... 49728691us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1b:2, current 2 <0> [520.509611] kworker/-170 21..... 49728738us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1c:2, current 2 <0> [520.509914] kworker/-170 21..... 80760741us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 31:2, current 2 <0> [520.510221] kworker/-170 21..... 80761393us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:5, current 5 <0> [520.510357] kworker/-170 21..... 80761420us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:6, current 7 <0> [520.510585] kworker/-170 21..... 80761428us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:7, current 7 <0> [520.510789] kworker/-170 21..... 80761447us : __engine_park: 0000:00:02.0 bcs0: parked <0> [520.511066] kworker/-171 20..... 80894051us : intel_gt_runtime_suspend: 0000:00:02.0 <0> [520.511279] kworker/-171 20..... 80895374us : intel_gt_runtime_suspend: 0000:00:02.0 <0> [520.511571] kworker/-443 21..... 84730957us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:7, current 6 <0> [520.511873] kworker/-443 21D..1. 84731012us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:7, current 6 <7> [520.511760] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.512170] kworker/-170 21..... 86713057us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4b:4, current 4 <4> [520.512301] kauditd_printk_skb: 113 callbacks suppressed <0> [520.512306] kworker/-170 21..... 89784140us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4f:2, current 2 <5> [520.512307] audit: type=1400 audit(1754584918.863:125): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512534] audit: type=1400 audit(1754584918.863:126): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <0> [520.512542] kworker/-170 21..... 89784534us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:8, current 8 <5> [520.512597] audit: type=1400 audit(1754584918.863:127): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512654] audit: type=1400 audit(1754584918.863:128): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512709] audit: type=1400 audit(1754584918.863:129): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512765] audit: type=1400 audit(1754584918.863:130): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <0> [520.512774] kworker/-170 21..... 89784564us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:8, current 9 <5> [520.512819] audit: type=1400 audit(1754584918.863:131): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512874] audit: type=1400 audit(1754584918.863:132): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512927] audit: type=1400 audit(1754584918.863:133): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <5> [520.512984] audit: type=1400 audit(1754584918.863:134): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0 <0> [520.513068] kworker/-170 21..... 89784570us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:9, current 9 <0> [520.513351] kworker/-170 21..... 89784590us : __engine_park: 0000:00:02.0 bcs0: parked <0> [520.513576] kworker/-170 21..... 181752088us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:4, current 142 <0> [520.513700] kworker/-170 21..... 181752117us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:6, current 142 <0> [520.513824] kworker/-170 21..... 181752126us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:8, current 142 <0> [520.513948] kworker/-170 21..... 181752135us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:10, current 142 <0> [520.514072] kworker/-170 21..... 181752143us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:12, current 142 <0> [520.514208] kworker/-170 21..... 181752151us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:14, current 142 <0> [520.514348] kworker/-170 21..... 181752161us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:16, current 142 <0> [520.514487] kworker/-170 21..... 181752170us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:18, current 142 <0> [520.514616] kworker/-170 21..... 181752178us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:20, current 142 <0> [520.514746] kworker/-170 21..... 181752185us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:22, current 142 <0> [520.514875] kworker/-170 21..... 181752194us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:24, current 142 <0> [520.515004] kworker/-170 21..... 181752202us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:26, current 142 <0> [520.515133] kworker/-170 21..... 181752209us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:28, current 142 <0> [520.515262] kworker/-170 21..... 181752217us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:30, current 142 <0> [520.515391] kworker/-170 21..... 181752223us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:32, current 142 <0> [520.515528] kworker/-170 21..... 181752230us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:34, current 142 <0> [520.515661] kworker/-170 21..... 181752237us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:36, current 142 <7> [520.515478] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.515789] kworker/-170 21..... 181752247us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:38, current 142 <0> [520.515899] kworker/-170 21..... 181752260us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:40, current 142 <0> [520.516009] kworker/-170 21..... 181752270us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:42, current 142 <0> [520.516138] kworker/-170 21..... 181752277us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:44, current 142 <0> [520.516267] kworker/-170 21..... 181752287us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:46, current 142 <0> [520.516403] kworker/-170 21..... 181752295us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:48, current 142 <0> [520.516535] kworker/-170 21..... 181752303us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:50, current 142 <0> [520.516655] kworker/-170 21..... 181752309us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:52, current 142 <0> [520.516775] kworker/-170 21..... 181752318us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:54, current 142 <0> [520.516896] kworker/-170 21..... 181752325us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:56, current 142 <0> [520.517016] kworker/-170 21..... 181752332us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:58, current 142 <0> [520.517136] kworker/-170 21..... 181752339us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:60, current 142 <0> [520.517257] kworker/-170 21..... 181752346us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:62, current 142 <0> [520.517377] kworker/-170 21..... 181752353us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:64, current 142 <0> [520.517497] kworker/-170 21..... 181752359us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:66, current 142 <0> [520.517617] kworker/-170 21..... 181752365us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:68, current 142 <0> [520.517739] kworker/-170 21..... 181752372us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:70, current 142 <0> [520.517860] kworker/-170 21..... 181752378us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:72, current 142 <0> [520.517981] kworker/-170 21..... 181752385us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:74, current 142 <0> [520.518101] kworker/-170 21..... 181752392us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:76, current 142 <0> [520.518223] kworker/-170 21..... 181752399us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:78, current 142 <0> [520.518344] kworker/-170 21..... 181752406us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:80, current 142 <0> [520.518465] kworker/-170 21..... 181752412us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:82, current 142 <0> [520.518585] kworker/-170 21..... 181752419us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:84, current 142 <0> [520.518705] kworker/-170 21..... 181752425us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:86, current 142 <0> [520.518825] kworker/-170 21..... 181752433us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:88, current 142 <0> [520.518946] kworker/-170 21..... 181752438us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:90, current 142 <0> [520.519066] kworker/-170 21..... 181752446us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:92, current 142 <0> [520.519185] kworker/-170 21..... 181752452us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:94, current 142 <0> [520.519304] kworker/-170 21..... 181752459us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:96, current 142 <0> [520.519424] kworker/-170 21..... 181752465us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:98, current 142 <0> [520.519543] kworker/-170 21..... 181752471us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:100, current 142 <0> [520.519663] kworker/-170 21..... 181752480us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:102, current 142 <0> [520.519783] kworker/-170 21..... 181752495us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:104, current 142 <0> [520.519902] kworker/-170 21..... 181752504us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:106, current 142 <0> [520.520020] kworker/-170 21..... 181752512us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:108, current 142 <0> [520.520139] kworker/-170 21..... 181752520us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:110, current 142 <0> [520.520258] kworker/-170 21..... 181752528us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:112, current 142 <0> [520.520377] kworker/-170 21..... 181752535us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:114, current 142 <0> [520.520496] kworker/-170 21..... 181752541us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:116, current 142 <0> [520.520615] kworker/-170 21..... 181752593us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:118, current 142 <0> [520.520738] kworker/-170 21..... 181752601us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:120, current 142 <0> [520.520860] kworker/-170 21..... 181752608us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:122, current 142 <0> [520.520979] kworker/-170 21..... 181752616us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:124, current 142 <0> [520.521099] kworker/-170 21..... 181752623us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:126, current 142 <7> [520.521129] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.521218] kworker/-170 21..... 181752630us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:128, current 142 <0> [520.521321] kworker/-170 21..... 181752637us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:130, current 142 <0> [520.521441] kworker/-170 21..... 181752644us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:132, current 142 <0> [520.521561] kworker/-170 21..... 181752651us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:134, current 142 <0> [520.521680] kworker/-170 21..... 181752657us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:136, current 142 <0> [520.521799] kworker/-170 21..... 181752664us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:138, current 142 <0> [520.521918] kworker/-170 21..... 181752670us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:140, current 142 <0> [520.522038] kworker/-170 21..... 181752677us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:142, current 142 <0> [520.522158] kworker/-170 21..... 181753513us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:4, current 142 <0> [520.522278] kworker/-170 21..... 181753529us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:6, current 142 <0> [520.522399] kworker/-170 21..... 181753536us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:8, current 142 <0> [520.522526] kworker/-170 21..... 181753544us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:10, current 142 <0> [520.522650] kworker/-170 21..... 181753552us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:12, current 142 <0> [520.522774] kworker/-170 21..... 181753559us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:14, current 142 <0> [520.522897] kworker/-170 21..... 181753566us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:16, current 142 <0> [520.523020] kworker/-170 21..... 181753572us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:18, current 142 <0> [520.523143] kworker/-170 21..... 181753579us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:20, current 142 <0> [520.523266] kworker/-170 21..... 181753587us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:22, current 142 <0> [520.523389] kworker/-170 21..... 181753598us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:24, current 142 <0> [520.523512] kworker/-170 21..... 181753606us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:26, current 142 <0> [520.523635] kworker/-170 21..... 181753629us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:28, current 142 <0> [520.523758] kworker/-170 21..... 181753639us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:30, current 142 <7> [520.523739] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.523881] kworker/-170 21..... 181753646us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:32, current 142 <0> [520.523986] kworker/-170 21..... 181753658us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:34, current 142 <0> [520.524109] kworker/-170 21..... 181753667us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:36, current 142 <0> [520.524232] kworker/-170 21..... 181753676us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:38, current 142 <0> [520.524355] kworker/-170 21..... 181753683us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:40, current 142 <0> [520.524477] kworker/-170 21..... 181753690us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:42, current 142 <0> [520.524600] kworker/-170 21..... 181753697us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:44, current 142 <0> [520.524723] kworker/-170 21..... 181753707us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:46, current 142 <0> [520.524846] kworker/-170 21..... 181753713us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:48, current 142 <0> [520.524966] kworker/-170 21..... 181753721us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:50, current 142 <0> [520.525085] kworker/-170 21..... 181753728us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:52, current 142 <0> [520.525204] kworker/-170 21..... 181753735us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:54, current 142 <0> [520.525324] kworker/-170 21..... 181753743us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:56, current 142 <0> [520.525443] kworker/-170 21..... 181753751us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:58, current 142 <0> [520.525563] kworker/-170 21..... 181753757us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:60, current 142 <0> [520.525683] kworker/-170 21..... 181753764us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:62, current 142 <0> [520.525803] kworker/-170 21..... 181753771us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:64, current 142 <0> [520.525922] kworker/-170 21..... 181753777us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:66, current 142 <0> [520.526041] kworker/-170 21..... 181753784us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:68, current 142 <0> [520.526160] kworker/-170 21..... 181753791us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:70, current 142 <7> [520.526169] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.526280] kworker/-170 21..... 181753798us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:72, current 142 <0> [520.526382] kworker/-170 21..... 181753805us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:74, current 142 <0> [520.526502] kworker/-170 21..... 181753821us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:76, current 142 <0> [520.526621] kworker/-170 21..... 181753829us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:78, current 142 <0> [520.526739] kworker/-170 21..... 181753836us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:80, current 142 <0> [520.526858] kworker/-170 21..... 181753842us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:82, current 142 <0> [520.526976] kworker/-170 21..... 181753849us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:84, current 142 <0> [520.527095] kworker/-170 21..... 181753856us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:86, current 142 <0> [520.527214] kworker/-170 21..... 181753862us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:88, current 142 <0> [520.527333] kworker/-170 21..... 181753871us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:90, current 142 <0> [520.527452] kworker/-170 21..... 181753885us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:92, current 142 <0> [520.527571] kworker/-170 21..... 181753894us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:94, current 142 <0> [520.527690] kworker/-170 21..... 181753901us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:96, current 142 <0> [520.527809] kworker/-170 21..... 181753910us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:98, current 142 <0> [520.527928] kworker/-170 21..... 181753919us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:100, current 142 <0> [520.528048] kworker/-170 21..... 181753928us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:102, current 142 <0> [520.528171] kworker/-170 21..... 181753937us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:104, current 142 <0> [520.528298] kworker/-170 21..... 181753944us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:106, current 142 <0> [520.528422] kworker/-170 21..... 181753951us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:108, current 142 <0> [520.528546] kworker/-170 21..... 181753959us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:110, current 142 <7> [520.528526] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.528669] kworker/-170 21..... 181753967us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:112, current 142 <0> [520.528774] kworker/-170 21..... 181753973us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:114, current 142 <0> [520.528898] kworker/-170 21..... 181753980us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:116, current 142 <0> [520.529019] kworker/-170 21..... 181753986us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:118, current 142 <0> [520.529138] kworker/-170 21..... 181753993us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:120, current 142 <0> [520.529259] kworker/-170 21..... 181753999us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:122, current 142 <0> [520.529385] kworker/-170 21..... 181754006us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:124, current 142 <0> [520.529509] kworker/-170 21..... 181754013us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:126, current 142 <0> [520.529629] kworker/-170 21..... 181754019us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:128, current 142 <0> [520.529748] kworker/-170 21..... 181754025us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:130, current 142 <0> [520.529868] kworker/-170 21..... 181754031us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:132, current 142 <0> [520.529988] kworker/-170 21..... 181754038us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:134, current 142 <0> [520.530108] kworker/-170 21..... 181754045us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:136, current 142 <0> [520.530228] kworker/-170 21..... 181754051us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:138, current 142 <0> [520.530348] kworker/-170 21..... 181754057us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:140, current 142 <0> [520.530468] kworker/-170 21..... 181754064us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:142, current 142 <0> [520.530587] kworker/-170 21..... 181754228us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:4, current 142 <0> [520.530706] kworker/-170 21..... 181754237us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:6, current 142 <0> [520.530826] kworker/-170 21..... 181754244us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:8, current 142 <7> [520.530787] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.530946] kworker/-170 21..... 181754251us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:10, current 142 <0> [520.531049] kworker/-170 21..... 181754258us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:12, current 142 <0> [520.531172] kworker/-170 21..... 181754264us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:14, current 142 <0> [520.531299] kworker/-170 21..... 181754276us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:16, current 142 <0> [520.531428] kworker/-170 21..... 181754285us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:18, current 142 <0> [520.531552] kworker/-170 21..... 181754293us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:20, current 142 <0> [520.531676] kworker/-170 21..... 181754302us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:22, current 142 <0> [520.531799] kworker/-170 21..... 181754311us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:24, current 142 <0> [520.531922] kworker/-170 21..... 181754319us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:26, current 142 <0> [520.532046] kworker/-170 21..... 181754326us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:28, current 142 <0> [520.532170] kworker/-170 21..... 181754337us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:30, current 142 <0> [520.532293] kworker/-170 21..... 181754344us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:32, current 142 <0> [520.532420] kworker/-170 21..... 181754351us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:34, current 142 <0> [520.532542] kworker/-170 21..... 181754358us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:36, current 142 <0> [520.532662] kworker/-170 21..... 181754366us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:38, current 142 <0> [520.532782] kworker/-170 21..... 181754374us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:40, current 142 <0> [520.532902] kworker/-170 21..... 181754381us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:42, current 142 <0> [520.533022] kworker/-170 21..... 181754387us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:44, current 142 <0> [520.533143] kworker/-170 21..... 181754394us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:46, current 142 <0> [520.533264] kworker/-170 21..... 181754400us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:48, current 142 <0> [520.533387] kworker/-170 21..... 181754410us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:50, current 142 <7> [520.533445] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.533516] kworker/-170 21..... 181754418us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:52, current 142 <0> [520.533625] kworker/-170 21..... 181754426us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:54, current 142 <0> [520.533751] kworker/-170 21..... 181754432us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:56, current 142 <0> [520.533870] kworker/-170 21..... 181754439us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:58, current 142 <0> [520.533989] kworker/-170 21..... 181754445us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:60, current 142 <0> [520.534114] kworker/-170 21..... 181754452us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:62, current 142 <0> [520.534241] kworker/-170 21..... 181754458us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:64, current 142 <0> [520.534369] kworker/-170 21..... 181754466us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:66, current 142 <0> [520.534493] kworker/-170 21..... 181754472us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:68, current 142 <0> [520.534613] kworker/-170 21..... 181754478us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:70, current 142 <0> [520.534734] kworker/-170 21..... 181754485us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:72, current 142 <0> [520.534855] kworker/-170 21..... 181754491us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:74, current 142 <0> [520.534976] kworker/-170 21..... 181754497us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:76, current 142 <0> [520.535097] kworker/-170 21..... 181754506us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:78, current 142 <0> [520.535217] kworker/-170 21..... 181754519us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:80, current 142 <0> [520.535344] kworker/-170 21..... 181754528us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:82, current 142 <0> [520.535472] kworker/-170 21..... 181754537us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:84, current 142 <0> [520.535598] kworker/-170 21..... 181754546us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:86, current 142 <0> [520.535718] kworker/-170 21..... 181754554us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:88, current 142 <0> [520.535838] kworker/-170 21..... 181754562us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:90, current 142 <0> [520.535957] kworker/-170 21..... 181754569us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:92, current 142 <0> [520.536077] kworker/-170 21..... 181754576us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:94, current 142 <0> [520.536198] kworker/-170 21..... 181754584us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:96, current 142 <7> [520.536148] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.536325] kworker/-170 21..... 181754593us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:98, current 142 <0> [520.536451] kworker/-170 21..... 181754599us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:100, current 142 <0> [520.536578] kworker/-170 21..... 181754605us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:102, current 142 <0> [520.536702] kworker/-170 21..... 181754612us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:104, current 142 <0> [520.536822] kworker/-170 21..... 181754619us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:106, current 142 <0> [520.536947] kworker/-170 21..... 181754625us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:108, current 142 <0> [520.537075] kworker/-170 21..... 181754631us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:110, current 142 <0> [520.537203] kworker/-170 21..... 181754637us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:112, current 142 <0> [520.537330] kworker/-170 21..... 181754643us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:114, current 142 <0> [520.537457] kworker/-170 21..... 181754649us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:116, current 142 <0> [520.537580] kworker/-170 21..... 181754656us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:118, current 142 <0> [520.537708] kworker/-170 21..... 181754663us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:120, current 142 <0> [520.537833] kworker/-170 21..... 181754671us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:122, current 142 <0> [520.537957] kworker/-170 21..... 181754678us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:124, current 142 <0> [520.538081] kworker/-170 21..... 181754684us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:126, current 142 <0> [520.538205] kworker/-170 21..... 181754691us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:128, current 142 <0> [520.538333] kworker/-170 21..... 181754698us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:130, current 142 <0> [520.538459] kworker/-170 21..... 181754708us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:132, current 142 <0> [520.538586] kworker/-170 21..... 181754715us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:134, current 142 <0> [520.538706] kworker/-170 21..... 181754721us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:136, current 142 <0> [520.538826] kworker/-170 21..... 181754728us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:138, current 142 <7> [520.538826] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.538946] kworker/-170 21..... 181754735us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:140, current 142 <0> [520.539053] kworker/-170 21..... 181754744us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:142, current 142 <0> [520.539181] kworker/-170 21..... 187764736us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1c3:2, current 2 <0> [520.539308] kworker/-170 21..... 187765444us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:21, current 21 <0> [520.539437] kworker/-443 21..... 202229964us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:22, current 21 <0> [520.539565] kworker/-443 21D..1. 202229981us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:22, current 21 <0> [520.539688] kworker/-170 21..... 213684022us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:22, current 22 <0> [520.539807] kworker/-170 21..... 213684072us : __engine_park: 0000:00:02.0 rcs0: parked <0> [520.539923] kworker/-170 21..... 213684247us : __gt_park: 0000:00:02.0 <0> [520.540043] kworker/-430 20..... 217780135us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:23, current 22 <0> [520.540170] kworker/-430 20D..1. 217780165us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:23, current 22 <0> [520.540292] gem_exec-2345 20..... 229734151us : __gt_unpark: 0000:00:02.0 <0> [520.540408] kworker/-171 20..... 229734728us : __gt_park: 0000:00:02.0 <0> [520.540528] kworker/-170 21..... 231731703us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1cc:2, current 2 <0> [520.540648] kworker/-170 21..... 231731968us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:4, current 5 <0> [520.540775] kworker/-170 21..... 231731975us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:5, current 5 <0> [520.540896] kworker/-170 21..... 231732040us : __gt_park: 0000:00:02.0 <0> [520.541018] kworker/-170 21..... 233779778us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1ce:2, current 2 <0> [520.541146] kworker/-170 21..... 233780013us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:4, current 5 <7> [520.541105] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.541274] kworker/-170 21..... 233780023us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:5, current 5 <0> [520.541392] kworker/-170 21..... 233780103us : __gt_park: 0000:00:02.0 <0> [520.541507] kworker/-170 21..... 235763730us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1d0:2, current 2 <0> [520.541627] kworker/-170 21..... 235763958us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence e:4, current 5 <0> [520.541748] kworker/-170 21..... 235763968us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence e:5, current 5 <0> [520.541862] kworker/-170 21..... 235764056us : __gt_park: 0000:00:02.0 <0> [520.541974] kms_curs-2359 21..... 263198421us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [520.542089] kms_curs-2359 21..... 263198775us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0> [520.542213] kworker/-170 21..... 263731331us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 291:2, current 2 <0> [520.542341] kworker/-170 21..... 263731821us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:29, current 29 <0> [520.542466] kworker/-170 21..... 263731854us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:506, current 509 <0> [520.542587] kworker/-170 21..... 263731861us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:507, current 509 <0> [520.542707] kworker/-170 21..... 263731901us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:508, current 509 <0> [520.542827] kworker/-170 21..... 263731919us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:509, current 509 <0> [520.542942] kworker/-170 21..... 263731939us : __engine_park: 0000:00:02.0 bcs0: parked <0> [520.543052] gem_exec-2401 20..... 283413341us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:88c active <0> [520.543163] gem_exec-2381 20..... 283431623us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:876 pin ring:{start:f29a0000, head:0000, tail:0000} <0> [520.543297] gem_exec-2381 20..... 283431915us : __i915_request_commit: 0000:00:02.0 vcs1: fence 876:2, current 0 <0> [520.543428] gem_exec-2381 20D..1. 283431935us : __i915_request_submit: 0000:00:02.0 vcs1: fence 876:2, current 0 <0> [520.543557] gem_exec-2381 20..... 283432151us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11308, current 11310 <0> [520.543683] gem_exec-2381 20..... 283432255us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11312, current 11310 <0> [520.543804] gem_exec-2381 20..... 283432376us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11310, current 11310 <0> [520.543926] gem_exec-2381 20..... 283432423us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11314, current 11310 <0> [520.544046] gem_exec-2381 20..... 283432662us : intel_rps_boost: 0000:00:02.0 boost fence:298:2c30 <7> [520.544003] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.544174] gem_exec-2381 20..... 283433792us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11312, current 11314 <0> [520.544307] gem_exec-2381 20..... 283433992us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11316, current 11314 <0> [520.544437] gem_exec-2381 20D..1. 283434012us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11316, current 11314 <0> [520.544558] gem_exec-2384 20..... 283436056us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:880 pin ring:{start:f28c3000, head:0000, tail:0000} <0> [520.544692] gem_exec-2384 20..... 283436779us : __i915_request_commit: 0000:00:02.0 rcs0: fence 880:2, current 0 <0> [520.544817] gem_exec-2384 20D..1. 283436787us : __i915_request_submit: 0000:00:02.0 rcs0: fence 880:2, current 0 <0> [520.544940] gem_exec-2384 20..... 283436900us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11314, current 11316 <0> [520.545064] gem_exec-2384 20..... 283437285us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11318, current 11316 <0> [520.545186] gem_exec-2384 20D..1. 283437293us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11318, current 11316 <0> [520.545311] gem_exec-2384 20..... 283437350us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11316, current 11316 <0> [520.545442] gem_exec-2384 20..... 283437369us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11320, current 11316 <0> [520.545571] gem_exec-2384 20D..1. 283437377us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11320, current 11316 <0> [520.545697] gem_exec-2384 20..... 283437491us : intel_rps_boost: 0000:00:02.0 boost fence:298:2c36 <0> [520.545825] gem_exec-2384 21..... 283438104us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11318, current 11320 <0> [520.545953] gem_exec-2384 21..... 283438161us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11322, current 11320 <0> [520.546077] gem_exec-2384 21D..1. 283438181us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11322, current 11320 <0> [520.546195] gem_exec-2384 21..... 283510983us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad active <0> [520.546324] gem_exec-2384 21..... 283563608us : intel_timeline_pin: 0000:00:02.0 timeline:8ad using HWSP offset:f2202000 <0> [520.546451] gem_exec-2384 21..... 283584101us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad pin ring:{start:f2263000, head:0000, tail:0000} <7> [520.546425] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.546577] gem_exec-2384 21..... 283584129us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:8ad unpin <0> [520.546677] gem_exec-2384 21..... 283584208us : __intel_context_retire: 0000:00:02.0 rcs0: context:8ad retire runtime: { total:0ns, avg:0ns } <0> [520.546797] gem_exec-2384 21..... 283584214us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad active <0> [520.546921] gem_exec-2384 21..... 283584217us : intel_timeline_pin: 0000:00:02.0 timeline:8ad using HWSP offset:f2202000 <0> [520.547039] gem_exec-2384 21..... 283584223us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad pin ring:{start:f2263000, head:0000, tail:0000} <0> [520.547162] gem_exec-2384 21..... 283584244us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:8ad unpin <0> [520.547279] gem_exec-2384 21..... 283584322us : __intel_context_retire: 0000:00:02.0 rcs0: context:8ad retire runtime: { total:0ns, avg:0ns } <0> [520.547397] gem_exec-2384 21..... 283584326us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad active <0> [520.547521] gem_exec-2384 21..... 283584328us : intel_timeline_pin: 0000:00:02.0 timeline:8ad using HWSP offset:f2202000 <0> [520.547638] gem_exec-2384 21..... 283584333us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad pin ring:{start:f2263000, head:0000, tail:0000} <0> [520.547772] gem_exec-2384 21..... 283584734us : __i915_request_commit: 0000:00:02.0 rcs0: fence 8ad:2, current 0 <0> [520.547902] gem_exec-2384 21D..1. 283584746us : __i915_request_submit: 0000:00:02.0 rcs0: fence 8ad:2, current 0 <0> [520.548031] gem_exec-2384 21..... 283585030us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11702, current 11700 <0> [520.548157] gem_exec-2384 21D..1. 283585042us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11702, current 11700 <0> [520.548279] gem_exec-2377 20..... 283585408us : intel_timeline_pin: 0000:00:02.0 timeline:8b2 using HWSP offset:f20f8000 <0> [520.548405] gem_exec-2384 21..... 283585418us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11700, current 11700 <0> [520.548536] gem_exec-2384 21..... 283585451us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11704, current 11700 <0> [520.548666] gem_exec-2384 21D..1. 283585459us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11704, current 11700 <0> [520.548795] gem_exec-2384 21..... 283585851us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11702, current 11704 <0> [520.548924] gem_exec-2407 20..... 285066276us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21418, current 21416 <7> [520.548899] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.549053] gem_exec-2407 20D..1. 285066303us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:21418, current 21416 <0> [520.549173] gem_exec-2407 20..... 285067300us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:735 active <0> [520.549300] gem_exec-2407 20..... 285067311us : intel_timeline_pin: 0000:00:02.0 timeline:735 using HWSP offset:f5275000 <0> [520.549421] gem_exec-2407 20..... 285067333us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:735 pin ring:{start:f530c000, head:0168, tail:0168} <0> [520.549555] gem_exec-2407 20..... 285067472us : __i915_request_commit: 0000:00:02.0 ccs0: fence 735:4, current 2 <0> [520.549686] gem_exec-2407 20D..1. 285067489us : __i915_request_submit: 0000:00:02.0 ccs0: fence 735:4, current 2 <0> [520.549816] gem_exec-2407 20..... 285067743us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21426, current 21420 <0> [520.549947] gem_exec-2407 20..... 285068104us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21428, current 21420 <0> [520.550078] gem_exec-2407 20..... 285069483us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21436, current 21430 <0> [520.550200] gem_exec-2407 20..... 285069801us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:8a7 active <0> [520.550327] gem_exec-2407 20.N... 285069859us : intel_timeline_pin: 0000:00:02.0 timeline:8a7 using HWSP offset:f2223000 <0> [520.550459] gem_exec-2395 20..... 285353776us : __i915_request_commit: 0000:00:02.0 ccs0: fence afe:2, current 0 <0> [520.550590] gem_exec-2395 20D..1. 285354801us : __i915_request_submit: 0000:00:02.0 ccs0: fence afe:2, current 0 <0> [520.550711] gem_exec-2394 20..... 286105474us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 pin ring:{start:eb539000, head:0000, tail:0000} <0> [520.550835] gem_exec-2394 20..... 286105541us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:bf4 unpin <0> [520.550955] gem_exec-2394 20..... 286105713us : __intel_context_retire: 0000:00:02.0 vecs0: context:bf4 retire runtime: { total:0ns, avg:0ns } <0> [520.551076] gem_exec-2394 20..... 286105724us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 active <0> [520.551202] gem_exec-2394 20..... 286105730us : intel_timeline_pin: 0000:00:02.0 timeline:bf4 using HWSP offset:eb449000 <0> [520.551321] gem_exec-2394 20..... 286105741us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 pin ring:{start:eb539000, head:0000, tail:0000} <7> [520.551283] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508 <0> [520.551444] gem_exec-2394 20..... 286105775us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:bf4 unpin <0> [520.551561] gem_exec-2394 20..... 286105961us : __intel_context_retire: 0000:00:02.0 vecs0: context:bf4 retire runtime: { total:0ns, avg:0ns } <0> [520.551681] gem_exec-2394 20..... 286105969us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 active <0> [520.551806] gem_exec-2394 20..... 286105973us : intel_timeline_pin: 0000:00:02.0 timeline:bf4 using HWSP offset:eb449000 <0> [520.551923] gem_exec-2394 20..... 286105983us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 pin ring:{start:eb539000, head:0000, tail:0000} <0> [520.552061] gem_exec-2394 20..... 286106177us : __i915_request_commit: 0000:00:02.0 vecs0: fence bf4:2, current 0 <0> [520.552194] gem_exec-2394 20D..1. 286106195us : __i915_request_submit: 0000:00:02.0 vecs0: fence bf4:2, current 0 <0> [520.552326] gem_exec-2394 20..... 286106392us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31730, current 31742 <0> [520.552458] gem_exec-2394 20..... 286106488us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:31754, current 31746 <0> [520.552589] gem_exec-2394 20..... 286106502us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31732, current 31746 <0> [520.552721] gem_exec-2394 20..... 286106523us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31734, current 31747 <0> [520.552852] gem_exec-2394 20..... 286106528us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31736, current 31748 <0> [520.552983] gem_exec-2394 20..... 286106531us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31738, current 31748 <0> [520.553115] gem_exec-2394 20..... 286106536us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31740, current 31748 <0> [520.553246] gem_exec-2394 20..... 286106540us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31742, current 31748 <0> [520.553376] gem_exec-2394 20..... 286106544us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31744, current 31748 <0> [520.553506] gem_exec-2394 20..... 286106547us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31746, current 31748 <0> [520.553636] gem_exec-2394 20..... 286106552us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31748, current 31749 <0> [520.553766] gem_exec-2394 20..... 286106572us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31750, current 31750 <0> [520.553897] gem_exec-2393 21..... 286107638us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31762, current 31764 <0> [520.554029] gem_exec-2393 21..... 286107685us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:31766, current 31764 <0> [520.554160] gem_exec-2393 21D..1. 286107698us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:31766, current 31764 <0> [520.554290] gem_exec-2394 20..... 286107716us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31764, current 31764 <0> [520.554421] gem_exec-2394 20..... 286107756us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:31768, current 31764 <0> [520.554552] gem_exec-2394 20D..1. 286107767us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:31768, current 31764 <0> [520.554674] gem_exec-2393 21..... 286108152us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 active <0> [520.554801] gem_exec-2393 21..... 286108166us : intel_timeline_pin: 0000:00:02.0 timeline:7c9 using HWSP offset:f3ec9000 <0> [520.554921] gem_exec-2393 21..... 286108190us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 pin ring:{start:f4057000, head:0210, tail:0210} <0> [520.555044] gem_exec-2393 21..... 286108309us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:7c9 unpin <0> [520.555163] gem_exec-2393 21..... 286108476us : __intel_context_retire: 0000:00:02.0 vcs1: context:7c9 retire runtime: { total:139178ns, avg:48813ns } <0> [520.555285] gem_exec-2393 21..... 286108485us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 active <0> [520.555409] gem_exec-2393 21..... 286108488us : intel_timeline_pin: 0000:00:02.0 timeline:7c9 using HWSP offset:f3ec9000 <0> [520.555528] gem_exec-2393 21..... 286108498us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 pin ring:{start:f4057000, head:0210, tail:0210} <0> [520.555650] gem_exec-2393 21..... 286108533us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:7c9 unpin <0> [520.555767] gem_exec-2393 21..... 286108700us : __intel_context_retire: 0000:00:02.0 vcs1: context:7c9 retire runtime: { total:139178ns, avg:48813ns } <0> [520.555887] gem_exec-2393 21..... 286108705us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 active <0> [520.556012] gem_exec-2393 21..... 286108708us : intel_timeline_pin: 0000:00:02.0 timeline:7c9 using HWSP offset:f3ec9000 <0> [520.556130] gem_exec-2393 21..... 286108717us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 pin ring:{start:f4057000, head:0210, tail:0210} <0> [520.556267] gem_exec-2393 21..... 286108843us : __i915_request_commit: 0000:00:02.0 vcs1: fence 7c9:8, current 6 <0> [520.556399] gem_exec-2393 21D..1. 286108854us : __i915_request_submit: 0000:00:02.0 vcs1: fence 7c9:8, current 6 <0> [520.556526] gem_exec-2385 20.N... 286111239us : intel_timeline_pin: 0000:00:02.0 timeline:bff using HWSP offset:eb2ba000 <0> [520.556647] gem_exec-2389 20..... 286255702us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c3c active <0> [520.556772] gem_exec-2389 20..... 286303032us : intel_timeline_pin: 0000:00:02.0 timeline:c3c using HWSP offset:eab35000 <0> [520.556891] gem_exec-2402 20..... 286485303us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:405 active <0> [520.557016] gem_exec-2402 20..... 286485320us : intel_timeline_pin: 0000:00:02.0 timeline:405 using HWSP offset:fbe81000 <0> [520.557135] gem_exec-2402 20..... 286485352us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:405 pin ring:{start:fbf50000, head:05a0, tail:05a0} <0> [520.557275] gem_exec-2402 20..... 286485644us : __i915_request_commit: 0000:00:02.0 rcs0: fence 405:10, current 8 <0> [520.557416] gem_exec-2402 20D..1. 286485897us : __i915_request_submit: 0000:00:02.0 rcs0: fence 405:10, current 8 <0> [520.557556] gem_exec-2377 21..... 286742828us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40366, current 40351 <0> [520.557692] gem_exec-2377 21D..1. 286742852us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40366, current 40352 <0> [520.557823] gem_exec-2377 21..... 286743210us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40352, current 40366 <0> [520.557955] gem_exec-2377 21..... 286743222us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40354, current 40366 <0> [520.558085] gem_exec-2377 21..... 286743232us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40356, current 40366 <0> [520.558217] gem_exec-2377 21..... 286743239us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40358, current 40366 <0> [520.558348] gem_exec-2377 21..... 286743247us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40360, current 40366 <0> [520.558479] gem_exec-2377 21..... 286743257us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40362, current 40366 <0> [520.558611] gem_exec-2377 21..... 286744756us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40414, current 40376 <0> [520.558741] gem_exec-2376 20..... 286744864us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40418, current 40376 <0> [520.558862] gem_exec-2377 21..... 286745138us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b31 active <0> [520.558989] gem_exec-2377 21..... 286745148us : intel_timeline_pin: 0000:00:02.0 timeline:b31 using HWSP offset:ecc7e000 <0> [520.559109] gem_exec-2376 20..... 286745154us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 active <0> [520.559235] gem_exec-2376 20..... 286745163us : intel_timeline_pin: 0000:00:02.0 timeline:4e4 using HWSP offset:fa14e000 <0> [520.559355] gem_exec-2377 21..... 286745168us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b31 pin ring:{start:ecd4c000, head:0168, tail:0168} <0> [520.559487] gem_exec-2376 20..... 286745181us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 pin ring:{start:fa1ce000, head:0370, tail:0370} <0> [520.559616] gem_exec-2376 20..... 286745244us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:4e4 unpin <0> [520.559749] gem_exec-2377 21..... 286745294us : __i915_request_commit: 0000:00:02.0 ccs0: fence b31:4, current 2 <0> [520.559882] gem_exec-2377 21D..1. 286745309us : __i915_request_submit: 0000:00:02.0 ccs0: fence b31:4, current 2 <0> [520.560002] gem_exec-2376 20..... 286745335us : __intel_context_retire: 0000:00:02.0 vecs0: context:4e4 retire runtime: { total:110929ns, avg:18550ns } <0> [520.560129] gem_exec-2376 20..... 286745345us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 active <0> [520.560262] gem_exec-2376 20..... 286745350us : intel_timeline_pin: 0000:00:02.0 timeline:4e4 using HWSP offset:fa14e000 <0> [520.560385] gem_exec-2376 20..... 286745363us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 pin ring:{start:fa1ce000, head:0370, tail:0370} <0> [520.560522] gem_exec-2390 20..... 286752077us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40458, current 40437 <0> [520.560654] gem_exec-2390 20D..1. 286752090us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40458, current 40438 <0> [520.560786] gem_exec-2390 20..... 286752100us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40436, current 40439 <0> [520.560918] gem_exec-2390 20..... 286752110us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40438, current 40439 <0> [520.561050] gem_exec-2390 20..... 286752116us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40440, current 40440 <0> [520.561181] gem_exec-2377 21..... 286753232us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40476, current 40463 <0> [520.561319] gem_exec-2377 21D..1. 286753247us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40476, current 40464 <0> [520.561458] gem_exec-2377 21..... 286753259us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40464, current 40464 <0> [520.561598] gem_exec-2390 20..... 286754052us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40494, current 40484 <0> [520.561731] gem_exec-2390 20D..1. 286754067us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40494, current 40484 <0> [520.561858] gem_exec-2390 20..... 286754192us : intel_rps_boost: 0000:00:02.0 boost fence:298:9e2e <0> [520.561984] gem_exec-2377 21..... 286754858us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40526, current 40496 <0> [520.562114] gem_exec-2401 20..... 286755923us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40578, current 40520 <0> [520.562244] gem_exec-2387 21..... 286756270us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40526, current 40526 <0> [520.562376] gem_exec-2387 21..... 286756440us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40598, current 40528 <0> [520.562506] gem_exec-2387 21..... 286756454us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40528, current 40528 <0> [520.562637] gem_exec-2374 21..... 286758196us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40616, current 40559 <0> [520.562766] gem_exec-2374 21..... 286758210us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40558, current 40559 <0> [520.562897] gem_exec-2374 21..... 286758233us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40560, current 40560 <0> [520.563027] gem_exec-2383 20..... 286758485us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40634, current 40566 <0> [520.563158] gem_exec-2383 20..... 286758499us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40566, current 40566 <0> [520.563287] gem_exec-2383 20..... 286758530us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40568, current 40568 <0> [520.563410] gem_exec-2374 21..... 286758938us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:44d active <0> [520.563536] gem_exec-2374 21..... 286758949us : intel_timeline_pin: 0000:00:02.0 timeline:44d using HWSP offset:fb3b1000 <0> [520.563656] gem_exec-2374 21..... 286758974us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:44d pin ring:{start:fb546000, head:0160, tail:0160} <0> [520.563792] gem_exec-2374 21..... 286759161us : __i915_request_commit: 0000:00:02.0 vcs0: fence 44d:6, current 4 <0> [520.563923] gem_exec-2374 21DN.1. 286759176us : __i915_request_submit: 0000:00:02.0 vcs0: fence 44d:6, current 4 <0> [520.564043] gem_exec-2403 21..... 286771984us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:94b active <0> [520.564169] gem_exec-2403 21..... 286771993us : intel_timeline_pin: 0000:00:02.0 timeline:94b using HWSP offset:f0d02000 <0> [520.564301] gem_exec-2391 20..... 286929441us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43376, current 43344 <0> [520.564432] gem_exec-2391 20D..1. 286929470us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43376, current 43346 <0> [520.564562] gem_exec-2391 20..... 286929859us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43340, current 43350 <0> [520.564693] gem_exec-2391 20..... 286929880us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43342, current 43350 <0> [520.564823] gem_exec-2391 20..... 286929894us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43344, current 43351 <0> [520.564956] gem_exec-2391 20..... 286929908us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43346, current 43352 <0> [520.565095] gem_exec-2391 20.N... 286929924us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43348, current 43352 <0> [520.565234] gem_exec-2391 20.N... 286929937us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43350, current 43352 <0> [520.565373] gem_exec-2391 20.N... 286929949us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43352, current 43352 <0> [520.565512] gem_exec-2380 20..... 286938285us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43504, current 43438 <0> [520.565651] gem_exec-2380 20D..1. 286938305us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43504, current 43440 <0> [520.565790] gem_exec-2380 20..... 286938319us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43440, current 43440 <0> [520.565929] gem_exec-2383 20..... 286940259us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43552, current 43492 <0> [520.566068] gem_exec-2373 20..... 286944100us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43640, current 43592 <0> [520.566202] gem_exec-2373 20D..1. 286944121us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43640, current 43594 <0> [520.566331] gem_exec-2373 20..... 286944135us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43574, current 43594 <0> [520.566462] gem_exec-2373 20..... 286944146us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43576, current 43594 <0> [520.566590] gem_exec-2373 20..... 286944151us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43578, current 43594 <0> [520.566720] gem_exec-2373 20..... 286944168us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43580, current 43594 <0> [520.566850] gem_exec-2373 20..... 286944176us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43582, current 43594 <0> [520.566980] gem_exec-2373 20..... 286944181us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43584, current 43594 <0> [520.567110] gem_exec-2373 20..... 286944189us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43586, current 43594 <0> [520.567239] gem_exec-2373 20..... 286944193us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43588, current 43594 <0> [520.567369] gem_exec-2373 20..... 286944196us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43590, current 43594 <0> [520.567507] gem_exec-2373 20..... 286944199us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43592, current 43594 <0> [520.567645] gem_exec-2373 20.N... 286944208us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43594, current 43594 <0> [520.567784] gem_exec-2367 20.N... 286944725us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43602, current 43610 <0> [520.567922] gem_exec-2367 20.N... 286944739us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43604, current 43611 <0> [520.568055] gem_exec-2383 20..... 286945159us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43660, current 43630 <0> [520.568184] gem_exec-2383 20D..1. 286945513us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43660, current 43634 <0> [520.568313] gem_exec-2383 20..... 286945524us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43628, current 43634 <0> [520.568443] gem_exec-2383 20..... 286945532us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43630, current 43634 <0> [520.568572] gem_exec-2383 20..... 286945540us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43632, current 43634 <0> [520.568701] gem_exec-2383 20..... 286945546us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43634, current 43634 <0> [520.568832] CPU:0 [LOST 4749 EVENTS] gem_exec-2394 0..... 287005697us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44614, current 44560 <0> [520.568967] gem_exec-2394 0D..1. 287005702us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:44614, current 44560 <0> [520.569097] gem_exec-2392 0..... 287006190us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44644, current 44569 <0> [520.569225] gem_exec-2394 0..... 287011263us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44696, current 44660 <0> [520.569354] gem_exec-2394 0D..1. 287011270us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:44696, current 44660 <0> [520.569476] gem_exec-2394 0..... 287011414us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:54d active <0> [520.569602] gem_exec-2394 0..... 287011418us : intel_timeline_pin: 0000:00:02.0 timeline:54d using HWSP offset:f93b5000 <0> [520.569723] gem_exec-2394 0..... 287011430us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:54d pin ring:{start:f94be000, head:0370, tail:0370} <0> [520.569859] gem_exec-2394 0..... 287011472us : __i915_request_commit: 0000:00:02.0 vecs0: fence 54d:12, current 10 <0> [520.569989] gem_exec-2394 0D..1. 287011477us : __i915_request_submit: 0000:00:02.0 vecs0: fence 54d:12, current 10 <0> [520.570119] gem_exec-2394 0..... 287011866us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44724, current 44670 <0> [520.570249] gem_exec-2394 0..... 287011871us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:44670, current 44670 <0> [520.570387] gem_exec-2396 0..... 287012250us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44754, current 44676 <0> [520.570517] kworker/-2413 0..... 287018055us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:447 unpin <0> [520.570645] kworker/-2413 0..... 287018186us : __intel_context_retire: 0000:00:02.0 ccs0: context:447 retire runtime: { total:336974ns, avg:79712ns } <0> [520.570776] kworker/-2413 0..... 287018199us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:3c4 unpin <0> [520.570898] kworker/-2413 0..... 287018329us : __intel_context_retire: 0000:00:02.0 ccs0: context:3c4 retire runtime: { total:241733ns, avg:92644ns } <0> [520.571020] kworker/-2413 0..... 287018684us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:9ab unpin <0> [520.571138] kworker/-2413 0.N... 287018819us : __intel_context_retire: 0000:00:02.0 vcs0: context:9ab retire runtime: { total:129320ns, avg:56392ns } <0> [520.571276] gem_exec-2378 0..... 287020645us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44822, current 44795 <0> [520.571416] gem_exec-2378 0..... 287020651us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:44796, current 44796 <0> [520.571537] gem_exec-2378 0..... 287022044us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:73b active <0> [520.571663] gem_exec-2378 0..... 287022047us : intel_timeline_pin: 0000:00:02.0 timeline:73b using HWSP offset:f51bc000 <0> [520.571782] gem_exec-2378 0..... 287022057us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:73b pin ring:{start:f5288000, head:05a0, tail:05a0} <0> [520.571918] gem_exec-2378 0..... 287022095us : __i915_request_commit: 0000:00:02.0 rcs0: fence 73b:10, current 8 <0> [520.572049] gem_exec-2378 0D..1. 287022179us : __i915_request_submit: 0000:00:02.0 rcs0: fence 73b:10, current 8 <0> [520.572180] gem_exec-2378 0..... 287027016us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44938, current 44856 <0> [520.572311] gem_exec-2378 0D..1. 287027021us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:44938, current 44856 <0> [520.572442] gem_exec-2398 0..... 287032355us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45018, current 44974 <0> [520.572573] gem_exec-2376 0..... 287032929us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45052, current 44992 <0> [520.572693] gem_exec-2376 0..... 287033085us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:8fb active <0> [520.572820] gem_exec-2376 0..... 287033089us : intel_timeline_pin: 0000:00:02.0 timeline:8fb using HWSP offset:f1827000 <0> [520.572941] gem_exec-2376 0..... 287033099us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:8fb pin ring:{start:f1982000, head:0370, tail:0370} <0> [520.573078] gem_exec-2376 0..... 287033145us : __i915_request_commit: 0000:00:02.0 vecs0: fence 8fb:12, current 10 <0> [520.573210] gem_exec-2376 0D..1. 287033151us : __i915_request_submit: 0000:00:02.0 vecs0: fence 8fb:12, current 10 <0> [520.573341] gem_exec-2373 0..... 287038408us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45146, current 45066 <0> [520.573462] gem_exec-2373 0..... 287038584us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3cb active <0> [520.573587] gem_exec-2373 0..... 287038587us : intel_timeline_pin: 0000:00:02.0 timeline:3cb using HWSP offset:fc595000 <0> [520.573707] gem_exec-2373 0..... 287038596us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3cb pin ring:{start:fc5fd000, head:0160, tail:0160} <0> [520.573844] gem_exec-2373 0..... 287038632us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3cb:6, current 4 <0> [520.573975] gem_exec-2373 0Dn.1. 287039364us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3cb:6, current 4 <0> [520.574105] gem_exec-2383 0..... 287040171us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45072, current 45074 <0> [520.574237] gem_exec-2383 0..... 287040185us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45160, current 45074 <0> [520.574367] gem_exec-2383 0..... 287040189us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45074, current 45074 <0> [520.574498] gem_exec-2383 0..... 287044048us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45184, current 45139 <0> [520.574629] gem_exec-2383 0D..1. 287044054us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45184, current 45139 <0> [520.574760] gem_exec-2383 0..... 287044061us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45140, current 45140 <0> [520.574892] gem_exec-2409 0..... 287045810us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45240, current 45160 <0> [520.575022] gem_exec-2372 0..... 287046136us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45248, current 45160 <0> [520.575145] kworker/-2413 0..... 287047973us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:b56 unpin <0> [520.575267] kworker/-2413 0..... 287048107us : __intel_context_retire: 0000:00:02.0 rcs0: context:b56 retire runtime: { total:361089ns, avg:99057ns } <0> [520.575389] kworker/-2413 0..... 287048118us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:b0c unpin <0> [520.575507] kworker/-2413 0..... 287048244us : __intel_context_retire: 0000:00:02.0 rcs0: context:b0c retire runtime: { total:351973ns, avg:157145ns } <0> [520.575629] kworker/-2413 0..... 287048249us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:7e4 unpin <0> [520.575746] kworker/-2413 0..... 287048374us : __intel_context_retire: 0000:00:02.0 rcs0: context:7e4 retire runtime: { total:456330ns, avg:109604ns } <0> [520.575883] gem_exec-2372 0..... 287053243us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45306, current 45260 <0> [520.576015] gem_exec-2372 0D..1. 287053249us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45306, current 45260 <0> [520.576147] gem_exec-2383 0..... 287054010us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45334, current 45262 <0> [520.576278] gem_exec-2397 0..... 287056868us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45382, current 45331 <0> [520.576408] gem_exec-2376 0..... 287057049us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45342, current 45342 <0> [520.576539] gem_exec-2376 0..... 287057087us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45396, current 45344 <0> [520.576670] gem_exec-2376 0..... 287057091us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45344, current 45344 <0> [520.576800] gem_exec-2376 0..... 287057131us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45346, current 45346 <0> [520.576922] kworker/-2413 0..... 287057719us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:718 unpin <0> [520.577042] kworker/-2413 0..... 287057801us : __intel_context_retire: 0000:00:02.0 vcs1: context:718 retire runtime: { total:84323ns, avg:38160ns } <0> [520.577164] kworker/-2413 0..... 287057819us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:33f unpin <0> [520.577281] kworker/-2413 0..... 287057890us : __intel_context_retire: 0000:00:02.0 vcs0: context:33f retire runtime: { total:173787ns, avg:40015ns } <0> [520.577402] kworker/-2413 0..... 287057956us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:cef unpin <0> [520.577520] kworker/-2413 0.N... 287058027us : __intel_context_retire: 0000:00:02.0 vcs1: context:cef retire runtime: { total:19928ns, avg:19928ns } <0> [520.577640] kworker/-2413 0..... 287058090us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:89d unpin <0> [520.577757] kworker/-2413 0..... 287058159us : __intel_context_retire: 0000:00:02.0 vecs0: context:89d retire runtime: { total:94446ns, avg:30634ns } <0> [520.577895] gem_exec-2397 0..... 287058353us : __i915_request_commit: 0000:00:02.0 bcs0: fence c6f:4, current 2 <0> [520.578027] gem_exec-2397 0D..1. 287058357us : __i915_request_submit: 0000:00:02.0 bcs0: fence c6f:4, current 2 <0> [520.578159] gem_exec-2374 0..... 287064273us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45468, current 45456 <0> [520.578291] gem_exec-2374 0D..1. 287064280us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45468, current 45456 <0> [520.578417] gem_exec-2374 0..... 287064429us : intel_rps_boost: 0000:00:02.0 boost fence:298:b19c <0> [520.578547] gem_exec-2397 0..... 287064577us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45488, current 45462 <0> [520.578678] gem_exec-2397 0D..1. 287064583us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45488, current 45462 <0> [520.578810] gem_exec-2382 0..... 287065817us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45524, current 45477 <0> [520.578941] gem_exec-2382 0..... 287066402us : __i915_request_commit: 0000:00:02.0 vecs0: fence 331:12, current 10 <0> [520.579072] gem_exec-2382 0D..1. 287066406us : __i915_request_submit: 0000:00:02.0 vecs0: fence 331:12, current 10 <0> [520.579202] gem_exec-2382 0..... 287066874us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45590, current 45510 <0> [520.579332] gem_exec-2382 0..... 287066879us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45510, current 45510 <0> [520.579463] gem_exec-2384 0..... 287068671us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45618, current 45550 <0> [520.579593] gem_exec-2372 0..... 287072873us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45652, current 45578 <0> [520.579723] gem_exec-2372 0..... 287072878us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45578, current 45578 <0> [520.579846] gem_exec-2372 0..... 287072933us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:70b active <0> [520.579972] gem_exec-2372 0..... 287072937us : intel_timeline_pin: 0000:00:02.0 timeline:70b using HWSP offset:f584b000 <0> [520.580093] gem_exec-2372 0..... 287072946us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:70b pin ring:{start:f592c000, head:02d0, tail:02d0} <0> [520.580230] gem_exec-2372 0..... 287072976us : __i915_request_commit: 0000:00:02.0 rcs0: fence 70b:6, current 4 <0> [520.580362] gem_exec-2372 0D..1. 287073043us : __i915_request_submit: 0000:00:02.0 rcs0: fence 70b:6, current 4 <0> [520.580493] gem_exec-2375 0..... 287073181us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45662, current 45584 <0> [520.580614] gem_exec-2375 0..... 287073239us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:774 active <0> [520.580740] gem_exec-2375 0..... 287073242us : intel_timeline_pin: 0000:00:02.0 timeline:774 using HWSP offset:f4af1000 <0> [520.580860] gem_exec-2375 0..... 287073252us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:774 pin ring:{start:f4b50000, head:00b0, tail:00b0} <0> [520.580997] gem_exec-2375 0..... 287073283us : __i915_request_commit: 0000:00:02.0 vcs1: fence 774:4, current 2 <0> [520.581128] gem_exec-2375 0D..1. 287073287us : __i915_request_submit: 0000:00:02.0 vcs1: fence 774:4, current 2 <0> [520.581258] gem_exec-2369 0..... 287076230us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45704, current 45665 <0> [520.581389] gem_exec-2369 0D..1. 287076237us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45704, current 45665 <0> [520.581520] gem_exec-2369 0..... 287076244us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45666, current 45666 <0> [520.581642] gem_exec-2369 0..... 287076322us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:2e3 active <0> [520.581769] gem_exec-2369 0..... 287076326us : intel_timeline_pin: 0000:00:02.0 timeline:2e3 using HWSP offset:fe468000 <0> [520.581889] gem_exec-2369 0..... 287076336us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:2e3 pin ring:{start:fe5a2000, head:0210, tail:0210} <0> [520.582024] gem_exec-2369 0..... 287076387us : __i915_request_commit: 0000:00:02.0 vcs1: fence 2e3:8, current 6 <0> [520.582155] gem_exec-2369 0D..1. 287076396us : __i915_request_submit: 0000:00:02.0 vcs1: fence 2e3:8, current 6 <0> [520.582282] gem_exec-2406 0..... 287077395us : intel_timeline_pin: 0000:00:02.0 timeline:585 using HWSP offset:f8cfd000 <0> [520.582403] gem_exec-2406 0..... 287077407us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:585 pin ring:{start:f8e05000, head:0210, tail:0210} <0> [520.582527] gem_exec-2406 0..... 287077422us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:585 unpin <0> [520.582645] gem_exec-2406 0..... 287077550us : __intel_context_retire: 0000:00:02.0 vecs0: context:585 retire runtime: { total:75260ns, avg:18656ns } <0> [520.582766] gem_exec-2406 0..... 287077553us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:585 active <0> [520.582891] gem_exec-2406 0..... 287077554us : intel_timeline_pin: 0000:00:02.0 timeline:585 using HWSP offset:f8cfd000 <0> [520.583009] gem_exec-2406 0..... 287077558us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:585 pin ring:{start:f8e05000, head:0210, tail:0210} <0> [520.583146] gem_exec-2406 0..... 287077589us : __i915_request_commit: 0000:00:02.0 vecs0: fence 585:8, current 6 <0> [520.583278] gem_exec-2406 0D..1. 287077593us : __i915_request_submit: 0000:00:02.0 vecs0: fence 585:8, current 6 <0> [520.583410] gem_exec-2406 0..... 287082709us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45824, current 45768 <0> [520.583542] gem_exec-2406 0D..1. 287082715us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45824, current 45768 <0> [520.583672] gem_exec-2406 0..... 287082721us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45768, current 45768 <0> [520.583805] gem_exec-2401 0..... 287085631us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45902, current 45842 <0> [520.583936] gem_exec-2401 0..... 287085636us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45842, current 45843 <0> [520.584068] gem_exec-2401 0..... 287087882us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45958, current 45890 <0> [520.584198] gem_exec-2368 0..... 287088195us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45982, current 45898 <0> [520.584320] gem_exec-2368 0..... 287088260us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:bfe active <0> [520.584446] gem_exec-2368 0..... 287088263us : intel_timeline_pin: 0000:00:02.0 timeline:bfe using HWSP offset:eb2b8000 <0> [520.584566] gem_exec-2368 0..... 287088271us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:bfe pin ring:{start:eb331000, head:0160, tail:0160} <0> [520.584702] gem_exec-2368 0..... 287088310us : __i915_request_commit: 0000:00:02.0 vcs0: fence bfe:6, current 4 <0> [520.584834] gem_exec-2368 0D..1. 287088316us : __i915_request_submit: 0000:00:02.0 vcs0: fence bfe:6, current 4 <0> [520.584965] gem_exec-2378 0..... 287088397us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45984, current 45898 <0> [520.585095] gem_exec-2395 0..... 287089339us : intel_timeline_pin: 0000:00:02.0 timeline:cf8 using HWSP offset:e91a8000 <0> [520.585216] gem_exec-2395 0..... 287091568us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:cf8 pin ring:{start:e91a9000, head:0000, tail:0000} <0> [520.585353] gem_exec-2395 0..... 287091927us : __i915_request_commit: 0000:00:02.0 ccs0: fence cf8:2, current 0 <0> [520.585484] gem_exec-2395 0D..1. 287091945us : __i915_request_submit: 0000:00:02.0 ccs0: fence cf8:2, current 0 <0> [520.585615] gem_exec-2375 0..... 287095134us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:45928, current 45954 <0> [520.585746] gem_exec-2375 0..... 287095134us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45928, current 45954 <0> [520.585878] gem_exec-2375 0..... 287095141us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46016, current 45954 <0> [520.586009] gem_exec-2375 0D..1. 287095147us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46016, current 45955 <0> [520.586142] gem_exec-2375 0..... 287095154us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45930, current 45955 <0> [520.586273] gem_exec-2375 0..... 287095156us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45932, current 45955 <0> [520.586405] gem_exec-2375 0..... 287095157us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45934, current 45955 <0> [520.586536] gem_exec-2375 0..... 287095163us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45936, current 45956 <0> [520.586667] gem_exec-2375 0..... 287095164us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45938, current 45956 <0> [520.586797] gem_exec-2375 0..... 287095165us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45940, current 45956 <0> [520.586928] gem_exec-2375 0..... 287095167us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45942, current 45956 <0> [520.587059] gem_exec-2375 0..... 287095168us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45944, current 45956 <0> [520.587190] gem_exec-2375 0..... 287095169us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45946, current 45956 <0> [520.587320] gem_exec-2375 0..... 287095170us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45948, current 45956 <0> [520.587450] gem_exec-2375 0..... 287095171us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45950, current 45956 <0> [520.587580] gem_exec-2375 0..... 287095172us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45952, current 45956 <0> [520.587710] gem_exec-2375 0..... 287095173us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45954, current 45956 <0> [520.587839] gem_exec-2375 0..... 287095176us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45956, current 45956 <0> [520.587970] gem_exec-2375 0..... 287095699us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46026, current 45958 <0> [520.588099] gem_exec-2375 0D..1. 287095703us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46026, current 45958 <0> [520.588228] gem_exec-2395 0..... 287097866us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:45970, current 45971 <0> [520.588357] gem_exec-2395 0..... 287097868us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45970, current 45971 <0> [520.588488] gem_exec-2395 0..... 287097903us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46058, current 45973 <0> [520.588617] gem_exec-2395 0D..1. 287097925us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46058, current 45974 <0> [520.588746] gem_exec-2395 0..... 287098207us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45972, current 45974 <0> [520.588875] gem_exec-2395 0..... 287098210us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45974, current 45974 <0> [520.589005] gem_exec-2389 0..... 287099709us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46088, current 46026 <0> [520.589135] gem_exec-2389 0D..1. 287099714us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46088, current 46026 <0> [520.589264] gem_exec-2389 0..... 287099720us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46026, current 46026 <0> [520.589387] gem_exec-2389 0..... 287099795us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:5a0 active <0> [520.589513] gem_exec-2389 0..... 287099799us : intel_timeline_pin: 0000:00:02.0 timeline:5a0 using HWSP offset:f8834000 <0> [520.589635] gem_exec-2389 0..... 287099808us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:5a0 pin ring:{start:f88bf000, head:0438, tail:0438} <0> [520.589770] gem_exec-2389 0..... 287099858us : __i915_request_commit: 0000:00:02.0 ccs0: fence 5a0:8, current 6 <0> [520.589901] gem_exec-2389 0D..1. 287099864us : __i915_request_submit: 0000:00:02.0 ccs0: fence 5a0:8, current 6 <0> [520.590030] gem_exec-2395 0..... 287104373us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46142, current 46084 <0> [520.590158] gem_exec-2398 0..... 287104924us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46104, current 46104 <0> [520.590289] gem_exec-2398 0..... 287104970us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46172, current 46106 <0> [520.590419] gem_exec-2398 0..... 287104974us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46106, current 46106 <0> [520.590550] gem_exec-2408 0..... 287110461us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46234, current 46184 <0> [520.590680] gem_exec-2408 0D..1. 287110466us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46234, current 46184 <0> [520.590801] gem_exec-2408 0..... 287110654us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:5f0 active <0> [520.590927] gem_exec-2408 0..... 287110659us : intel_timeline_pin: 0000:00:02.0 timeline:5f0 using HWSP offset:f7dc7000 <0> [520.591047] gem_exec-2408 0..... 287110669us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:5f0 pin ring:{start:f7e76000, head:0168, tail:0168} <0> [520.591183] gem_exec-2408 0..... 287110714us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5f0:4, current 2 <0> [520.591313] gem_exec-2408 0D..1. 287110719us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5f0:4, current 2 <0> [520.591444] gem_exec-2408 0..... 287114273us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46294, current 46222 <0> [520.591573] gem_exec-2408 0D..1. 287114277us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46294, current 46222 <0> [520.591703] gem_exec-2368 0.n... 287115086us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46232, current 46240 <0> [520.591834] gem_exec-2368 0.N... 287115101us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46234, current 46240 <0> [520.591965] gem_exec-2368 0.N... 287115114us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46236, current 46240 <0> [520.592095] gem_exec-2368 0.N... 287115120us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46238, current 46240 <0> [520.592226] gem_exec-2389 0..... 287119681us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46342, current 46303 <0> [520.592356] gem_exec-2389 0D..1. 287119687us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46342, current 46304 <0> [520.592486] gem_exec-2389 0..... 287119693us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46304, current 46304 <0> [520.592618] gem_exec-2389 0..... 287120421us : __i915_request_commit: 0000:00:02.0 ccs0: fence 412:6, current 4 <0> [520.592746] gem_exec-2389 0D..1. 287120742us : __i915_request_submit: 0000:00:02.0 ccs0: fence 412:6, current 4 <0> [520.592875] gem_exec-2389 0..... 287121082us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46374, current 46328 <0> [520.593005] gem_exec-2389 0..... 287122694us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46364, current 46364 <0> [520.593135] gem_exec-2389 0..... 287122708us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46444, current 46365 <0> [520.593265] gem_exec-2382 0..... 287126845us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46486, current 46445 <0> [520.593395] gem_exec-2382 0D..1. 287126851us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46486, current 46446 <0> [520.593524] gem_exec-2382 0..... 287126864us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46446, current 46446 <0> [520.593647] gem_exec-2382 0..... 287127738us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:cff active <0> [520.593777] gem_exec-2401 0..... 287128079us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46530, current 46476 <0> [520.593898] gem_exec-2401 0..... 287128327us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b68 active <0> [520.594024] gem_exec-2401 0..... 287128331us : intel_timeline_pin: 0000:00:02.0 timeline:b68 using HWSP offset:ec612000 <0> [520.594145] gem_exec-2401 0..... 287128340us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b68 pin ring:{start:ec697000, head:0168, tail:0168} <0> [520.594282] gem_exec-2401 0..... 287128379us : __i915_request_commit: 0000:00:02.0 ccs0: fence b68:4, current 2 <0> [520.594413] gem_exec-2401 0D..1. 287128383us : __i915_request_submit: 0000:00:02.0 ccs0: fence b68:4, current 2 <0> [520.594544] gem_exec-2401 0..... 287132448us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46596, current 46536 <0> [520.594677] gem_exec-2401 0D..1. 287132455us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46596, current 46536 <0> [520.594808] gem_exec-2399 0..... 287133238us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46620, current 46540 <0> [520.594940] gem_exec-2382 0..... 287135642us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46674, current 46611 <0> [520.595071] gem_exec-2382 0..... 287135647us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46612, current 46612 <0> [520.595202] gem_exec-2399 0..... 287135855us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46684, current 46618 <0> [520.595332] gem_exec-2375 0..... 287141613us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46742, current 46680 <0> [520.595462] gem_exec-2375 0D..1. 287141618us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46742, current 46680 <0> [520.595593] gem_exec-2375 0..... 287141624us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46680, current 46680 <0> [520.595724] gem_exec-2398 0..... 287142323us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46708, current 46708 <0> [520.595854] gem_exec-2398 0..... 287142349us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46766, current 46708 <0> [520.595976] kworker/-546 0..... 287143025us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:52f unpin <0> [520.596096] kworker/-546 0..... 287143153us : __intel_context_retire: 0000:00:02.0 vecs0: context:52f retire runtime: { total:141351ns, avg:25069ns } <0> [520.596231] gem_exec-2377 0..... 287147304us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46852, current 46770 <0> [520.596363] gem_exec-2377 0D..1. 287147310us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46852, current 46770 <0> [520.596484] kworker/-546 0..... 287148150us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:959 unpin <0> [520.596602] kworker/-546 0.N... 287148281us : __intel_context_retire: 0000:00:02.0 bcs0: context:959 retire runtime: { total:86708ns, avg:31111ns } <0> [520.596738] gem_exec-2389 21..... 287149189us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46882, current 46806 <0> [520.596871] gem_exec-2389 21D..1. 287149256us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46882, current 46806 <0> [520.597001] gem_exec-2389 21..... 287150049us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46808, current 46810 <0> [520.597133] gem_exec-2389 21..... 287150082us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46810, current 46810 <0> [520.597265] gem_exec-2377 0..... 287150890us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46902, current 46832 <0> [520.597395] gem_exec-2377 0D..1. 287150897us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46902, current 46832 <0> [520.597525] gem_exec-2377 0..... 287150903us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46832, current 46832 <0> [520.597647] gem_exec-2389 21..... 287150926us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c60 active <0> [520.597773] gem_exec-2389 21..... 287150956us : intel_timeline_pin: 0000:00:02.0 timeline:c60 using HWSP offset:ea60c000 <0> [520.597893] gem_exec-2389 21..... 287151011us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c60 pin ring:{start:ea655000, head:0438, tail:0438} <0> [520.598029] gem_exec-2389 21..... 287151222us : __i915_request_commit: 0000:00:02.0 ccs0: fence c60:8, current 6 <0> [520.598159] gem_exec-2389 21D..1. 287151348us : __i915_request_submit: 0000:00:02.0 ccs0: fence c60:8, current 6 <0> [520.598289] gem_exec-2368 0..... 287152582us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46936, current 46876 <0> [520.598423] gem_exec-2389 21..... 287152771us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46944, current 46876 <0> [520.598555] gem_exec-2367 20..... 287152973us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46952, current 46876 <0> [520.598687] gem_exec-2368 0..... 287155622us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46986, current 46925 <6> [520.598697] [IGT] gem_exec_schedule: finished subtest vcs0, SUCCESS <0> [520.598816] gem_exec-2368 0..... 287155627us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46926, current 46926 <0> [520.598948] gem_exec-2368 0..... 287155703us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46928, current 46928

<6> [520.499168] [IGT] gem_exec_schedule: starting dynamic subtest vcs0

<7> [520.499308] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<7> [520.507459] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

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

<0> [520.508581] ---------------------------------

<0> [520.509292] kworker/-170 21..... 49725322us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1a:2, current 2

<0> [520.509431] kworker/-170 21..... 49728691us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1b:2, current 2

<0> [520.509611] kworker/-170 21..... 49728738us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1c:2, current 2

<0> [520.509914] kworker/-170 21..... 80760741us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 31:2, current 2

<0> [520.510221] kworker/-170 21..... 80761393us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:5, current 5

<0> [520.510357] kworker/-170 21..... 80761420us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:6, current 7

<0> [520.510585] kworker/-170 21..... 80761428us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:7, current 7

<0> [520.510789] kworker/-170 21..... 80761447us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [520.511066] kworker/-171 20..... 80894051us : intel_gt_runtime_suspend: 0000:00:02.0

<0> [520.511279] kworker/-171 20..... 80895374us : intel_gt_runtime_suspend: 0000:00:02.0

<0> [520.511571] kworker/-443 21..... 84730957us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:7, current 6

<0> [520.511873] kworker/-443 21D..1. 84731012us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:7, current 6

<7> [520.511760] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.512170] kworker/-170 21..... 86713057us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4b:4, current 4

<4> [520.512301] kauditd_printk_skb: 113 callbacks suppressed

<0> [520.512306] kworker/-170 21..... 89784140us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4f:2, current 2

<5> [520.512307] audit: type=1400 audit(1754584918.863:125): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512534] audit: type=1400 audit(1754584918.863:126): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<0> [520.512542] kworker/-170 21..... 89784534us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:8, current 8

<5> [520.512597] audit: type=1400 audit(1754584918.863:127): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512654] audit: type=1400 audit(1754584918.863:128): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512709] audit: type=1400 audit(1754584918.863:129): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512765] audit: type=1400 audit(1754584918.863:130): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<0> [520.512774] kworker/-170 21..... 89784564us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:8, current 9

<5> [520.512819] audit: type=1400 audit(1754584918.863:131): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512874] audit: type=1400 audit(1754584918.863:132): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512927] audit: type=1400 audit(1754584918.863:133): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<5> [520.512984] audit: type=1400 audit(1754584918.863:134): apparmor="DENIED" operation="open" class="file" profile="rsyslogd" name="/run/systemd/sessions/" pid=833 comm=72733A6D61696E20513A526567 requested_mask="r" denied_mask="r" fsuid=103 ouid=0

<0> [520.513068] kworker/-170 21..... 89784570us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:9, current 9

<0> [520.513351] kworker/-170 21..... 89784590us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [520.513576] kworker/-170 21..... 181752088us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:4, current 142

<0> [520.513700] kworker/-170 21..... 181752117us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:6, current 142

<0> [520.513824] kworker/-170 21..... 181752126us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:8, current 142

<0> [520.513948] kworker/-170 21..... 181752135us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:10, current 142

<0> [520.514072] kworker/-170 21..... 181752143us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:12, current 142

<0> [520.514208] kworker/-170 21..... 181752151us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:14, current 142

<0> [520.514348] kworker/-170 21..... 181752161us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:16, current 142

<0> [520.514487] kworker/-170 21..... 181752170us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:18, current 142

<0> [520.514616] kworker/-170 21..... 181752178us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:20, current 142

<0> [520.514746] kworker/-170 21..... 181752185us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:22, current 142

<0> [520.514875] kworker/-170 21..... 181752194us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:24, current 142

<0> [520.515004] kworker/-170 21..... 181752202us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:26, current 142

<0> [520.515133] kworker/-170 21..... 181752209us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:28, current 142

<0> [520.515262] kworker/-170 21..... 181752217us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:30, current 142

<0> [520.515391] kworker/-170 21..... 181752223us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:32, current 142

<0> [520.515528] kworker/-170 21..... 181752230us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:34, current 142

<0> [520.515661] kworker/-170 21..... 181752237us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:36, current 142

<7> [520.515478] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.515789] kworker/-170 21..... 181752247us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:38, current 142

<0> [520.515899] kworker/-170 21..... 181752260us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:40, current 142

<0> [520.516009] kworker/-170 21..... 181752270us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:42, current 142

<0> [520.516138] kworker/-170 21..... 181752277us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:44, current 142

<0> [520.516267] kworker/-170 21..... 181752287us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:46, current 142

<0> [520.516403] kworker/-170 21..... 181752295us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:48, current 142

<0> [520.516535] kworker/-170 21..... 181752303us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:50, current 142

<0> [520.516655] kworker/-170 21..... 181752309us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:52, current 142

<0> [520.516775] kworker/-170 21..... 181752318us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:54, current 142

<0> [520.516896] kworker/-170 21..... 181752325us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:56, current 142

<0> [520.517016] kworker/-170 21..... 181752332us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:58, current 142

<0> [520.517136] kworker/-170 21..... 181752339us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:60, current 142

<0> [520.517257] kworker/-170 21..... 181752346us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:62, current 142

<0> [520.517377] kworker/-170 21..... 181752353us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:64, current 142

<0> [520.517497] kworker/-170 21..... 181752359us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:66, current 142

<0> [520.517617] kworker/-170 21..... 181752365us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:68, current 142

<0> [520.517739] kworker/-170 21..... 181752372us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:70, current 142

<0> [520.517860] kworker/-170 21..... 181752378us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:72, current 142

<0> [520.517981] kworker/-170 21..... 181752385us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:74, current 142

<0> [520.518101] kworker/-170 21..... 181752392us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:76, current 142

<0> [520.518223] kworker/-170 21..... 181752399us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:78, current 142

<0> [520.518344] kworker/-170 21..... 181752406us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:80, current 142

<0> [520.518465] kworker/-170 21..... 181752412us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:82, current 142

<0> [520.518585] kworker/-170 21..... 181752419us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:84, current 142

<0> [520.518705] kworker/-170 21..... 181752425us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:86, current 142

<0> [520.518825] kworker/-170 21..... 181752433us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:88, current 142

<0> [520.518946] kworker/-170 21..... 181752438us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:90, current 142

<0> [520.519066] kworker/-170 21..... 181752446us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:92, current 142

<0> [520.519185] kworker/-170 21..... 181752452us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:94, current 142

<0> [520.519304] kworker/-170 21..... 181752459us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:96, current 142

<0> [520.519424] kworker/-170 21..... 181752465us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:98, current 142

<0> [520.519543] kworker/-170 21..... 181752471us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:100, current 142

<0> [520.519663] kworker/-170 21..... 181752480us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:102, current 142

<0> [520.519783] kworker/-170 21..... 181752495us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:104, current 142

<0> [520.519902] kworker/-170 21..... 181752504us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:106, current 142

<0> [520.520020] kworker/-170 21..... 181752512us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:108, current 142

<0> [520.520139] kworker/-170 21..... 181752520us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:110, current 142

<0> [520.520258] kworker/-170 21..... 181752528us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:112, current 142

<0> [520.520377] kworker/-170 21..... 181752535us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:114, current 142

<0> [520.520496] kworker/-170 21..... 181752541us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:116, current 142

<0> [520.520615] kworker/-170 21..... 181752593us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:118, current 142

<0> [520.520738] kworker/-170 21..... 181752601us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:120, current 142

<0> [520.520860] kworker/-170 21..... 181752608us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:122, current 142

<0> [520.520979] kworker/-170 21..... 181752616us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:124, current 142

<0> [520.521099] kworker/-170 21..... 181752623us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:126, current 142

<7> [520.521129] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.521218] kworker/-170 21..... 181752630us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:128, current 142

<0> [520.521321] kworker/-170 21..... 181752637us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:130, current 142

<0> [520.521441] kworker/-170 21..... 181752644us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:132, current 142

<0> [520.521561] kworker/-170 21..... 181752651us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:134, current 142

<0> [520.521680] kworker/-170 21..... 181752657us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:136, current 142

<0> [520.521799] kworker/-170 21..... 181752664us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:138, current 142

<0> [520.521918] kworker/-170 21..... 181752670us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:140, current 142

<0> [520.522038] kworker/-170 21..... 181752677us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 199:142, current 142

<0> [520.522158] kworker/-170 21..... 181753513us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:4, current 142

<0> [520.522278] kworker/-170 21..... 181753529us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:6, current 142

<0> [520.522399] kworker/-170 21..... 181753536us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:8, current 142

<0> [520.522526] kworker/-170 21..... 181753544us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:10, current 142

<0> [520.522650] kworker/-170 21..... 181753552us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:12, current 142

<0> [520.522774] kworker/-170 21..... 181753559us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:14, current 142

<0> [520.522897] kworker/-170 21..... 181753566us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:16, current 142

<0> [520.523020] kworker/-170 21..... 181753572us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:18, current 142

<0> [520.523143] kworker/-170 21..... 181753579us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:20, current 142

<0> [520.523266] kworker/-170 21..... 181753587us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:22, current 142

<0> [520.523389] kworker/-170 21..... 181753598us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:24, current 142

<0> [520.523512] kworker/-170 21..... 181753606us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:26, current 142

<0> [520.523635] kworker/-170 21..... 181753629us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:28, current 142

<0> [520.523758] kworker/-170 21..... 181753639us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:30, current 142

<7> [520.523739] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.523881] kworker/-170 21..... 181753646us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:32, current 142

<0> [520.523986] kworker/-170 21..... 181753658us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:34, current 142

<0> [520.524109] kworker/-170 21..... 181753667us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:36, current 142

<0> [520.524232] kworker/-170 21..... 181753676us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:38, current 142

<0> [520.524355] kworker/-170 21..... 181753683us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:40, current 142

<0> [520.524477] kworker/-170 21..... 181753690us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:42, current 142

<0> [520.524600] kworker/-170 21..... 181753697us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:44, current 142

<0> [520.524723] kworker/-170 21..... 181753707us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:46, current 142

<0> [520.524846] kworker/-170 21..... 181753713us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:48, current 142

<0> [520.524966] kworker/-170 21..... 181753721us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:50, current 142

<0> [520.525085] kworker/-170 21..... 181753728us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:52, current 142

<0> [520.525204] kworker/-170 21..... 181753735us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:54, current 142

<0> [520.525324] kworker/-170 21..... 181753743us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:56, current 142

<0> [520.525443] kworker/-170 21..... 181753751us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:58, current 142

<0> [520.525563] kworker/-170 21..... 181753757us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:60, current 142

<0> [520.525683] kworker/-170 21..... 181753764us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:62, current 142

<0> [520.525803] kworker/-170 21..... 181753771us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:64, current 142

<0> [520.525922] kworker/-170 21..... 181753777us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:66, current 142

<0> [520.526041] kworker/-170 21..... 181753784us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:68, current 142

<0> [520.526160] kworker/-170 21..... 181753791us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:70, current 142

<7> [520.526169] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.526280] kworker/-170 21..... 181753798us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:72, current 142

<0> [520.526382] kworker/-170 21..... 181753805us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:74, current 142

<0> [520.526502] kworker/-170 21..... 181753821us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:76, current 142

<0> [520.526621] kworker/-170 21..... 181753829us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:78, current 142

<0> [520.526739] kworker/-170 21..... 181753836us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:80, current 142

<0> [520.526858] kworker/-170 21..... 181753842us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:82, current 142

<0> [520.526976] kworker/-170 21..... 181753849us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:84, current 142

<0> [520.527095] kworker/-170 21..... 181753856us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:86, current 142

<0> [520.527214] kworker/-170 21..... 181753862us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:88, current 142

<0> [520.527333] kworker/-170 21..... 181753871us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:90, current 142

<0> [520.527452] kworker/-170 21..... 181753885us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:92, current 142

<0> [520.527571] kworker/-170 21..... 181753894us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:94, current 142

<0> [520.527690] kworker/-170 21..... 181753901us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:96, current 142

<0> [520.527809] kworker/-170 21..... 181753910us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:98, current 142

<0> [520.527928] kworker/-170 21..... 181753919us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:100, current 142

<0> [520.528048] kworker/-170 21..... 181753928us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:102, current 142

<0> [520.528171] kworker/-170 21..... 181753937us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:104, current 142

<0> [520.528298] kworker/-170 21..... 181753944us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:106, current 142

<0> [520.528422] kworker/-170 21..... 181753951us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:108, current 142

<0> [520.528546] kworker/-170 21..... 181753959us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:110, current 142

<7> [520.528526] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.528669] kworker/-170 21..... 181753967us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:112, current 142

<0> [520.528774] kworker/-170 21..... 181753973us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:114, current 142

<0> [520.528898] kworker/-170 21..... 181753980us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:116, current 142

<0> [520.529019] kworker/-170 21..... 181753986us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:118, current 142

<0> [520.529138] kworker/-170 21..... 181753993us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:120, current 142

<0> [520.529259] kworker/-170 21..... 181753999us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:122, current 142

<0> [520.529385] kworker/-170 21..... 181754006us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:124, current 142

<0> [520.529509] kworker/-170 21..... 181754013us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:126, current 142

<0> [520.529629] kworker/-170 21..... 181754019us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:128, current 142

<0> [520.529748] kworker/-170 21..... 181754025us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:130, current 142

<0> [520.529868] kworker/-170 21..... 181754031us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:132, current 142

<0> [520.529988] kworker/-170 21..... 181754038us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:134, current 142

<0> [520.530108] kworker/-170 21..... 181754045us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:136, current 142

<0> [520.530228] kworker/-170 21..... 181754051us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:138, current 142

<0> [520.530348] kworker/-170 21..... 181754057us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:140, current 142

<0> [520.530468] kworker/-170 21..... 181754064us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 19b:142, current 142

<0> [520.530587] kworker/-170 21..... 181754228us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:4, current 142

<0> [520.530706] kworker/-170 21..... 181754237us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:6, current 142

<0> [520.530826] kworker/-170 21..... 181754244us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:8, current 142

<7> [520.530787] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.530946] kworker/-170 21..... 181754251us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:10, current 142

<0> [520.531049] kworker/-170 21..... 181754258us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:12, current 142

<0> [520.531172] kworker/-170 21..... 181754264us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:14, current 142

<0> [520.531299] kworker/-170 21..... 181754276us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:16, current 142

<0> [520.531428] kworker/-170 21..... 181754285us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:18, current 142

<0> [520.531552] kworker/-170 21..... 181754293us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:20, current 142

<0> [520.531676] kworker/-170 21..... 181754302us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:22, current 142

<0> [520.531799] kworker/-170 21..... 181754311us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:24, current 142

<0> [520.531922] kworker/-170 21..... 181754319us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:26, current 142

<0> [520.532046] kworker/-170 21..... 181754326us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:28, current 142

<0> [520.532170] kworker/-170 21..... 181754337us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:30, current 142

<0> [520.532293] kworker/-170 21..... 181754344us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:32, current 142

<0> [520.532420] kworker/-170 21..... 181754351us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:34, current 142

<0> [520.532542] kworker/-170 21..... 181754358us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:36, current 142

<0> [520.532662] kworker/-170 21..... 181754366us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:38, current 142

<0> [520.532782] kworker/-170 21..... 181754374us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:40, current 142

<0> [520.532902] kworker/-170 21..... 181754381us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:42, current 142

<0> [520.533022] kworker/-170 21..... 181754387us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:44, current 142

<0> [520.533143] kworker/-170 21..... 181754394us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:46, current 142

<0> [520.533264] kworker/-170 21..... 181754400us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:48, current 142

<0> [520.533387] kworker/-170 21..... 181754410us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:50, current 142

<7> [520.533445] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.533516] kworker/-170 21..... 181754418us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:52, current 142

<0> [520.533625] kworker/-170 21..... 181754426us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:54, current 142

<0> [520.533751] kworker/-170 21..... 181754432us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:56, current 142

<0> [520.533870] kworker/-170 21..... 181754439us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:58, current 142

<0> [520.533989] kworker/-170 21..... 181754445us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:60, current 142

<0> [520.534114] kworker/-170 21..... 181754452us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:62, current 142

<0> [520.534241] kworker/-170 21..... 181754458us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:64, current 142

<0> [520.534369] kworker/-170 21..... 181754466us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:66, current 142

<0> [520.534493] kworker/-170 21..... 181754472us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:68, current 142

<0> [520.534613] kworker/-170 21..... 181754478us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:70, current 142

<0> [520.534734] kworker/-170 21..... 181754485us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:72, current 142

<0> [520.534855] kworker/-170 21..... 181754491us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:74, current 142

<0> [520.534976] kworker/-170 21..... 181754497us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:76, current 142

<0> [520.535097] kworker/-170 21..... 181754506us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:78, current 142

<0> [520.535217] kworker/-170 21..... 181754519us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:80, current 142

<0> [520.535344] kworker/-170 21..... 181754528us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:82, current 142

<0> [520.535472] kworker/-170 21..... 181754537us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:84, current 142

<0> [520.535598] kworker/-170 21..... 181754546us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:86, current 142

<0> [520.535718] kworker/-170 21..... 181754554us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:88, current 142

<0> [520.535838] kworker/-170 21..... 181754562us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:90, current 142

<0> [520.535957] kworker/-170 21..... 181754569us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:92, current 142

<0> [520.536077] kworker/-170 21..... 181754576us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:94, current 142

<0> [520.536198] kworker/-170 21..... 181754584us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:96, current 142

<7> [520.536148] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.536325] kworker/-170 21..... 181754593us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:98, current 142

<0> [520.536451] kworker/-170 21..... 181754599us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:100, current 142

<0> [520.536578] kworker/-170 21..... 181754605us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:102, current 142

<0> [520.536702] kworker/-170 21..... 181754612us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:104, current 142

<0> [520.536822] kworker/-170 21..... 181754619us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:106, current 142

<0> [520.536947] kworker/-170 21..... 181754625us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:108, current 142

<0> [520.537075] kworker/-170 21..... 181754631us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:110, current 142

<0> [520.537203] kworker/-170 21..... 181754637us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:112, current 142

<0> [520.537330] kworker/-170 21..... 181754643us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:114, current 142

<0> [520.537457] kworker/-170 21..... 181754649us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:116, current 142

<0> [520.537580] kworker/-170 21..... 181754656us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:118, current 142

<0> [520.537708] kworker/-170 21..... 181754663us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:120, current 142

<0> [520.537833] kworker/-170 21..... 181754671us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:122, current 142

<0> [520.537957] kworker/-170 21..... 181754678us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:124, current 142

<0> [520.538081] kworker/-170 21..... 181754684us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:126, current 142

<0> [520.538205] kworker/-170 21..... 181754691us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:128, current 142

<0> [520.538333] kworker/-170 21..... 181754698us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:130, current 142

<0> [520.538459] kworker/-170 21..... 181754708us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:132, current 142

<0> [520.538586] kworker/-170 21..... 181754715us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:134, current 142

<0> [520.538706] kworker/-170 21..... 181754721us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:136, current 142

<0> [520.538826] kworker/-170 21..... 181754728us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:138, current 142

<7> [520.538826] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.538946] kworker/-170 21..... 181754735us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:140, current 142

<0> [520.539053] kworker/-170 21..... 181754744us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 19d:142, current 142

<0> [520.539181] kworker/-170 21..... 187764736us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1c3:2, current 2

<0> [520.539308] kworker/-170 21..... 187765444us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:21, current 21

<0> [520.539437] kworker/-443 21..... 202229964us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:22, current 21

<0> [520.539565] kworker/-443 21D..1. 202229981us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:22, current 21

<0> [520.539688] kworker/-170 21..... 213684022us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:22, current 22

<0> [520.539807] kworker/-170 21..... 213684072us : __engine_park: 0000:00:02.0 rcs0: parked

<0> [520.539923] kworker/-170 21..... 213684247us : __gt_park: 0000:00:02.0

<0> [520.540043] kworker/-430 20..... 217780135us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5:23, current 22

<0> [520.540170] kworker/-430 20D..1. 217780165us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5:23, current 22

<0> [520.540292] gem_exec-2345 20..... 229734151us : __gt_unpark: 0000:00:02.0

<0> [520.540408] kworker/-171 20..... 229734728us : __gt_park: 0000:00:02.0

<0> [520.540528] kworker/-170 21..... 231731703us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1cc:2, current 2

<0> [520.540648] kworker/-170 21..... 231731968us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:4, current 5

<0> [520.540775] kworker/-170 21..... 231731975us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:5, current 5

<0> [520.540896] kworker/-170 21..... 231732040us : __gt_park: 0000:00:02.0

<0> [520.541018] kworker/-170 21..... 233779778us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1ce:2, current 2

<0> [520.541146] kworker/-170 21..... 233780013us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:4, current 5

<7> [520.541105] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.541274] kworker/-170 21..... 233780023us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:5, current 5

<0> [520.541392] kworker/-170 21..... 233780103us : __gt_park: 0000:00:02.0

<0> [520.541507] kworker/-170 21..... 235763730us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1d0:2, current 2

<0> [520.541627] kworker/-170 21..... 235763958us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence e:4, current 5

<0> [520.541748] kworker/-170 21..... 235763968us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence e:5, current 5

<0> [520.541862] kworker/-170 21..... 235764056us : __gt_park: 0000:00:02.0

<0> [520.541974] kms_curs-2359 21..... 263198421us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [520.542089] kms_curs-2359 21..... 263198775us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes

<0> [520.542213] kworker/-170 21..... 263731331us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 291:2, current 2

<0> [520.542341] kworker/-170 21..... 263731821us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:29, current 29

<0> [520.542466] kworker/-170 21..... 263731854us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:506, current 509

<0> [520.542587] kworker/-170 21..... 263731861us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:507, current 509

<0> [520.542707] kworker/-170 21..... 263731901us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:508, current 509

<0> [520.542827] kworker/-170 21..... 263731919us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:509, current 509

<0> [520.542942] kworker/-170 21..... 263731939us : __engine_park: 0000:00:02.0 bcs0: parked

<0> [520.543052] gem_exec-2401 20..... 283413341us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:88c active

<0> [520.543163] gem_exec-2381 20..... 283431623us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:876 pin ring:{start:f29a0000, head:0000, tail:0000}

<0> [520.543297] gem_exec-2381 20..... 283431915us : __i915_request_commit: 0000:00:02.0 vcs1: fence 876:2, current 0

<0> [520.543428] gem_exec-2381 20D..1. 283431935us : __i915_request_submit: 0000:00:02.0 vcs1: fence 876:2, current 0

<0> [520.543557] gem_exec-2381 20..... 283432151us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11308, current 11310

<0> [520.543683] gem_exec-2381 20..... 283432255us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11312, current 11310

<0> [520.543804] gem_exec-2381 20..... 283432376us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11310, current 11310

<0> [520.543926] gem_exec-2381 20..... 283432423us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11314, current 11310

<0> [520.544046] gem_exec-2381 20..... 283432662us : intel_rps_boost: 0000:00:02.0 boost fence:298:2c30

<7> [520.544003] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.544174] gem_exec-2381 20..... 283433792us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11312, current 11314

<0> [520.544307] gem_exec-2381 20..... 283433992us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11316, current 11314

<0> [520.544437] gem_exec-2381 20D..1. 283434012us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11316, current 11314

<0> [520.544558] gem_exec-2384 20..... 283436056us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:880 pin ring:{start:f28c3000, head:0000, tail:0000}

<0> [520.544692] gem_exec-2384 20..... 283436779us : __i915_request_commit: 0000:00:02.0 rcs0: fence 880:2, current 0

<0> [520.544817] gem_exec-2384 20D..1. 283436787us : __i915_request_submit: 0000:00:02.0 rcs0: fence 880:2, current 0

<0> [520.544940] gem_exec-2384 20..... 283436900us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11314, current 11316

<0> [520.545064] gem_exec-2384 20..... 283437285us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11318, current 11316

<0> [520.545186] gem_exec-2384 20D..1. 283437293us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11318, current 11316

<0> [520.545311] gem_exec-2384 20..... 283437350us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11316, current 11316

<0> [520.545442] gem_exec-2384 20..... 283437369us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11320, current 11316

<0> [520.545571] gem_exec-2384 20D..1. 283437377us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11320, current 11316

<0> [520.545697] gem_exec-2384 20..... 283437491us : intel_rps_boost: 0000:00:02.0 boost fence:298:2c36

<0> [520.545825] gem_exec-2384 21..... 283438104us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11318, current 11320

<0> [520.545953] gem_exec-2384 21..... 283438161us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11322, current 11320

<0> [520.546077] gem_exec-2384 21D..1. 283438181us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11322, current 11320

<0> [520.546195] gem_exec-2384 21..... 283510983us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad active

<0> [520.546324] gem_exec-2384 21..... 283563608us : intel_timeline_pin: 0000:00:02.0 timeline:8ad using HWSP offset:f2202000

<0> [520.546451] gem_exec-2384 21..... 283584101us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad pin ring:{start:f2263000, head:0000, tail:0000}

<7> [520.546425] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.546577] gem_exec-2384 21..... 283584129us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:8ad unpin

<0> [520.546677] gem_exec-2384 21..... 283584208us : __intel_context_retire: 0000:00:02.0 rcs0: context:8ad retire runtime: { total:0ns, avg:0ns }

<0> [520.546797] gem_exec-2384 21..... 283584214us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad active

<0> [520.546921] gem_exec-2384 21..... 283584217us : intel_timeline_pin: 0000:00:02.0 timeline:8ad using HWSP offset:f2202000

<0> [520.547039] gem_exec-2384 21..... 283584223us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad pin ring:{start:f2263000, head:0000, tail:0000}

<0> [520.547162] gem_exec-2384 21..... 283584244us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:8ad unpin

<0> [520.547279] gem_exec-2384 21..... 283584322us : __intel_context_retire: 0000:00:02.0 rcs0: context:8ad retire runtime: { total:0ns, avg:0ns }

<0> [520.547397] gem_exec-2384 21..... 283584326us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad active

<0> [520.547521] gem_exec-2384 21..... 283584328us : intel_timeline_pin: 0000:00:02.0 timeline:8ad using HWSP offset:f2202000

<0> [520.547638] gem_exec-2384 21..... 283584333us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:8ad pin ring:{start:f2263000, head:0000, tail:0000}

<0> [520.547772] gem_exec-2384 21..... 283584734us : __i915_request_commit: 0000:00:02.0 rcs0: fence 8ad:2, current 0

<0> [520.547902] gem_exec-2384 21D..1. 283584746us : __i915_request_submit: 0000:00:02.0 rcs0: fence 8ad:2, current 0

<0> [520.548031] gem_exec-2384 21..... 283585030us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11702, current 11700

<0> [520.548157] gem_exec-2384 21D..1. 283585042us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11702, current 11700

<0> [520.548279] gem_exec-2377 20..... 283585408us : intel_timeline_pin: 0000:00:02.0 timeline:8b2 using HWSP offset:f20f8000

<0> [520.548405] gem_exec-2384 21..... 283585418us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11700, current 11700

<0> [520.548536] gem_exec-2384 21..... 283585451us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:11704, current 11700

<0> [520.548666] gem_exec-2384 21D..1. 283585459us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:11704, current 11700

<0> [520.548795] gem_exec-2384 21..... 283585851us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:11702, current 11704

<0> [520.548924] gem_exec-2407 20..... 285066276us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21418, current 21416

<7> [520.548899] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.549053] gem_exec-2407 20D..1. 285066303us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:21418, current 21416

<0> [520.549173] gem_exec-2407 20..... 285067300us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:735 active

<0> [520.549300] gem_exec-2407 20..... 285067311us : intel_timeline_pin: 0000:00:02.0 timeline:735 using HWSP offset:f5275000

<0> [520.549421] gem_exec-2407 20..... 285067333us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:735 pin ring:{start:f530c000, head:0168, tail:0168}

<0> [520.549555] gem_exec-2407 20..... 285067472us : __i915_request_commit: 0000:00:02.0 ccs0: fence 735:4, current 2

<0> [520.549686] gem_exec-2407 20D..1. 285067489us : __i915_request_submit: 0000:00:02.0 ccs0: fence 735:4, current 2

<0> [520.549816] gem_exec-2407 20..... 285067743us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21426, current 21420

<0> [520.549947] gem_exec-2407 20..... 285068104us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21428, current 21420

<0> [520.550078] gem_exec-2407 20..... 285069483us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:21436, current 21430

<0> [520.550200] gem_exec-2407 20..... 285069801us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:8a7 active

<0> [520.550327] gem_exec-2407 20.N... 285069859us : intel_timeline_pin: 0000:00:02.0 timeline:8a7 using HWSP offset:f2223000

<0> [520.550459] gem_exec-2395 20..... 285353776us : __i915_request_commit: 0000:00:02.0 ccs0: fence afe:2, current 0

<0> [520.550590] gem_exec-2395 20D..1. 285354801us : __i915_request_submit: 0000:00:02.0 ccs0: fence afe:2, current 0

<0> [520.550711] gem_exec-2394 20..... 286105474us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 pin ring:{start:eb539000, head:0000, tail:0000}

<0> [520.550835] gem_exec-2394 20..... 286105541us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:bf4 unpin

<0> [520.550955] gem_exec-2394 20..... 286105713us : __intel_context_retire: 0000:00:02.0 vecs0: context:bf4 retire runtime: { total:0ns, avg:0ns }

<0> [520.551076] gem_exec-2394 20..... 286105724us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 active

<0> [520.551202] gem_exec-2394 20..... 286105730us : intel_timeline_pin: 0000:00:02.0 timeline:bf4 using HWSP offset:eb449000

<0> [520.551321] gem_exec-2394 20..... 286105741us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 pin ring:{start:eb539000, head:0000, tail:0000}

<7> [520.551283] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508

<0> [520.551444] gem_exec-2394 20..... 286105775us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:bf4 unpin

<0> [520.551561] gem_exec-2394 20..... 286105961us : __intel_context_retire: 0000:00:02.0 vecs0: context:bf4 retire runtime: { total:0ns, avg:0ns }

<0> [520.551681] gem_exec-2394 20..... 286105969us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 active

<0> [520.551806] gem_exec-2394 20..... 286105973us : intel_timeline_pin: 0000:00:02.0 timeline:bf4 using HWSP offset:eb449000

<0> [520.551923] gem_exec-2394 20..... 286105983us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bf4 pin ring:{start:eb539000, head:0000, tail:0000}

<0> [520.552061] gem_exec-2394 20..... 286106177us : __i915_request_commit: 0000:00:02.0 vecs0: fence bf4:2, current 0

<0> [520.552194] gem_exec-2394 20D..1. 286106195us : __i915_request_submit: 0000:00:02.0 vecs0: fence bf4:2, current 0

<0> [520.552326] gem_exec-2394 20..... 286106392us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31730, current 31742

<0> [520.552458] gem_exec-2394 20..... 286106488us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:31754, current 31746

<0> [520.552589] gem_exec-2394 20..... 286106502us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31732, current 31746

<0> [520.552721] gem_exec-2394 20..... 286106523us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31734, current 31747

<0> [520.552852] gem_exec-2394 20..... 286106528us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31736, current 31748

<0> [520.552983] gem_exec-2394 20..... 286106531us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31738, current 31748

<0> [520.553115] gem_exec-2394 20..... 286106536us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31740, current 31748

<0> [520.553246] gem_exec-2394 20..... 286106540us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31742, current 31748

<0> [520.553376] gem_exec-2394 20..... 286106544us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31744, current 31748

<0> [520.553506] gem_exec-2394 20..... 286106547us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31746, current 31748

<0> [520.553636] gem_exec-2394 20..... 286106552us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31748, current 31749

<0> [520.553766] gem_exec-2394 20..... 286106572us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31750, current 31750

<0> [520.553897] gem_exec-2393 21..... 286107638us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31762, current 31764

<0> [520.554029] gem_exec-2393 21..... 286107685us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:31766, current 31764

<0> [520.554160] gem_exec-2393 21D..1. 286107698us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:31766, current 31764

<0> [520.554290] gem_exec-2394 20..... 286107716us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:31764, current 31764

<0> [520.554421] gem_exec-2394 20..... 286107756us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:31768, current 31764

<0> [520.554552] gem_exec-2394 20D..1. 286107767us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:31768, current 31764

<0> [520.554674] gem_exec-2393 21..... 286108152us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 active

<0> [520.554801] gem_exec-2393 21..... 286108166us : intel_timeline_pin: 0000:00:02.0 timeline:7c9 using HWSP offset:f3ec9000

<0> [520.554921] gem_exec-2393 21..... 286108190us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 pin ring:{start:f4057000, head:0210, tail:0210}

<0> [520.555044] gem_exec-2393 21..... 286108309us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:7c9 unpin

<0> [520.555163] gem_exec-2393 21..... 286108476us : __intel_context_retire: 0000:00:02.0 vcs1: context:7c9 retire runtime: { total:139178ns, avg:48813ns }

<0> [520.555285] gem_exec-2393 21..... 286108485us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 active

<0> [520.555409] gem_exec-2393 21..... 286108488us : intel_timeline_pin: 0000:00:02.0 timeline:7c9 using HWSP offset:f3ec9000

<0> [520.555528] gem_exec-2393 21..... 286108498us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 pin ring:{start:f4057000, head:0210, tail:0210}

<0> [520.555650] gem_exec-2393 21..... 286108533us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:7c9 unpin

<0> [520.555767] gem_exec-2393 21..... 286108700us : __intel_context_retire: 0000:00:02.0 vcs1: context:7c9 retire runtime: { total:139178ns, avg:48813ns }

<0> [520.555887] gem_exec-2393 21..... 286108705us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 active

<0> [520.556012] gem_exec-2393 21..... 286108708us : intel_timeline_pin: 0000:00:02.0 timeline:7c9 using HWSP offset:f3ec9000

<0> [520.556130] gem_exec-2393 21..... 286108717us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:7c9 pin ring:{start:f4057000, head:0210, tail:0210}

<0> [520.556267] gem_exec-2393 21..... 286108843us : __i915_request_commit: 0000:00:02.0 vcs1: fence 7c9:8, current 6

<0> [520.556399] gem_exec-2393 21D..1. 286108854us : __i915_request_submit: 0000:00:02.0 vcs1: fence 7c9:8, current 6

<0> [520.556526] gem_exec-2385 20.N... 286111239us : intel_timeline_pin: 0000:00:02.0 timeline:bff using HWSP offset:eb2ba000

<0> [520.556647] gem_exec-2389 20..... 286255702us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c3c active

<0> [520.556772] gem_exec-2389 20..... 286303032us : intel_timeline_pin: 0000:00:02.0 timeline:c3c using HWSP offset:eab35000

<0> [520.556891] gem_exec-2402 20..... 286485303us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:405 active

<0> [520.557016] gem_exec-2402 20..... 286485320us : intel_timeline_pin: 0000:00:02.0 timeline:405 using HWSP offset:fbe81000

<0> [520.557135] gem_exec-2402 20..... 286485352us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:405 pin ring:{start:fbf50000, head:05a0, tail:05a0}

<0> [520.557275] gem_exec-2402 20..... 286485644us : __i915_request_commit: 0000:00:02.0 rcs0: fence 405:10, current 8

<0> [520.557416] gem_exec-2402 20D..1. 286485897us : __i915_request_submit: 0000:00:02.0 rcs0: fence 405:10, current 8

<0> [520.557556] gem_exec-2377 21..... 286742828us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40366, current 40351

<0> [520.557692] gem_exec-2377 21D..1. 286742852us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40366, current 40352

<0> [520.557823] gem_exec-2377 21..... 286743210us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40352, current 40366

<0> [520.557955] gem_exec-2377 21..... 286743222us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40354, current 40366

<0> [520.558085] gem_exec-2377 21..... 286743232us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40356, current 40366

<0> [520.558217] gem_exec-2377 21..... 286743239us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40358, current 40366

<0> [520.558348] gem_exec-2377 21..... 286743247us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40360, current 40366

<0> [520.558479] gem_exec-2377 21..... 286743257us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40362, current 40366

<0> [520.558611] gem_exec-2377 21..... 286744756us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40414, current 40376

<0> [520.558741] gem_exec-2376 20..... 286744864us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40418, current 40376

<0> [520.558862] gem_exec-2377 21..... 286745138us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b31 active

<0> [520.558989] gem_exec-2377 21..... 286745148us : intel_timeline_pin: 0000:00:02.0 timeline:b31 using HWSP offset:ecc7e000

<0> [520.559109] gem_exec-2376 20..... 286745154us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 active

<0> [520.559235] gem_exec-2376 20..... 286745163us : intel_timeline_pin: 0000:00:02.0 timeline:4e4 using HWSP offset:fa14e000

<0> [520.559355] gem_exec-2377 21..... 286745168us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b31 pin ring:{start:ecd4c000, head:0168, tail:0168}

<0> [520.559487] gem_exec-2376 20..... 286745181us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 pin ring:{start:fa1ce000, head:0370, tail:0370}

<0> [520.559616] gem_exec-2376 20..... 286745244us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:4e4 unpin

<0> [520.559749] gem_exec-2377 21..... 286745294us : __i915_request_commit: 0000:00:02.0 ccs0: fence b31:4, current 2

<0> [520.559882] gem_exec-2377 21D..1. 286745309us : __i915_request_submit: 0000:00:02.0 ccs0: fence b31:4, current 2

<0> [520.560002] gem_exec-2376 20..... 286745335us : __intel_context_retire: 0000:00:02.0 vecs0: context:4e4 retire runtime: { total:110929ns, avg:18550ns }

<0> [520.560129] gem_exec-2376 20..... 286745345us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 active

<0> [520.560262] gem_exec-2376 20..... 286745350us : intel_timeline_pin: 0000:00:02.0 timeline:4e4 using HWSP offset:fa14e000

<0> [520.560385] gem_exec-2376 20..... 286745363us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:4e4 pin ring:{start:fa1ce000, head:0370, tail:0370}

<0> [520.560522] gem_exec-2390 20..... 286752077us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40458, current 40437

<0> [520.560654] gem_exec-2390 20D..1. 286752090us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40458, current 40438

<0> [520.560786] gem_exec-2390 20..... 286752100us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40436, current 40439

<0> [520.560918] gem_exec-2390 20..... 286752110us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40438, current 40439

<0> [520.561050] gem_exec-2390 20..... 286752116us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40440, current 40440

<0> [520.561181] gem_exec-2377 21..... 286753232us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40476, current 40463

<0> [520.561319] gem_exec-2377 21D..1. 286753247us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40476, current 40464

<0> [520.561458] gem_exec-2377 21..... 286753259us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40464, current 40464

<0> [520.561598] gem_exec-2390 20..... 286754052us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40494, current 40484

<0> [520.561731] gem_exec-2390 20D..1. 286754067us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:40494, current 40484

<0> [520.561858] gem_exec-2390 20..... 286754192us : intel_rps_boost: 0000:00:02.0 boost fence:298:9e2e

<0> [520.561984] gem_exec-2377 21..... 286754858us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40526, current 40496

<0> [520.562114] gem_exec-2401 20..... 286755923us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40578, current 40520

<0> [520.562244] gem_exec-2387 21..... 286756270us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40526, current 40526

<0> [520.562376] gem_exec-2387 21..... 286756440us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40598, current 40528

<0> [520.562506] gem_exec-2387 21..... 286756454us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40528, current 40528

<0> [520.562637] gem_exec-2374 21..... 286758196us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40616, current 40559

<0> [520.562766] gem_exec-2374 21..... 286758210us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40558, current 40559

<0> [520.562897] gem_exec-2374 21..... 286758233us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40560, current 40560

<0> [520.563027] gem_exec-2383 20..... 286758485us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:40634, current 40566

<0> [520.563158] gem_exec-2383 20..... 286758499us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40566, current 40566

<0> [520.563287] gem_exec-2383 20..... 286758530us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:40568, current 40568

<0> [520.563410] gem_exec-2374 21..... 286758938us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:44d active

<0> [520.563536] gem_exec-2374 21..... 286758949us : intel_timeline_pin: 0000:00:02.0 timeline:44d using HWSP offset:fb3b1000

<0> [520.563656] gem_exec-2374 21..... 286758974us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:44d pin ring:{start:fb546000, head:0160, tail:0160}

<0> [520.563792] gem_exec-2374 21..... 286759161us : __i915_request_commit: 0000:00:02.0 vcs0: fence 44d:6, current 4

<0> [520.563923] gem_exec-2374 21DN.1. 286759176us : __i915_request_submit: 0000:00:02.0 vcs0: fence 44d:6, current 4

<0> [520.564043] gem_exec-2403 21..... 286771984us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:94b active

<0> [520.564169] gem_exec-2403 21..... 286771993us : intel_timeline_pin: 0000:00:02.0 timeline:94b using HWSP offset:f0d02000

<0> [520.564301] gem_exec-2391 20..... 286929441us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43376, current 43344

<0> [520.564432] gem_exec-2391 20D..1. 286929470us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43376, current 43346

<0> [520.564562] gem_exec-2391 20..... 286929859us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43340, current 43350

<0> [520.564693] gem_exec-2391 20..... 286929880us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43342, current 43350

<0> [520.564823] gem_exec-2391 20..... 286929894us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43344, current 43351

<0> [520.564956] gem_exec-2391 20..... 286929908us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43346, current 43352

<0> [520.565095] gem_exec-2391 20.N... 286929924us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43348, current 43352

<0> [520.565234] gem_exec-2391 20.N... 286929937us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43350, current 43352

<0> [520.565373] gem_exec-2391 20.N... 286929949us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43352, current 43352

<0> [520.565512] gem_exec-2380 20..... 286938285us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43504, current 43438

<0> [520.565651] gem_exec-2380 20D..1. 286938305us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43504, current 43440

<0> [520.565790] gem_exec-2380 20..... 286938319us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43440, current 43440

<0> [520.565929] gem_exec-2383 20..... 286940259us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43552, current 43492

<0> [520.566068] gem_exec-2373 20..... 286944100us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43640, current 43592

<0> [520.566202] gem_exec-2373 20D..1. 286944121us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43640, current 43594

<0> [520.566331] gem_exec-2373 20..... 286944135us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43574, current 43594

<0> [520.566462] gem_exec-2373 20..... 286944146us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43576, current 43594

<0> [520.566590] gem_exec-2373 20..... 286944151us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43578, current 43594

<0> [520.566720] gem_exec-2373 20..... 286944168us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43580, current 43594

<0> [520.566850] gem_exec-2373 20..... 286944176us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43582, current 43594

<0> [520.566980] gem_exec-2373 20..... 286944181us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43584, current 43594

<0> [520.567110] gem_exec-2373 20..... 286944189us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43586, current 43594

<0> [520.567239] gem_exec-2373 20..... 286944193us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43588, current 43594

<0> [520.567369] gem_exec-2373 20..... 286944196us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43590, current 43594

<0> [520.567507] gem_exec-2373 20..... 286944199us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43592, current 43594

<0> [520.567645] gem_exec-2373 20.N... 286944208us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43594, current 43594

<0> [520.567784] gem_exec-2367 20.N... 286944725us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43602, current 43610

<0> [520.567922] gem_exec-2367 20.N... 286944739us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43604, current 43611

<0> [520.568055] gem_exec-2383 20..... 286945159us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:43660, current 43630

<0> [520.568184] gem_exec-2383 20D..1. 286945513us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:43660, current 43634

<0> [520.568313] gem_exec-2383 20..... 286945524us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43628, current 43634

<0> [520.568443] gem_exec-2383 20..... 286945532us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43630, current 43634

<0> [520.568572] gem_exec-2383 20..... 286945540us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43632, current 43634

<0> [520.568701] gem_exec-2383 20..... 286945546us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:43634, current 43634

gem_exec-2394 0..... 287005697us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44614, current 44560

<0> [520.568967] gem_exec-2394 0D..1. 287005702us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:44614, current 44560

<0> [520.569097] gem_exec-2392 0..... 287006190us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44644, current 44569

<0> [520.569225] gem_exec-2394 0..... 287011263us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44696, current 44660

<0> [520.569354] gem_exec-2394 0D..1. 287011270us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:44696, current 44660

<0> [520.569476] gem_exec-2394 0..... 287011414us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:54d active

<0> [520.569602] gem_exec-2394 0..... 287011418us : intel_timeline_pin: 0000:00:02.0 timeline:54d using HWSP offset:f93b5000

<0> [520.569723] gem_exec-2394 0..... 287011430us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:54d pin ring:{start:f94be000, head:0370, tail:0370}

<0> [520.569859] gem_exec-2394 0..... 287011472us : __i915_request_commit: 0000:00:02.0 vecs0: fence 54d:12, current 10

<0> [520.569989] gem_exec-2394 0D..1. 287011477us : __i915_request_submit: 0000:00:02.0 vecs0: fence 54d:12, current 10

<0> [520.570119] gem_exec-2394 0..... 287011866us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44724, current 44670

<0> [520.570249] gem_exec-2394 0..... 287011871us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:44670, current 44670

<0> [520.570387] gem_exec-2396 0..... 287012250us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44754, current 44676

<0> [520.570517] kworker/-2413 0..... 287018055us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:447 unpin

<0> [520.570645] kworker/-2413 0..... 287018186us : __intel_context_retire: 0000:00:02.0 ccs0: context:447 retire runtime: { total:336974ns, avg:79712ns }

<0> [520.570776] kworker/-2413 0..... 287018199us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:3c4 unpin

<0> [520.570898] kworker/-2413 0..... 287018329us : __intel_context_retire: 0000:00:02.0 ccs0: context:3c4 retire runtime: { total:241733ns, avg:92644ns }

<0> [520.571020] kworker/-2413 0..... 287018684us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:9ab unpin

<0> [520.571138] kworker/-2413 0.N... 287018819us : __intel_context_retire: 0000:00:02.0 vcs0: context:9ab retire runtime: { total:129320ns, avg:56392ns }

<0> [520.571276] gem_exec-2378 0..... 287020645us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44822, current 44795

<0> [520.571416] gem_exec-2378 0..... 287020651us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:44796, current 44796

<0> [520.571537] gem_exec-2378 0..... 287022044us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:73b active

<0> [520.571663] gem_exec-2378 0..... 287022047us : intel_timeline_pin: 0000:00:02.0 timeline:73b using HWSP offset:f51bc000

<0> [520.571782] gem_exec-2378 0..... 287022057us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:73b pin ring:{start:f5288000, head:05a0, tail:05a0}

<0> [520.571918] gem_exec-2378 0..... 287022095us : __i915_request_commit: 0000:00:02.0 rcs0: fence 73b:10, current 8

<0> [520.572049] gem_exec-2378 0D..1. 287022179us : __i915_request_submit: 0000:00:02.0 rcs0: fence 73b:10, current 8

<0> [520.572180] gem_exec-2378 0..... 287027016us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:44938, current 44856

<0> [520.572311] gem_exec-2378 0D..1. 287027021us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:44938, current 44856

<0> [520.572442] gem_exec-2398 0..... 287032355us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45018, current 44974

<0> [520.572573] gem_exec-2376 0..... 287032929us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45052, current 44992

<0> [520.572693] gem_exec-2376 0..... 287033085us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:8fb active

<0> [520.572820] gem_exec-2376 0..... 287033089us : intel_timeline_pin: 0000:00:02.0 timeline:8fb using HWSP offset:f1827000

<0> [520.572941] gem_exec-2376 0..... 287033099us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:8fb pin ring:{start:f1982000, head:0370, tail:0370}

<0> [520.573078] gem_exec-2376 0..... 287033145us : __i915_request_commit: 0000:00:02.0 vecs0: fence 8fb:12, current 10

<0> [520.573210] gem_exec-2376 0D..1. 287033151us : __i915_request_submit: 0000:00:02.0 vecs0: fence 8fb:12, current 10

<0> [520.573341] gem_exec-2373 0..... 287038408us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45146, current 45066

<0> [520.573462] gem_exec-2373 0..... 287038584us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3cb active

<0> [520.573587] gem_exec-2373 0..... 287038587us : intel_timeline_pin: 0000:00:02.0 timeline:3cb using HWSP offset:fc595000

<0> [520.573707] gem_exec-2373 0..... 287038596us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:3cb pin ring:{start:fc5fd000, head:0160, tail:0160}

<0> [520.573844] gem_exec-2373 0..... 287038632us : __i915_request_commit: 0000:00:02.0 bcs0: fence 3cb:6, current 4

<0> [520.573975] gem_exec-2373 0Dn.1. 287039364us : __i915_request_submit: 0000:00:02.0 bcs0: fence 3cb:6, current 4

<0> [520.574105] gem_exec-2383 0..... 287040171us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45072, current 45074

<0> [520.574237] gem_exec-2383 0..... 287040185us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45160, current 45074

<0> [520.574367] gem_exec-2383 0..... 287040189us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45074, current 45074

<0> [520.574498] gem_exec-2383 0..... 287044048us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45184, current 45139

<0> [520.574629] gem_exec-2383 0D..1. 287044054us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45184, current 45139

<0> [520.574760] gem_exec-2383 0..... 287044061us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45140, current 45140

<0> [520.574892] gem_exec-2409 0..... 287045810us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45240, current 45160

<0> [520.575022] gem_exec-2372 0..... 287046136us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45248, current 45160

<0> [520.575145] kworker/-2413 0..... 287047973us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:b56 unpin

<0> [520.575267] kworker/-2413 0..... 287048107us : __intel_context_retire: 0000:00:02.0 rcs0: context:b56 retire runtime: { total:361089ns, avg:99057ns }

<0> [520.575389] kworker/-2413 0..... 287048118us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:b0c unpin

<0> [520.575507] kworker/-2413 0..... 287048244us : __intel_context_retire: 0000:00:02.0 rcs0: context:b0c retire runtime: { total:351973ns, avg:157145ns }

<0> [520.575629] kworker/-2413 0..... 287048249us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:7e4 unpin

<0> [520.575746] kworker/-2413 0..... 287048374us : __intel_context_retire: 0000:00:02.0 rcs0: context:7e4 retire runtime: { total:456330ns, avg:109604ns }

<0> [520.575883] gem_exec-2372 0..... 287053243us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45306, current 45260

<0> [520.576015] gem_exec-2372 0D..1. 287053249us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45306, current 45260

<0> [520.576147] gem_exec-2383 0..... 287054010us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45334, current 45262

<0> [520.576278] gem_exec-2397 0..... 287056868us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45382, current 45331

<0> [520.576408] gem_exec-2376 0..... 287057049us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45342, current 45342

<0> [520.576539] gem_exec-2376 0..... 287057087us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45396, current 45344

<0> [520.576670] gem_exec-2376 0..... 287057091us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45344, current 45344

<0> [520.576800] gem_exec-2376 0..... 287057131us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45346, current 45346

<0> [520.576922] kworker/-2413 0..... 287057719us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:718 unpin

<0> [520.577042] kworker/-2413 0..... 287057801us : __intel_context_retire: 0000:00:02.0 vcs1: context:718 retire runtime: { total:84323ns, avg:38160ns }

<0> [520.577164] kworker/-2413 0..... 287057819us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:33f unpin

<0> [520.577281] kworker/-2413 0..... 287057890us : __intel_context_retire: 0000:00:02.0 vcs0: context:33f retire runtime: { total:173787ns, avg:40015ns }

<0> [520.577402] kworker/-2413 0..... 287057956us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:cef unpin

<0> [520.577520] kworker/-2413 0.N... 287058027us : __intel_context_retire: 0000:00:02.0 vcs1: context:cef retire runtime: { total:19928ns, avg:19928ns }

<0> [520.577640] kworker/-2413 0..... 287058090us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:89d unpin

<0> [520.577757] kworker/-2413 0..... 287058159us : __intel_context_retire: 0000:00:02.0 vecs0: context:89d retire runtime: { total:94446ns, avg:30634ns }

<0> [520.577895] gem_exec-2397 0..... 287058353us : __i915_request_commit: 0000:00:02.0 bcs0: fence c6f:4, current 2

<0> [520.578027] gem_exec-2397 0D..1. 287058357us : __i915_request_submit: 0000:00:02.0 bcs0: fence c6f:4, current 2

<0> [520.578159] gem_exec-2374 0..... 287064273us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45468, current 45456

<0> [520.578291] gem_exec-2374 0D..1. 287064280us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45468, current 45456

<0> [520.578417] gem_exec-2374 0..... 287064429us : intel_rps_boost: 0000:00:02.0 boost fence:298:b19c

<0> [520.578547] gem_exec-2397 0..... 287064577us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45488, current 45462

<0> [520.578678] gem_exec-2397 0D..1. 287064583us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45488, current 45462

<0> [520.578810] gem_exec-2382 0..... 287065817us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45524, current 45477

<0> [520.578941] gem_exec-2382 0..... 287066402us : __i915_request_commit: 0000:00:02.0 vecs0: fence 331:12, current 10

<0> [520.579072] gem_exec-2382 0D..1. 287066406us : __i915_request_submit: 0000:00:02.0 vecs0: fence 331:12, current 10

<0> [520.579202] gem_exec-2382 0..... 287066874us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45590, current 45510

<0> [520.579332] gem_exec-2382 0..... 287066879us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45510, current 45510

<0> [520.579463] gem_exec-2384 0..... 287068671us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45618, current 45550

<0> [520.579593] gem_exec-2372 0..... 287072873us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45652, current 45578

<0> [520.579723] gem_exec-2372 0..... 287072878us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45578, current 45578

<0> [520.579846] gem_exec-2372 0..... 287072933us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:70b active

<0> [520.579972] gem_exec-2372 0..... 287072937us : intel_timeline_pin: 0000:00:02.0 timeline:70b using HWSP offset:f584b000

<0> [520.580093] gem_exec-2372 0..... 287072946us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:70b pin ring:{start:f592c000, head:02d0, tail:02d0}

<0> [520.580230] gem_exec-2372 0..... 287072976us : __i915_request_commit: 0000:00:02.0 rcs0: fence 70b:6, current 4

<0> [520.580362] gem_exec-2372 0D..1. 287073043us : __i915_request_submit: 0000:00:02.0 rcs0: fence 70b:6, current 4

<0> [520.580493] gem_exec-2375 0..... 287073181us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45662, current 45584

<0> [520.580614] gem_exec-2375 0..... 287073239us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:774 active

<0> [520.580740] gem_exec-2375 0..... 287073242us : intel_timeline_pin: 0000:00:02.0 timeline:774 using HWSP offset:f4af1000

<0> [520.580860] gem_exec-2375 0..... 287073252us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:774 pin ring:{start:f4b50000, head:00b0, tail:00b0}

<0> [520.580997] gem_exec-2375 0..... 287073283us : __i915_request_commit: 0000:00:02.0 vcs1: fence 774:4, current 2

<0> [520.581128] gem_exec-2375 0D..1. 287073287us : __i915_request_submit: 0000:00:02.0 vcs1: fence 774:4, current 2

<0> [520.581258] gem_exec-2369 0..... 287076230us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45704, current 45665

<0> [520.581389] gem_exec-2369 0D..1. 287076237us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45704, current 45665

<0> [520.581520] gem_exec-2369 0..... 287076244us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45666, current 45666

<0> [520.581642] gem_exec-2369 0..... 287076322us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:2e3 active

<0> [520.581769] gem_exec-2369 0..... 287076326us : intel_timeline_pin: 0000:00:02.0 timeline:2e3 using HWSP offset:fe468000

<0> [520.581889] gem_exec-2369 0..... 287076336us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:2e3 pin ring:{start:fe5a2000, head:0210, tail:0210}

<0> [520.582024] gem_exec-2369 0..... 287076387us : __i915_request_commit: 0000:00:02.0 vcs1: fence 2e3:8, current 6

<0> [520.582155] gem_exec-2369 0D..1. 287076396us : __i915_request_submit: 0000:00:02.0 vcs1: fence 2e3:8, current 6

<0> [520.582282] gem_exec-2406 0..... 287077395us : intel_timeline_pin: 0000:00:02.0 timeline:585 using HWSP offset:f8cfd000

<0> [520.582403] gem_exec-2406 0..... 287077407us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:585 pin ring:{start:f8e05000, head:0210, tail:0210}

<0> [520.582527] gem_exec-2406 0..... 287077422us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:585 unpin

<0> [520.582645] gem_exec-2406 0..... 287077550us : __intel_context_retire: 0000:00:02.0 vecs0: context:585 retire runtime: { total:75260ns, avg:18656ns }

<0> [520.582766] gem_exec-2406 0..... 287077553us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:585 active

<0> [520.582891] gem_exec-2406 0..... 287077554us : intel_timeline_pin: 0000:00:02.0 timeline:585 using HWSP offset:f8cfd000

<0> [520.583009] gem_exec-2406 0..... 287077558us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:585 pin ring:{start:f8e05000, head:0210, tail:0210}

<0> [520.583146] gem_exec-2406 0..... 287077589us : __i915_request_commit: 0000:00:02.0 vecs0: fence 585:8, current 6

<0> [520.583278] gem_exec-2406 0D..1. 287077593us : __i915_request_submit: 0000:00:02.0 vecs0: fence 585:8, current 6

<0> [520.583410] gem_exec-2406 0..... 287082709us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45824, current 45768

<0> [520.583542] gem_exec-2406 0D..1. 287082715us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:45824, current 45768

<0> [520.583672] gem_exec-2406 0..... 287082721us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45768, current 45768

<0> [520.583805] gem_exec-2401 0..... 287085631us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45902, current 45842

<0> [520.583936] gem_exec-2401 0..... 287085636us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45842, current 45843

<0> [520.584068] gem_exec-2401 0..... 287087882us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45958, current 45890

<0> [520.584198] gem_exec-2368 0..... 287088195us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45982, current 45898

<0> [520.584320] gem_exec-2368 0..... 287088260us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:bfe active

<0> [520.584446] gem_exec-2368 0..... 287088263us : intel_timeline_pin: 0000:00:02.0 timeline:bfe using HWSP offset:eb2b8000

<0> [520.584566] gem_exec-2368 0..... 287088271us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:bfe pin ring:{start:eb331000, head:0160, tail:0160}

<0> [520.584702] gem_exec-2368 0..... 287088310us : __i915_request_commit: 0000:00:02.0 vcs0: fence bfe:6, current 4

<0> [520.584834] gem_exec-2368 0D..1. 287088316us : __i915_request_submit: 0000:00:02.0 vcs0: fence bfe:6, current 4

<0> [520.584965] gem_exec-2378 0..... 287088397us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:45984, current 45898

<0> [520.585095] gem_exec-2395 0..... 287089339us : intel_timeline_pin: 0000:00:02.0 timeline:cf8 using HWSP offset:e91a8000

<0> [520.585216] gem_exec-2395 0..... 287091568us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:cf8 pin ring:{start:e91a9000, head:0000, tail:0000}

<0> [520.585353] gem_exec-2395 0..... 287091927us : __i915_request_commit: 0000:00:02.0 ccs0: fence cf8:2, current 0

<0> [520.585484] gem_exec-2395 0D..1. 287091945us : __i915_request_submit: 0000:00:02.0 ccs0: fence cf8:2, current 0

<0> [520.585615] gem_exec-2375 0..... 287095134us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:45928, current 45954

<0> [520.585746] gem_exec-2375 0..... 287095134us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45928, current 45954

<0> [520.585878] gem_exec-2375 0..... 287095141us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46016, current 45954

<0> [520.586009] gem_exec-2375 0D..1. 287095147us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46016, current 45955

<0> [520.586142] gem_exec-2375 0..... 287095154us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45930, current 45955

<0> [520.586273] gem_exec-2375 0..... 287095156us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45932, current 45955

<0> [520.586405] gem_exec-2375 0..... 287095157us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45934, current 45955

<0> [520.586536] gem_exec-2375 0..... 287095163us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45936, current 45956

<0> [520.586667] gem_exec-2375 0..... 287095164us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45938, current 45956

<0> [520.586797] gem_exec-2375 0..... 287095165us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45940, current 45956

<0> [520.586928] gem_exec-2375 0..... 287095167us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45942, current 45956

<0> [520.587059] gem_exec-2375 0..... 287095168us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45944, current 45956

<0> [520.587190] gem_exec-2375 0..... 287095169us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45946, current 45956

<0> [520.587320] gem_exec-2375 0..... 287095170us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45948, current 45956

<0> [520.587450] gem_exec-2375 0..... 287095171us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45950, current 45956

<0> [520.587580] gem_exec-2375 0..... 287095172us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45952, current 45956

<0> [520.587710] gem_exec-2375 0..... 287095173us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45954, current 45956

<0> [520.587839] gem_exec-2375 0..... 287095176us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45956, current 45956

<0> [520.587970] gem_exec-2375 0..... 287095699us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46026, current 45958

<0> [520.588099] gem_exec-2375 0D..1. 287095703us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46026, current 45958

<0> [520.588228] gem_exec-2395 0..... 287097866us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:45970, current 45971

<0> [520.588357] gem_exec-2395 0..... 287097868us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45970, current 45971

<0> [520.588488] gem_exec-2395 0..... 287097903us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46058, current 45973

<0> [520.588617] gem_exec-2395 0D..1. 287097925us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46058, current 45974

<0> [520.588746] gem_exec-2395 0..... 287098207us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45972, current 45974

<0> [520.588875] gem_exec-2395 0..... 287098210us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:45974, current 45974

<0> [520.589005] gem_exec-2389 0..... 287099709us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46088, current 46026

<0> [520.589135] gem_exec-2389 0D..1. 287099714us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46088, current 46026

<0> [520.589264] gem_exec-2389 0..... 287099720us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46026, current 46026

<0> [520.589387] gem_exec-2389 0..... 287099795us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:5a0 active

<0> [520.589513] gem_exec-2389 0..... 287099799us : intel_timeline_pin: 0000:00:02.0 timeline:5a0 using HWSP offset:f8834000

<0> [520.589635] gem_exec-2389 0..... 287099808us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:5a0 pin ring:{start:f88bf000, head:0438, tail:0438}

<0> [520.589770] gem_exec-2389 0..... 287099858us : __i915_request_commit: 0000:00:02.0 ccs0: fence 5a0:8, current 6

<0> [520.589901] gem_exec-2389 0D..1. 287099864us : __i915_request_submit: 0000:00:02.0 ccs0: fence 5a0:8, current 6

<0> [520.590030] gem_exec-2395 0..... 287104373us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46142, current 46084

<0> [520.590158] gem_exec-2398 0..... 287104924us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46104, current 46104

<0> [520.590289] gem_exec-2398 0..... 287104970us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46172, current 46106

<0> [520.590419] gem_exec-2398 0..... 287104974us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46106, current 46106

<0> [520.590550] gem_exec-2408 0..... 287110461us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46234, current 46184

<0> [520.590680] gem_exec-2408 0D..1. 287110466us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46234, current 46184

<0> [520.590801] gem_exec-2408 0..... 287110654us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:5f0 active

<0> [520.590927] gem_exec-2408 0..... 287110659us : intel_timeline_pin: 0000:00:02.0 timeline:5f0 using HWSP offset:f7dc7000

<0> [520.591047] gem_exec-2408 0..... 287110669us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:5f0 pin ring:{start:f7e76000, head:0168, tail:0168}

<0> [520.591183] gem_exec-2408 0..... 287110714us : __i915_request_commit: 0000:00:02.0 rcs0: fence 5f0:4, current 2

<0> [520.591313] gem_exec-2408 0D..1. 287110719us : __i915_request_submit: 0000:00:02.0 rcs0: fence 5f0:4, current 2

<0> [520.591444] gem_exec-2408 0..... 287114273us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46294, current 46222

<0> [520.591573] gem_exec-2408 0D..1. 287114277us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46294, current 46222

<0> [520.591703] gem_exec-2368 0.n... 287115086us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46232, current 46240

<0> [520.591834] gem_exec-2368 0.N... 287115101us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46234, current 46240

<0> [520.591965] gem_exec-2368 0.N... 287115114us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46236, current 46240

<0> [520.592095] gem_exec-2368 0.N... 287115120us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46238, current 46240

<0> [520.592226] gem_exec-2389 0..... 287119681us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46342, current 46303

<0> [520.592356] gem_exec-2389 0D..1. 287119687us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46342, current 46304

<0> [520.592486] gem_exec-2389 0..... 287119693us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46304, current 46304

<0> [520.592618] gem_exec-2389 0..... 287120421us : __i915_request_commit: 0000:00:02.0 ccs0: fence 412:6, current 4

<0> [520.592746] gem_exec-2389 0D..1. 287120742us : __i915_request_submit: 0000:00:02.0 ccs0: fence 412:6, current 4

<0> [520.592875] gem_exec-2389 0..... 287121082us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46374, current 46328

<0> [520.593005] gem_exec-2389 0..... 287122694us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46364, current 46364

<0> [520.593135] gem_exec-2389 0..... 287122708us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46444, current 46365

<0> [520.593265] gem_exec-2382 0..... 287126845us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46486, current 46445

<0> [520.593395] gem_exec-2382 0D..1. 287126851us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46486, current 46446

<0> [520.593524] gem_exec-2382 0..... 287126864us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46446, current 46446

<0> [520.593647] gem_exec-2382 0..... 287127738us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:cff active

<0> [520.593777] gem_exec-2401 0..... 287128079us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46530, current 46476

<0> [520.593898] gem_exec-2401 0..... 287128327us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b68 active

<0> [520.594024] gem_exec-2401 0..... 287128331us : intel_timeline_pin: 0000:00:02.0 timeline:b68 using HWSP offset:ec612000

<0> [520.594145] gem_exec-2401 0..... 287128340us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:b68 pin ring:{start:ec697000, head:0168, tail:0168}

<0> [520.594282] gem_exec-2401 0..... 287128379us : __i915_request_commit: 0000:00:02.0 ccs0: fence b68:4, current 2

<0> [520.594413] gem_exec-2401 0D..1. 287128383us : __i915_request_submit: 0000:00:02.0 ccs0: fence b68:4, current 2

<0> [520.594544] gem_exec-2401 0..... 287132448us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46596, current 46536

<0> [520.594677] gem_exec-2401 0D..1. 287132455us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46596, current 46536

<0> [520.594808] gem_exec-2399 0..... 287133238us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46620, current 46540

<0> [520.594940] gem_exec-2382 0..... 287135642us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46674, current 46611

<0> [520.595071] gem_exec-2382 0..... 287135647us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46612, current 46612

<0> [520.595202] gem_exec-2399 0..... 287135855us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46684, current 46618

<0> [520.595332] gem_exec-2375 0..... 287141613us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46742, current 46680

<0> [520.595462] gem_exec-2375 0D..1. 287141618us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46742, current 46680

<0> [520.595593] gem_exec-2375 0..... 287141624us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46680, current 46680

<0> [520.595724] gem_exec-2398 0..... 287142323us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46708, current 46708

<0> [520.595854] gem_exec-2398 0..... 287142349us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46766, current 46708

<0> [520.595976] kworker/-546 0..... 287143025us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:52f unpin

<0> [520.596096] kworker/-546 0..... 287143153us : __intel_context_retire: 0000:00:02.0 vecs0: context:52f retire runtime: { total:141351ns, avg:25069ns }

<0> [520.596231] gem_exec-2377 0..... 287147304us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46852, current 46770

<0> [520.596363] gem_exec-2377 0D..1. 287147310us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46852, current 46770

<0> [520.596484] kworker/-546 0..... 287148150us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:959 unpin

<0> [520.596602] kworker/-546 0.N... 287148281us : __intel_context_retire: 0000:00:02.0 bcs0: context:959 retire runtime: { total:86708ns, avg:31111ns }

<0> [520.596738] gem_exec-2389 21..... 287149189us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46882, current 46806

<0> [520.596871] gem_exec-2389 21D..1. 287149256us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46882, current 46806

<0> [520.597001] gem_exec-2389 21..... 287150049us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46808, current 46810

<0> [520.597133] gem_exec-2389 21..... 287150082us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46810, current 46810

<0> [520.597265] gem_exec-2377 0..... 287150890us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46902, current 46832

<0> [520.597395] gem_exec-2377 0D..1. 287150897us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:46902, current 46832

<0> [520.597525] gem_exec-2377 0..... 287150903us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46832, current 46832

<0> [520.597647] gem_exec-2389 21..... 287150926us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c60 active

<0> [520.597773] gem_exec-2389 21..... 287150956us : intel_timeline_pin: 0000:00:02.0 timeline:c60 using HWSP offset:ea60c000

<0> [520.597893] gem_exec-2389 21..... 287151011us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c60 pin ring:{start:ea655000, head:0438, tail:0438}

<0> [520.598029] gem_exec-2389 21..... 287151222us : __i915_request_commit: 0000:00:02.0 ccs0: fence c60:8, current 6

<0> [520.598159] gem_exec-2389 21D..1. 287151348us : __i915_request_submit: 0000:00:02.0 ccs0: fence c60:8, current 6

<0> [520.598289] gem_exec-2368 0..... 287152582us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46936, current 46876

<0> [520.598423] gem_exec-2389 21..... 287152771us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46944, current 46876

<0> [520.598555] gem_exec-2367 20..... 287152973us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46952, current 46876

<0> [520.598687] gem_exec-2368 0..... 287155622us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:46986, current 46925

<6> [520.598697] [IGT] gem_exec_schedule: finished subtest vcs0, SUCCESS

<0> [520.598816] gem_exec-2368 0..... 287155627us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46926, current 46926

<0> [520.598948] gem_exec-2368 0..... 287155703us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:46928, current 46928

Results for igt@gem_exec_schedule@in-order@vcs0