Result: 489 Warning(s)
git-log-oneline i915_display_info13 igt_runner13 runtimes13 results13.json results13-i915-load.json guc_logs13.tar i915_display_info_post_exec13 boot13 dmesg13
pstore-001-dmesg13| Detail | Value |
|---|---|
| Duration | unknown |
| Hostname |
shard-dg2-4 |
| Igt-Version |
IGT-Version: 2.3-g33d772ed2 (x86_64) (Linux: 6.19.0-rc6-CI_DRM_17872-g75950b1567cb+ x86_64) |
| Out |
Using IGT_SRANDOM=1769126272 for randomisation Opened device: /dev/dri/card0 Running on dg2 Using GuC submission Opened device: /dev/dri/card1 Ring size: 26 batches Starting subtest: syncobj-stationary-timeline-chain-engines Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.150s) This test caused an abort condition: Child refuses to die, tainted 0x42c4. |
| Err |
Starting subtest: syncobj-stationary-timeline-chain-engines Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.150s) |
| Dmesg |
<0> [240.194324] gem_exec-2229 1...1. 238237632us : tracing_mark_write: gem_exec_fence: starting subtest syncobj-stationary-timeline-chain-engines
<0> [240.194414] gem_exec-2229 1..... 238243823us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:25f active
<0> [240.194508] gem_exec-2229 1..... 238243993us : intel_timeline_pin: 0000:03:00.0 timeline:25f using HWSP offset:fec6c000
<0> [240.194597] gem_exec-2229 1..... 238244202us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:25f pin ring:{start:015c0000, head:0000, tail:0000}
<0> [240.194701] gem_exec-2229 1..... 238245696us : __i915_request_commit: 0000:03:00.0 rcs0: fence 25f:2, current 0
<0> [240.194800] gem_exec-2229 1D..1. 238245703us : __i915_request_submit: 0000:03:00.0 rcs0: fence 25f:2, current 0
<0> [240.194887] gem_exec-2229 1..... 238247408us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:261 active
<0> [240.194983] gem_exec-2229 1..... 238247536us : intel_timeline_pin: 0000:03:00.0 timeline:261 using HWSP offset:fec6b000
<0> [240.195070] gem_exec-2229 1..... 238247858us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:261 pin ring:{start:015d0000, head:0000, tail:0000}
<0> [240.195174] gem_exec-2229 1..... 238249228us : __i915_request_commit: 0000:03:00.0 rcs0: fence 261:2, current 0
<0> [240.195283] gem_exec-2229 1..... 238249715us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:263 active
<0> [240.195390] gem_exec-2229 1..... 238249911us : intel_timeline_pin: 0000:03:00.0 timeline:263 using HWSP offset:fec6a000
<0> [240.195479] gem_exec-2229 1..... 238250019us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:263 pin ring:{start:015e0000, head:0000, tail:0000}
<0> [240.195583] gem_exec-2229 1..... 238251411us : __i915_request_commit: 0000:03:00.0 bcs0: fence 263:2, current 0
<0> [240.195672] gem_exec-2229 1..... 238251602us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:265 active
<0> [240.195766] gem_exec-2229 1..... 238251751us : intel_timeline_pin: 0000:03:00.0 timeline:265 using HWSP offset:fec69000
<0> [240.195855] gem_exec-2229 1..... 238251917us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:265 pin ring:{start:015f0000, head:0000, tail:0000}
<0> [240.195959] gem_exec-2229 1..... 238253366us : __i915_request_commit: 0000:03:00.0 vcs0: fence 265:2, current 0
<0> [240.196048] gem_exec-2229 1..... 238253583us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:267 active
<0> [240.196143] gem_exec-2229 1..... 238253722us : intel_timeline_pin: 0000:03:00.0 timeline:267 using HWSP offset:fec68000
<0> [240.196232] gem_exec-2229 1..... 238253827us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:267 pin ring:{start:01600000, head:0000, tail:0000}
<0> [240.196370] gem_exec-2229 1..... 238255281us : __i915_request_commit: 0000:03:00.0 vcs1: fence 267:2, current 0
<0> [240.196457] gem_exec-2229 1..... 238255488us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:269 active
<0> [240.196550] gem_exec-2229 1..... 238255654us : intel_timeline_pin: 0000:03:00.0 timeline:269 using HWSP offset:fec67000
<0> [240.196640] gem_exec-2229 1..... 238255758us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:269 pin ring:{start:01610000, head:0000, tail:0000}
<0> [240.196744] gem_exec-2229 1..... 238257232us : __i915_request_commit: 0000:03:00.0 vecs0: fence 269:2, current 0
<0> [240.196833] gem_exec-2229 1..... 238257441us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:26b active
<0> [240.196926] gem_exec-2229 1..... 238257579us : intel_timeline_pin: 0000:03:00.0 timeline:26b using HWSP offset:fec66000
<0> [240.197014] gem_exec-2229 1..... 238257683us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:26b pin ring:{start:01620000, head:0000, tail:0000}
<0> [240.197106] gem_exec-2229 1..... 238257696us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:26b unpin
<0> [240.197195] gem_exec-2229 1..... 238258259us : __intel_context_retire: 0000:03:00.0 vecs1: context:26b retire runtime: { total:0ns, avg:0ns }
<0> [240.197283] gem_exec-2229 1..... 238258262us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:26b active
<0> [240.197412] gem_exec-2229 1..... 238258264us : intel_timeline_pin: 0000:03:00.0 timeline:26b using HWSP offset:fec66000
<0> [240.197501] gem_exec-2229 1..... 238258267us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:26b pin ring:{start:01620000, head:0000, tail:0000}
<0> [240.197606] gem_exec-2229 1..... 238259618us : __i915_request_commit: 0000:03:00.0 vecs1: fence 26b:2, current 0
<0> [240.197695] gem_exec-2229 1..... 238259813us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:26d active
<0> [240.197790] gem_exec-2229 1..... 238259976us : intel_timeline_pin: 0000:03:00.0 timeline:26d using HWSP offset:fec65000
<0> [240.197879] gem_exec-2229 1..... 238260105us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:26d pin ring:{start:01630000, head:0000, tail:0000}
<0> [240.197971] gem_exec-2229 1..... 238260119us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:26d unpin
<0> [240.198058] gem_exec-2229 1..... 238260680us : __intel_context_retire: 0000:03:00.0 ccs0: context:26d retire runtime: { total:0ns, avg:0ns }
<0> [240.198148] gem_exec-2229 1..... 238260683us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:26d active
<0> [240.198243] gem_exec-2229 1..... 238260684us : intel_timeline_pin: 0000:03:00.0 timeline:26d using HWSP offset:fec65000
<0> [240.198332] gem_exec-2229 1..... 238260688us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:26d pin ring:{start:01630000, head:0000, tail:0000}
<0> [240.198469] gem_exec-2229 1..... 238262047us : __i915_request_commit: 0000:03:00.0 ccs0: fence 26d:2, current 0
<0> [240.198557] gem_exec-2229 1..... 238263589us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:26f active
<0> [240.198652] gem_exec-2229 1..... 238263748us : intel_timeline_pin: 0000:03:00.0 timeline:26f using HWSP offset:fec64000
<0> [240.198739] gem_exec-2229 1..... 238263871us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:26f pin ring:{start:016b0000, head:0000, tail:0000}
<0> [240.198830] gem_exec-2229 1..... 238263883us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:26f unpin
<0> [240.198918] gem_exec-2229 1..... 238264456us : __intel_context_retire: 0000:03:00.0 rcs0: context:26f retire runtime: { total:0ns, avg:0ns }
<0> [240.199007] gem_exec-2229 1..... 238264460us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:26f active
<0> [240.199101] gem_exec-2229 1..... 238264461us : intel_timeline_pin: 0000:03:00.0 timeline:26f using HWSP offset:fec64000
<0> [240.199190] gem_exec-2229 1..... 238264465us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:26f pin ring:{start:016b0000, head:0000, tail:0000}
<0> [240.199281] gem_exec-2229 1..... 238264477us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:26f unpin
<0> [240.199370] gem_exec-2229 1..... 238265002us : __intel_context_retire: 0000:03:00.0 rcs0: context:26f retire runtime: { total:0ns, avg:0ns }
<0> [240.199492] gem_exec-2229 1..... 238265005us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:26f active
<0> [240.199588] gem_exec-2229 1..... 238265006us : intel_timeline_pin: 0000:03:00.0 timeline:26f using HWSP offset:fec64000
<0> [240.199677] gem_exec-2229 1..... 238265010us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:26f pin ring:{start:016b0000, head:0000, tail:0000}
<0> [240.199780] <idle>-0 0dNh3. 238265879us : __i915_request_submit: 0000:03:00.0 rcs0: fence 261:2, current 0
<0> [240.199880] kworker/-10 0..... 238265898us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 25f:2, current 2
<0> [240.199977] kworker/-2094 4d..2. 238265981us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.200079] <idle>-0 0dNh3. 238266078us : __i915_request_submit: 0000:03:00.0 bcs0: fence 263:2, current 0
<0> [240.200179] kworker/-10 0..... 238266099us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 261:2, current 2
<0> [240.200278] <idle>-0 0dNh3. 238266147us : __i915_request_submit: 0000:03:00.0 vcs0: fence 265:2, current 0
<0> [240.200378] kworker/-10 0..... 238266164us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 263:2, current 2
<0> [240.200514] <idle>-0 0dNh3. 238266245us : __i915_request_submit: 0000:03:00.0 vcs1: fence 267:2, current 0
<0> [240.200614] kworker/-10 0..... 238266257us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 265:2, current 2
<0> [240.200715] <idle>-0 0dNh3. 238266317us : __i915_request_submit: 0000:03:00.0 vecs0: fence 269:2, current 0
<0> [240.200815] kworker/-10 0..... 238266333us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 267:2, current 2
<0> [240.200916] <idle>-0 0dNH3. 238266402us : __i915_request_submit: 0000:03:00.0 vecs1: fence 26b:2, current 0
<0> [240.201015] kworker/-10 0..... 238266418us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 269:2, current 2
<0> [240.201115] gem_exec-2229 1..... 238266449us : __i915_request_commit: 0000:03:00.0 rcs0: fence 26f:2, current 0
<0> [240.201216] <idle>-0 0dNh3. 238266523us : __i915_request_submit: 0000:03:00.0 ccs0: fence 26d:2, current 0
<0> [240.201318] kworker/-10 0..... 238266544us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 26b:2, current 2
<0> [240.201418] <idle>-0 0dNh3. 238266611us : __i915_request_submit: 0000:03:00.0 rcs0: fence 26f:2, current 0
<0> [240.201554] kworker/-10 0..... 238266622us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 26d:2, current 2
<0> [240.201642] gem_exec-2229 1..... 238266633us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:271 active
<0> [240.201737] gem_exec-2229 1..... 238266761us : intel_timeline_pin: 0000:03:00.0 timeline:271 using HWSP offset:fec63000
<0> [240.201829] gem_exec-2229 1..... 238266886us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:271 pin ring:{start:016c0000, head:0000, tail:0000}
<0> [240.201921] gem_exec-2229 1..... 238266916us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:271 unpin
<0> [240.202010] gem_exec-2229 1..... 238267563us : __intel_context_retire: 0000:03:00.0 bcs0: context:271 retire runtime: { total:0ns, avg:0ns }
<0> [240.202100] gem_exec-2229 1..... 238267567us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:271 active
<0> [240.202194] gem_exec-2229 1..... 238267568us : intel_timeline_pin: 0000:03:00.0 timeline:271 using HWSP offset:fec63000
<0> [240.202283] gem_exec-2229 1..... 238267572us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:271 pin ring:{start:016c0000, head:0000, tail:0000}
<0> [240.202374] gem_exec-2229 1..... 238267583us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:271 unpin
<0> [240.202479] gem_exec-2229 1..... 238268251us : __intel_context_retire: 0000:03:00.0 bcs0: context:271 retire runtime: { total:0ns, avg:0ns }
<0> [240.202582] gem_exec-2229 1..... 238268253us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:271 active
<0> [240.202676] gem_exec-2229 1..... 238268255us : intel_timeline_pin: 0000:03:00.0 timeline:271 using HWSP offset:fec63000
<0> [240.202765] gem_exec-2229 1..... 238268258us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:271 pin ring:{start:016c0000, head:0000, tail:0000}
<0> [240.202869] gem_exec-2229 1..... 238269548us : __i915_request_commit: 0000:03:00.0 bcs0: fence 271:2, current 0
<0> [240.202968] gem_exec-2229 1D..1. 238269553us : __i915_request_submit: 0000:03:00.0 bcs0: fence 271:2, current 0
<0> [240.203057] gem_exec-2229 1..... 238269723us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:273 active
<0> [240.203151] gem_exec-2229 1..... 238269846us : intel_timeline_pin: 0000:03:00.0 timeline:273 using HWSP offset:fec62000
<0> [240.203239] gem_exec-2229 1..... 238269966us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:273 pin ring:{start:016d0000, head:0000, tail:0000}
<0> [240.203343] gem_exec-2229 1..... 238271312us : __i915_request_commit: 0000:03:00.0 vcs0: fence 273:2, current 0
<0> [240.203442] gem_exec-2229 1D..1. 238271334us : __i915_request_submit: 0000:03:00.0 vcs0: fence 273:2, current 0
<0> [240.203565] gem_exec-2229 1..... 238271480us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:275 active
<0> [240.203660] gem_exec-2229 1..... 238271647us : intel_timeline_pin: 0000:03:00.0 timeline:275 using HWSP offset:fec61000
<0> [240.203749] gem_exec-2229 1..... 238271791us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:275 pin ring:{start:016e0000, head:0000, tail:0000}
<0> [240.203853] gem_exec-2229 1..... 238273031us : __i915_request_commit: 0000:03:00.0 vcs1: fence 275:2, current 0
<0> [240.203953] gem_exec-2229 1D..1. 238273053us : __i915_request_submit: 0000:03:00.0 vcs1: fence 275:2, current 0
<0> [240.204039] gem_exec-2229 1..... 238273193us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:277 active
<0> [240.204134] gem_exec-2229 1..... 238273321us : intel_timeline_pin: 0000:03:00.0 timeline:277 using HWSP offset:fec60000
<0> [240.204222] gem_exec-2229 1..... 238273435us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:277 pin ring:{start:016f0000, head:0000, tail:0000}
<0> [240.204326] gem_exec-2229 1..... 238274773us : __i915_request_commit: 0000:03:00.0 vecs0: fence 277:2, current 0
<0> [240.204425] gem_exec-2229 1D..1. 238274778us : __i915_request_submit: 0000:03:00.0 vecs0: fence 277:2, current 0
<0> [240.204513] gem_exec-2229 1..... 238274923us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:279 active
<0> [240.204643] gem_exec-2229 1..... 238275045us : intel_timeline_pin: 0000:03:00.0 timeline:279 using HWSP offset:feaef000
<0> [240.204732] gem_exec-2229 1..... 238275169us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:279 pin ring:{start:feaf0000, head:0000, tail:0000}
<0> [240.204836] gem_exec-2229 1..... 238276447us : __i915_request_commit: 0000:03:00.0 vecs1: fence 279:2, current 0
<0> [240.204937] gem_exec-2229 1D..1. 238276452us : __i915_request_submit: 0000:03:00.0 vecs1: fence 279:2, current 0
<0> [240.205025] gem_exec-2229 1..... 238276604us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:27b active
<0> [240.205119] gem_exec-2229 1..... 238276778us : intel_timeline_pin: 0000:03:00.0 timeline:27b using HWSP offset:feaee000
<0> [240.205208] gem_exec-2229 1..... 238276913us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:27b pin ring:{start:fea50000, head:0000, tail:0000}
<0> [240.205310] gem_exec-2229 1..... 238278213us : __i915_request_commit: 0000:03:00.0 ccs0: fence 27b:2, current 0
<0> [240.205410] gem_exec-2229 1D..1. 238278217us : __i915_request_submit: 0000:03:00.0 ccs0: fence 27b:2, current 0
<0> [240.205499] gem_exec-2229 1..... 238279645us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:27d active
<0> [240.205630] gem_exec-2229 1..... 238279775us : intel_timeline_pin: 0000:03:00.0 timeline:27d using HWSP offset:feaed000
<0> [240.205720] gem_exec-2229 1..... 238280130us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:27d pin ring:{start:01700000, head:0000, tail:0000}
<0> [240.205824] gem_exec-2229 1..... 238281746us : __i915_request_commit: 0000:03:00.0 rcs0: fence 27d:2, current 0
<0> [240.205924] gem_exec-2229 1D..1. 238281750us : __i915_request_submit: 0000:03:00.0 rcs0: fence 27d:2, current 0
<0> [240.206011] gem_exec-2229 1..... 238281894us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:27f active
<0> [240.206106] gem_exec-2229 1..... 238282080us : intel_timeline_pin: 0000:03:00.0 timeline:27f using HWSP offset:feaec000
<0> [240.206193] gem_exec-2229 1..... 238282183us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:27f pin ring:{start:01710000, head:0000, tail:0000}
<0> [240.206297] gem_exec-2229 1..... 238283459us : __i915_request_commit: 0000:03:00.0 bcs0: fence 27f:2, current 0
<0> [240.206397] gem_exec-2229 1D..1. 238283464us : __i915_request_submit: 0000:03:00.0 bcs0: fence 27f:2, current 0
<0> [240.206485] gem_exec-2229 1..... 238283643us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:281 active
<0> [240.206580] gem_exec-2229 1..... 238283781us : intel_timeline_pin: 0000:03:00.0 timeline:281 using HWSP offset:feaeb000
<0> [240.206703] gem_exec-2229 1..... 238283884us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:281 pin ring:{start:01720000, head:0000, tail:0000}
<0> [240.206808] gem_exec-2229 1..... 238285392us : __i915_request_commit: 0000:03:00.0 vcs0: fence 281:2, current 0
<0> [240.206908] gem_exec-2229 1D..1. 238285414us : __i915_request_submit: 0000:03:00.0 vcs0: fence 281:2, current 0
<0> [240.206996] gem_exec-2229 1..... 238285553us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:283 active
<0> [240.207091] gem_exec-2229 1..... 238285713us : intel_timeline_pin: 0000:03:00.0 timeline:283 using HWSP offset:feaea000
<0> [240.207180] gem_exec-2229 1..... 238285816us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:283 pin ring:{start:01730000, head:0000, tail:0000}
<0> [240.207282] gem_exec-2229 1..... 238287100us : __i915_request_commit: 0000:03:00.0 vcs1: fence 283:2, current 0
<0> [240.207381] gem_exec-2229 1D..1. 238287121us : __i915_request_submit: 0000:03:00.0 vcs1: fence 283:2, current 0
<0> [240.207469] gem_exec-2229 1..... 238287336us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:285 active
<0> [240.207563] gem_exec-2229 1..... 238287475us : intel_timeline_pin: 0000:03:00.0 timeline:285 using HWSP offset:feae9000
<0> [240.207687] gem_exec-2229 1..... 238287577us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:285 pin ring:{start:01740000, head:0000, tail:0000}
<0> [240.207791] gem_exec-2229 1..... 238288858us : __i915_request_commit: 0000:03:00.0 vecs0: fence 285:2, current 0
<0> [240.207892] gem_exec-2229 1D..1. 238288863us : __i915_request_submit: 0000:03:00.0 vecs0: fence 285:2, current 0
<0> [240.207978] gem_exec-2229 1..... 238289018us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:287 active
<0> [240.208072] gem_exec-2229 1..... 238289170us : intel_timeline_pin: 0000:03:00.0 timeline:287 using HWSP offset:feae8000
<0> [240.208161] gem_exec-2229 1..... 238289298us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:287 pin ring:{start:01750000, head:0000, tail:0000}
<0> [240.208252] gem_exec-2229 1..... 238289310us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:287 unpin
<0> [240.208341] gem_exec-2229 1..... 238289858us : __intel_context_retire: 0000:03:00.0 vecs1: context:287 retire runtime: { total:0ns, avg:0ns }
<0> [240.208431] gem_exec-2229 1..... 238289861us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:287 active
<0> [240.208524] gem_exec-2229 1..... 238289863us : intel_timeline_pin: 0000:03:00.0 timeline:287 using HWSP offset:feae8000
<0> [240.208613] gem_exec-2229 1..... 238289866us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:287 pin ring:{start:01750000, head:0000, tail:0000}
<0> [240.208751] gem_exec-2229 1..... 238291154us : __i915_request_commit: 0000:03:00.0 vecs1: fence 287:2, current 0
<0> [240.208851] gem_exec-2229 1D..1. 238291158us : __i915_request_submit: 0000:03:00.0 vecs1: fence 287:2, current 0
<0> [240.208939] gem_exec-2229 1..... 238291339us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:289 active
<0> [240.209034] gem_exec-2229 1..... 238291500us : intel_timeline_pin: 0000:03:00.0 timeline:289 using HWSP offset:feae7000
<0> [240.209122] gem_exec-2229 1..... 238291619us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:289 pin ring:{start:01760000, head:0000, tail:0000}
<0> [240.209214] gem_exec-2229 1..... 238291631us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:289 unpin
<0> [240.209303] gem_exec-2229 1..... 238292180us : __intel_context_retire: 0000:03:00.0 ccs0: context:289 retire runtime: { total:0ns, avg:0ns }
<0> [240.209392] gem_exec-2229 1..... 238292183us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:289 active
<0> [240.209487] gem_exec-2229 1..... 238292184us : intel_timeline_pin: 0000:03:00.0 timeline:289 using HWSP offset:feae7000
<0> [240.209576] gem_exec-2229 1..... 238292188us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:289 pin ring:{start:01760000, head:0000, tail:0000}
<0> [240.209679] gem_exec-2229 1..... 238293449us : __i915_request_commit: 0000:03:00.0 ccs0: fence 289:2, current 0
<0> [240.209814] gem_exec-2229 1D..1. 238293454us : __i915_request_submit: 0000:03:00.0 ccs0: fence 289:2, current 0
<0> [240.209902] gem_exec-2229 1..... 238294937us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28b active
<0> [240.209996] gem_exec-2229 1..... 238295070us : intel_timeline_pin: 0000:03:00.0 timeline:28b using HWSP offset:feae6000
<0> [240.210084] gem_exec-2229 1..... 238295190us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28b pin ring:{start:017e0000, head:0000, tail:0000}
<0> [240.210174] gem_exec-2229 1..... 238295202us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:28b unpin
<0> [240.210262] gem_exec-2229 1..... 238295787us : __intel_context_retire: 0000:03:00.0 rcs0: context:28b retire runtime: { total:0ns, avg:0ns }
<0> [240.210352] gem_exec-2229 1..... 238295790us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28b active
<0> [240.210447] gem_exec-2229 1..... 238295792us : intel_timeline_pin: 0000:03:00.0 timeline:28b using HWSP offset:feae6000
<0> [240.210536] gem_exec-2229 1..... 238295796us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28b pin ring:{start:017e0000, head:0000, tail:0000}
<0> [240.210628] gem_exec-2229 1..... 238295806us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:28b unpin
<0> [240.210738] gem_exec-2229 1..... 238296355us : __intel_context_retire: 0000:03:00.0 rcs0: context:28b retire runtime: { total:0ns, avg:0ns }
<0> [240.210843] gem_exec-2229 1..... 238296357us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28b active
<0> [240.210938] gem_exec-2229 1..... 238296359us : intel_timeline_pin: 0000:03:00.0 timeline:28b using HWSP offset:feae6000
<0> [240.211026] gem_exec-2229 1..... 238296362us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28b pin ring:{start:017e0000, head:0000, tail:0000}
<0> [240.211131] gem_exec-2229 1..... 238297714us : __i915_request_commit: 0000:03:00.0 rcs0: fence 28b:2, current 0
<0> [240.211230] gem_exec-2229 1D..1. 238297718us : __i915_request_submit: 0000:03:00.0 rcs0: fence 28b:2, current 0
<0> [240.211318] gem_exec-2229 1..... 238297858us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:28d active
<0> [240.211412] gem_exec-2229 1..... 238298006us : intel_timeline_pin: 0000:03:00.0 timeline:28d using HWSP offset:feae5000
<0> [240.211500] gem_exec-2229 1..... 238298109us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:28d pin ring:{start:017f0000, head:0000, tail:0000}
<0> [240.211592] gem_exec-2229 1..... 238298138us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:28d unpin
<0> [240.211680] gem_exec-2229 1.N... 238298744us : __intel_context_retire: 0000:03:00.0 bcs0: context:28d retire runtime: { total:0ns, avg:0ns }
<0> [240.211805] gem_exec-2229 1..... 238298834us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:28d active
<0> [240.211898] gem_exec-2229 1..... 238298836us : intel_timeline_pin: 0000:03:00.0 timeline:28d using HWSP offset:feae5000
<0> [240.211986] gem_exec-2229 1..... 238298840us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:28d pin ring:{start:017f0000, head:0000, tail:0000}
<0> [240.212076] gem_exec-2229 1..... 238298852us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:28d unpin
<0> [240.212164] gem_exec-2229 1..... 238299401us : __intel_context_retire: 0000:03:00.0 bcs0: context:28d retire runtime: { total:0ns, avg:0ns }
<0> [240.212253] gem_exec-2229 1..... 238299404us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:28d active
<0> [240.212348] gem_exec-2229 1..... 238299405us : intel_timeline_pin: 0000:03:00.0 timeline:28d using HWSP offset:feae5000
<0> [240.212437] gem_exec-2229 1..... 238299409us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:28d pin ring:{start:017f0000, head:0000, tail:0000}
<0> [240.212540] gem_exec-2229 1..... 238300696us : __i915_request_commit: 0000:03:00.0 bcs0: fence 28d:2, current 0
<0> [240.212641] gem_exec-2229 1D..1. 238300701us : __i915_request_submit: 0000:03:00.0 bcs0: fence 28d:2, current 0
<0> [240.212728] gem_exec-2229 1..... 238300882us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:28f active
<0> [240.212856] gem_exec-2229 1..... 238301020us : intel_timeline_pin: 0000:03:00.0 timeline:28f using HWSP offset:feae4000
<0> [240.212944] gem_exec-2229 1..... 238301124us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:28f pin ring:{start:01800000, head:0000, tail:0000}
<0> [240.213035] kworker/-2086 2..... 238301824us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:267 unpin
<0> [240.213122] kworker/-2086 2..... 238302442us : __intel_context_retire: 0000:03:00.0 vcs1: context:267 retire runtime: { total:13572ns, avg:13572ns }
<0> [240.213212] kworker/-2086 2..... 238302449us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:265 unpin
<0> [240.213311] gem_exec-2229 1..... 238302629us : __i915_request_commit: 0000:03:00.0 vcs0: fence 28f:2, current 0
<0> [240.213411] gem_exec-2229 1D..1. 238302655us : __i915_request_submit: 0000:03:00.0 vcs0: fence 28f:2, current 0
<0> [240.213498] gem_exec-2229 1..... 238302857us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:291 active
<0> [240.213593] gem_exec-2229 1..... 238303065us : intel_timeline_pin: 0000:03:00.0 timeline:291 using HWSP offset:feae3000
<0> [240.213680] kworker/-2086 2..... 238303157us : __intel_context_retire: 0000:03:00.0 vcs0: context:265 retire runtime: { total:13312ns, avg:13312ns }
<0> [240.213771] kworker/-2086 2..... 238303163us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:261 unpin
<0> [240.213893] gem_exec-2229 1..... 238303170us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:291 pin ring:{start:01810000, head:0000, tail:0000}
<0> [240.213984] kworker/-2086 2..... 238303767us : __intel_context_retire: 0000:03:00.0 rcs0: context:261 retire runtime: { total:23348ns, avg:23348ns }
<0> [240.214074] kworker/-2086 2..... 238303802us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:263 unpin
<0> [240.214162] kworker/-2086 2..... 238304378us : __intel_context_retire: 0000:03:00.0 bcs0: context:263 retire runtime: { total:11544ns, avg:11544ns }
<0> [240.214253] kworker/-2086 2..... 238304383us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:25f unpin
<0> [240.214353] gem_exec-2229 1..... 238304542us : __i915_request_commit: 0000:03:00.0 vcs1: fence 291:2, current 0
<0> [240.214453] gem_exec-2229 1D..1. 238304567us : __i915_request_submit: 0000:03:00.0 vcs1: fence 291:2, current 0
<0> [240.214541] gem_exec-2229 1..... 238304726us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:293 active
<0> [240.214636] gem_exec-2229 1..... 238304919us : intel_timeline_pin: 0000:03:00.0 timeline:293 using HWSP offset:feae2000
<0> [240.214723] gem_exec-2229 1..... 238305026us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:293 pin ring:{start:01820000, head:0000, tail:0000}
<0> [240.214814] kworker/-2086 2..... 238305054us : __intel_context_retire: 0000:03:00.0 rcs0: context:25f retire runtime: { total:19976008ns, avg:19976008ns }
<0> [240.214942] kworker/-2086 2..... 238305061us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:26d unpin
<0> [240.215029] kworker/-2086 2..... 238305610us : __intel_context_retire: 0000:03:00.0 ccs0: context:26d retire runtime: { total:44044ns, avg:44044ns }
<0> [240.215119] kworker/-2086 2..... 238305615us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:26b unpin
<0> [240.215208] kworker/-2086 2..... 238306199us : __intel_context_retire: 0000:03:00.0 vecs1: context:26b retire runtime: { total:13364ns, avg:13364ns }
<0> [240.215297] kworker/-2086 2..... 238306203us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:269 unpin
<0> [240.215398] gem_exec-2229 1..... 238306378us : __i915_request_commit: 0000:03:00.0 vecs0: fence 293:2, current 0
<0> [240.215498] gem_exec-2229 1D..1. 238306383us : __i915_request_submit: 0000:03:00.0 vecs0: fence 293:2, current 0
<0> [240.215586] gem_exec-2229 1..... 238306542us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:295 active
<0> [240.215681] gem_exec-2229 1..... 238306710us : intel_timeline_pin: 0000:03:00.0 timeline:295 using HWSP offset:feae1000
<0> [240.215769] gem_exec-2229 1..... 238306816us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:295 pin ring:{start:01830000, head:0000, tail:0000}
<0> [240.215881] kworker/-2086 2..... 238306837us : __intel_context_retire: 0000:03:00.0 vecs0: context:269 retire runtime: { total:14196ns, avg:14196ns }
<0> [240.215998] gem_exec-2229 1..... 238308184us : __i915_request_commit: 0000:03:00.0 vecs1: fence 295:2, current 0
<0> [240.216098] gem_exec-2229 1D..1. 238308189us : __i915_request_submit: 0000:03:00.0 vecs1: fence 295:2, current 0
<0> [240.216186] gem_exec-2229 1..... 238308350us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:297 active
<0> [240.216281] gem_exec-2229 1..... 238308508us : intel_timeline_pin: 0000:03:00.0 timeline:297 using HWSP offset:feae0000
<0> [240.216370] gem_exec-2229 1..... 238308630us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:297 pin ring:{start:01840000, head:0000, tail:0000}
<0> [240.216473] gem_exec-2229 1..... 238310128us : __i915_request_commit: 0000:03:00.0 ccs0: fence 297:2, current 0
<0> [240.216574] gem_exec-2229 1D..1. 238310133us : __i915_request_submit: 0000:03:00.0 ccs0: fence 297:2, current 0
<0> [240.216661] gem_exec-2229 1..... 238311626us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:299 active
<0> [240.216756] gem_exec-2229 1..... 238311766us : intel_timeline_pin: 0000:03:00.0 timeline:299 using HWSP offset:fe8ff000
<0> [240.216845] gem_exec-2229 1..... 238311897us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:299 pin ring:{start:fe900000, head:0000, tail:0000}
<0> [240.216983] gem_exec-2229 1..... 238313188us : __i915_request_commit: 0000:03:00.0 rcs0: fence 299:2, current 0
<0> [240.217084] gem_exec-2229 1D..1. 238313193us : __i915_request_submit: 0000:03:00.0 rcs0: fence 299:2, current 0
<0> [240.217172] gem_exec-2229 1..... 238313335us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:29b active
<0> [240.217267] gem_exec-2229 1..... 238313461us : intel_timeline_pin: 0000:03:00.0 timeline:29b using HWSP offset:fe8fe000
<0> [240.217356] gem_exec-2229 1..... 238313579us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:29b pin ring:{start:fe8c0000, head:0000, tail:0000}
<0> [240.217460] gem_exec-2229 1..... 238314861us : __i915_request_commit: 0000:03:00.0 bcs0: fence 29b:2, current 0
<0> [240.217560] gem_exec-2229 1D..1. 238314866us : __i915_request_submit: 0000:03:00.0 bcs0: fence 29b:2, current 0
<0> [240.217647] gem_exec-2229 1..... 238315033us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:29d active
<0> [240.217741] gem_exec-2229 1..... 238315164us : intel_timeline_pin: 0000:03:00.0 timeline:29d using HWSP offset:fe8fd000
<0> [240.217829] gem_exec-2229 1..... 238315337us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:29d pin ring:{start:018c0000, head:0000, tail:0000}
<0> [240.217967] gem_exec-2229 1..... 238316571us : __i915_request_commit: 0000:03:00.0 vcs0: fence 29d:2, current 0
<0> [240.218067] gem_exec-2229 1D..1. 238316593us : __i915_request_submit: 0000:03:00.0 vcs0: fence 29d:2, current 0
<0> [240.218156] gem_exec-2229 1..... 238316767us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:29f active
<0> [240.218251] gem_exec-2229 1..... 238316901us : intel_timeline_pin: 0000:03:00.0 timeline:29f using HWSP offset:fe8fc000
<0> [240.218339] gem_exec-2229 1..... 238317020us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:29f pin ring:{start:018d0000, head:0000, tail:0000}
<0> [240.218444] gem_exec-2229 1..... 238318252us : __i915_request_commit: 0000:03:00.0 vcs1: fence 29f:2, current 0
<0> [240.218544] gem_exec-2229 1D..1. 238318272us : __i915_request_submit: 0000:03:00.0 vcs1: fence 29f:2, current 0
<0> [240.218633] gem_exec-2229 1..... 238318484us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2a1 active
<0> [240.218727] gem_exec-2229 1..... 238318610us : intel_timeline_pin: 0000:03:00.0 timeline:2a1 using HWSP offset:fe8fb000
<0> [240.218816] gem_exec-2229 1..... 238318736us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2a1 pin ring:{start:018e0000, head:0000, tail:0000}
<0> [240.218919] gem_exec-2229 1..... 238320070us : __i915_request_commit: 0000:03:00.0 vecs0: fence 2a1:2, current 0
<0> [240.219054] gem_exec-2229 1D..1. 238320075us : __i915_request_submit: 0000:03:00.0 vecs0: fence 2a1:2, current 0
<0> [240.219142] gem_exec-2229 1..... 238320213us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2a3 active
<0> [240.219236] gem_exec-2229 1..... 238320345us : intel_timeline_pin: 0000:03:00.0 timeline:2a3 using HWSP offset:fe8fa000
<0> [240.219326] gem_exec-2229 1..... 238320501us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2a3 pin ring:{start:018f0000, head:0000, tail:0000}
<0> [240.219418] gem_exec-2229 1..... 238320514us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:2a3 unpin
<0> [240.219507] gem_exec-2229 1..... 238321063us : __intel_context_retire: 0000:03:00.0 vecs1: context:2a3 retire runtime: { total:0ns, avg:0ns }
<0> [240.219598] gem_exec-2229 1..... 238321067us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2a3 active
<0> [240.219693] gem_exec-2229 1..... 238321068us : intel_timeline_pin: 0000:03:00.0 timeline:2a3 using HWSP offset:fe8fa000
<0> [240.219780] gem_exec-2229 1..... 238321072us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2a3 pin ring:{start:018f0000, head:0000, tail:0000}
<0> [240.219884] gem_exec-2229 1..... 238322306us : __i915_request_commit: 0000:03:00.0 vecs1: fence 2a3:2, current 0
<0> [240.220017] gem_exec-2229 1D..1. 238322311us : __i915_request_submit: 0000:03:00.0 vecs1: fence 2a3:2, current 0
<0> [240.220105] gem_exec-2229 1..... 238322502us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2a5 active
<0> [240.220199] gem_exec-2229 1..... 238322684us : intel_timeline_pin: 0000:03:00.0 timeline:2a5 using HWSP offset:fe8f9000
<0> [240.220287] gem_exec-2229 1..... 238322824us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2a5 pin ring:{start:01900000, head:0000, tail:0000}
<0> [240.220378] gem_exec-2229 1..... 238322837us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:2a5 unpin
<0> [240.220465] gem_exec-2229 1..... 238323387us : __intel_context_retire: 0000:03:00.0 ccs0: context:2a5 retire runtime: { total:0ns, avg:0ns }
<0> [240.220556] gem_exec-2229 1..... 238323390us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2a5 active
<0> [240.220650] gem_exec-2229 1..... 238323391us : intel_timeline_pin: 0000:03:00.0 timeline:2a5 using HWSP offset:fe8f9000
<0> [240.220739] gem_exec-2229 1..... 238323395us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2a5 pin ring:{start:01900000, head:0000, tail:0000}
<0> [240.220844] gem_exec-2229 1..... 238325083us : __i915_request_commit: 0000:03:00.0 ccs0: fence 2a5:2, current 0
<0> [240.220945] gem_exec-2229 1D..1. 238325088us : __i915_request_submit: 0000:03:00.0 ccs0: fence 2a5:2, current 0
<0> [240.221066] gem_exec-2229 1..... 238326451us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2a7 active
<0> [240.221161] gem_exec-2229 1..... 238326582us : intel_timeline_pin: 0000:03:00.0 timeline:2a7 using HWSP offset:fe8f8000
<0> [240.221248] gem_exec-2229 1..... 238326721us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2a7 pin ring:{start:01980000, head:0000, tail:0000}
<0> [240.221339] gem_exec-2229 1..... 238326734us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2a7 unpin
<0> [240.221427] gem_exec-2229 1..... 238327285us : __intel_context_retire: 0000:03:00.0 rcs0: context:2a7 retire runtime: { total:0ns, avg:0ns }
<0> [240.221517] gem_exec-2229 1..... 238327288us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2a7 active
<0> [240.221611] gem_exec-2229 1..... 238327290us : intel_timeline_pin: 0000:03:00.0 timeline:2a7 using HWSP offset:fe8f8000
<0> [240.221699] gem_exec-2229 1..... 238327293us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2a7 pin ring:{start:01980000, head:0000, tail:0000}
<0> [240.221790] gem_exec-2229 1..... 238327304us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2a7 unpin
<0> [240.221878] gem_exec-2229 1.N... 238327894us : __intel_context_retire: 0000:03:00.0 rcs0: context:2a7 retire runtime: { total:0ns, avg:0ns }
<0> [240.221968] gem_exec-2229 1..... 238327899us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2a7 active
<0> [240.222098] gem_exec-2229 1..... 238327900us : intel_timeline_pin: 0000:03:00.0 timeline:2a7 using HWSP offset:fe8f8000
<0> [240.222187] gem_exec-2229 1..... 238327904us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2a7 pin ring:{start:01980000, head:0000, tail:0000}
<0> [240.222290] gem_exec-2229 1..... 238329181us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2a7:2, current 0
<0> [240.222390] gem_exec-2229 1D..1. 238329186us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2a7:2, current 0
<0> [240.222478] gem_exec-2229 1..... 238329328us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2a9 active
<0> [240.222573] gem_exec-2229 1..... 238329478us : intel_timeline_pin: 0000:03:00.0 timeline:2a9 using HWSP offset:fe8f7000
<0> [240.222661] gem_exec-2229 1..... 238329583us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2a9 pin ring:{start:01990000, head:0000, tail:0000}
<0> [240.222752] gem_exec-2229 1..... 238329613us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2a9 unpin
<0> [240.222840] gem_exec-2229 1..... 238330170us : __intel_context_retire: 0000:03:00.0 bcs0: context:2a9 retire runtime: { total:0ns, avg:0ns }
<0> [240.222929] gem_exec-2229 1..... 238330173us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2a9 active
<0> [240.223022] gem_exec-2229 1..... 238330175us : intel_timeline_pin: 0000:03:00.0 timeline:2a9 using HWSP offset:fe8f7000
<0> [240.223145] gem_exec-2229 1..... 238330179us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2a9 pin ring:{start:01990000, head:0000, tail:0000}
<0> [240.223236] gem_exec-2229 1..... 238330190us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2a9 unpin
<0> [240.223325] gem_exec-2229 1..... 238330741us : __intel_context_retire: 0000:03:00.0 bcs0: context:2a9 retire runtime: { total:0ns, avg:0ns }
<0> [240.223414] gem_exec-2229 1..... 238330743us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2a9 active
<0> [240.223509] gem_exec-2229 1..... 238330745us : intel_timeline_pin: 0000:03:00.0 timeline:2a9 using HWSP offset:fe8f7000
<0> [240.223597] gem_exec-2229 1..... 238330748us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2a9 pin ring:{start:01990000, head:0000, tail:0000}
<0> [240.223701] gem_exec-2229 1..... 238332006us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2a9:2, current 0
<0> [240.223802] gem_exec-2229 1D..1. 238332011us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2a9:2, current 0
<0> [240.223889] gem_exec-2229 1..... 238332157us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2ab active
<0> [240.223984] gem_exec-2229 1..... 238332333us : intel_timeline_pin: 0000:03:00.0 timeline:2ab using HWSP offset:fe8f6000
<0> [240.224073] gem_exec-2229 1..... 238332439us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2ab pin ring:{start:019a0000, head:0000, tail:0000}
<0> [240.224211] gem_exec-2229 1..... 238333782us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2ab:2, current 0
<0> [240.224311] gem_exec-2229 1D..1. 238333804us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2ab:2, current 0
<0> [240.224398] gem_exec-2229 1..... 238334009us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2ad active
<0> [240.224493] gem_exec-2229 1..... 238334156us : intel_timeline_pin: 0000:03:00.0 timeline:2ad using HWSP offset:fe8f5000
<0> [240.224581] gem_exec-2229 1..... 238334261us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2ad pin ring:{start:019b0000, head:0000, tail:0000}
<0> [240.224685] gem_exec-2229 1..... 238335542us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2ad:2, current 0
<0> [240.224784] gem_exec-2229 1D..1. 238335563us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2ad:2, current 0
<0> [240.224873] gem_exec-2229 1..... 238335708us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2af active
<0> [240.224967] gem_exec-2229 1..... 238335860us : intel_timeline_pin: 0000:03:00.0 timeline:2af using HWSP offset:fe8f4000
<0> [240.225056] gem_exec-2229 1..... 238335992us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2af pin ring:{start:019c0000, head:0000, tail:0000}
<0> [240.225195] gem_exec-2229 1..... 238337250us : __i915_request_commit: 0000:03:00.0 vecs0: fence 2af:2, current 0
<0> [240.225295] gem_exec-2229 1D..1. 238337255us : __i915_request_submit: 0000:03:00.0 vecs0: fence 2af:2, current 0
<0> [240.225383] gem_exec-2229 1..... 238337401us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2b1 active
<0> [240.225477] gem_exec-2229 1..... 238337542us : intel_timeline_pin: 0000:03:00.0 timeline:2b1 using HWSP offset:fe8f3000
<0> [240.225566] gem_exec-2229 1..... 238337646us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2b1 pin ring:{start:019d0000, head:0000, tail:0000}
<0> [240.225670] gem_exec-2229 1..... 238338887us : __i915_request_commit: 0000:03:00.0 vecs1: fence 2b1:2, current 0
<0> [240.225771] gem_exec-2229 1D..1. 238338892us : __i915_request_submit: 0000:03:00.0 vecs1: fence 2b1:2, current 0
<0> [240.225859] gem_exec-2229 1..... 238339033us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2b3 active
<0> [240.225954] gem_exec-2229 1..... 238339189us : intel_timeline_pin: 0000:03:00.0 timeline:2b3 using HWSP offset:fe8f2000
<0> [240.226041] gem_exec-2229 1..... 238339377us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2b3 pin ring:{start:019e0000, head:0000, tail:0000}
<0> [240.226145] gem_exec-2229 1..... 238340630us : __i915_request_commit: 0000:03:00.0 ccs0: fence 2b3:2, current 0
<0> [240.226280] gem_exec-2229 1D..1. 238340635us : __i915_request_submit: 0000:03:00.0 ccs0: fence 2b3:2, current 0
<0> [240.226368] gem_exec-2229 1..... 238341984us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2b5 active
<0> [240.226463] gem_exec-2229 1..... 238342114us : intel_timeline_pin: 0000:03:00.0 timeline:2b5 using HWSP offset:fe8f1000
<0> [240.226553] gem_exec-2229 1..... 238342258us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2b5 pin ring:{start:01a60000, head:0000, tail:0000}
<0> [240.226658] gem_exec-2229 1..... 238343573us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2b5:2, current 0
<0> [240.226758] gem_exec-2229 1D..1. 238343578us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2b5:2, current 0
<0> [240.226846] gem_exec-2229 1..... 238343724us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2b7 active
<0> [240.226942] gem_exec-2229 1..... 238343873us : intel_timeline_pin: 0000:03:00.0 timeline:2b7 using HWSP offset:fe8f0000
<0> [240.227030] gem_exec-2229 1..... 238343988us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2b7 pin ring:{start:01a70000, head:0000, tail:0000}
<0> [240.227134] gem_exec-2229 1..... 238345448us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2b7:2, current 0
<0> [240.227270] gem_exec-2229 1D..1. 238345453us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2b7:2, current 0
<0> [240.227358] gem_exec-2229 1..... 238345596us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2b9 active
<0> [240.227453] gem_exec-2229 1..... 238345766us : intel_timeline_pin: 0000:03:00.0 timeline:2b9 using HWSP offset:fe77f000
<0> [240.227541] gem_exec-2229 1..... 238345871us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2b9 pin ring:{start:fe780000, head:0000, tail:0000}
<0> [240.227645] gem_exec-2229 1..... 238347234us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2b9:2, current 0
<0> [240.227745] gem_exec-2229 1D..1. 238347256us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2b9:2, current 0
<0> [240.227833] gem_exec-2229 1..... 238347448us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2bb active
<0> [240.227928] gem_exec-2229 1..... 238347592us : intel_timeline_pin: 0000:03:00.0 timeline:2bb using HWSP offset:fe77e000
<0> [240.228017] gem_exec-2229 1..... 238347698us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2bb pin ring:{start:fe750000, head:0000, tail:0000}
<0> [240.228122] gem_exec-2229 1..... 238349050us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2bb:2, current 0
<0> [240.228243] gem_exec-2229 1D..1. 238349072us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2bb:2, current 0
<0> [240.228345] gem_exec-2229 1..... 238349220us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2bd active
<0> [240.228440] gem_exec-2229 1..... 238349372us : intel_timeline_pin: 0000:03:00.0 timeline:2bd using HWSP offset:fe77d000
<0> [240.228529] gem_exec-2229 1..... 238349484us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2bd pin ring:{start:01a80000, head:0000, tail:0000}
<0> [240.228634] gem_exec-2229 1..... 238350822us : __i915_request_commit: 0000:03:00.0 vecs0: fence 2bd:2, current 0
<0> [240.228735] gem_exec-2229 1D..1. 238350827us : __i915_request_submit: 0000:03:00.0 vecs0: fence 2bd:2, current 0
<0> [240.228821] gem_exec-2229 1..... 238350976us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2bf active
<0> [240.228917] gem_exec-2229 1..... 238351116us : intel_timeline_pin: 0000:03:00.0 timeline:2bf using HWSP offset:fe77c000
<0> [240.229005] gem_exec-2229 1..... 238351223us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2bf pin ring:{start:01a90000, head:0000, tail:0000}
<0> [240.229096] gem_exec-2229 1..... 238351236us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:2bf unpin
<0> [240.229183] gem_exec-2229 1..... 238351800us : __intel_context_retire: 0000:03:00.0 vecs1: context:2bf retire runtime: { total:0ns, avg:0ns }
<0> [240.229308] gem_exec-2229 1..... 238351803us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2bf active
<0> [240.229402] gem_exec-2229 1..... 238351805us : intel_timeline_pin: 0000:03:00.0 timeline:2bf using HWSP offset:fe77c000
<0> [240.229491] gem_exec-2229 1..... 238351809us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2bf pin ring:{start:01a90000, head:0000, tail:0000}
<0> [240.229597] gem_exec-2229 1..... 238353204us : __i915_request_commit: 0000:03:00.0 vecs1: fence 2bf:2, current 0
<0> [240.229697] gem_exec-2229 1D..1. 238353208us : __i915_request_submit: 0000:03:00.0 vecs1: fence 2bf:2, current 0
<0> [240.229784] gem_exec-2229 1..... 238353356us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2c1 active
<0> [240.229878] gem_exec-2229 1..... 238353532us : intel_timeline_pin: 0000:03:00.0 timeline:2c1 using HWSP offset:fe77b000
<0> [240.229967] gem_exec-2229 1..... 238353903us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2c1 pin ring:{start:01aa0000, head:0000, tail:0000}
<0> [240.230058] gem_exec-2229 1..... 238353916us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:2c1 unpin
<0> [240.230146] gem_exec-2229 1..... 238354476us : __intel_context_retire: 0000:03:00.0 ccs0: context:2c1 retire runtime: { total:0ns, avg:0ns }
<0> [240.230236] gem_exec-2229 1..... 238354479us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2c1 active
<0> [240.230365] gem_exec-2229 1..... 238354481us : intel_timeline_pin: 0000:03:00.0 timeline:2c1 using HWSP offset:fe77b000
<0> [240.230453] gem_exec-2229 1..... 238354484us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2c1 pin ring:{start:01aa0000, head:0000, tail:0000}
<0> [240.230555] gem_exec-2229 1..... 238355846us : __i915_request_commit: 0000:03:00.0 ccs0: fence 2c1:2, current 0
<0> [240.230656] gem_exec-2229 1D..1. 238355851us : __i915_request_submit: 0000:03:00.0 ccs0: fence 2c1:2, current 0
<0> [240.230744] gem_exec-2229 1..... 238357248us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2c3 active
<0> [240.230838] gem_exec-2229 1..... 238357404us : intel_timeline_pin: 0000:03:00.0 timeline:2c3 using HWSP offset:fe77a000
<0> [240.230927] gem_exec-2229 1..... 238357527us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2c3 pin ring:{start:01b20000, head:0000, tail:0000}
<0> [240.231017] gem_exec-2229 1..... 238357539us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2c3 unpin
<0> [240.231106] gem_exec-2229 1..... 238358109us : __intel_context_retire: 0000:03:00.0 rcs0: context:2c3 retire runtime: { total:0ns, avg:0ns }
<0> [240.231196] gem_exec-2229 1..... 238358112us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2c3 active
<0> [240.231290] gem_exec-2229 1..... 238358114us : intel_timeline_pin: 0000:03:00.0 timeline:2c3 using HWSP offset:fe77a000
<0> [240.231414] gem_exec-2229 1..... 238358118us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2c3 pin ring:{start:01b20000, head:0000, tail:0000}
<0> [240.231505] gem_exec-2229 1..... 238358129us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2c3 unpin
<0> [240.231593] gem_exec-2229 1..... 238358687us : __intel_context_retire: 0000:03:00.0 rcs0: context:2c3 retire runtime: { total:0ns, avg:0ns }
<0> [240.231683] gem_exec-2229 1..... 238358689us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2c3 active
<0> [240.231778] gem_exec-2229 1..... 238358691us : intel_timeline_pin: 0000:03:00.0 timeline:2c3 using HWSP offset:fe77a000
<0> [240.231866] gem_exec-2229 1..... 238358694us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2c3 pin ring:{start:01b20000, head:0000, tail:0000}
<0> [240.231969] gem_exec-2229 1..... 238359951us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2c3:2, current 0
<0> [240.232068] gem_exec-2229 1D..1. 238359956us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2c3:2, current 0
<0> [240.232157] gem_exec-2229 1..... 238360103us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2c5 active
<0> [240.232251] gem_exec-2229 1..... 238360233us : intel_timeline_pin: 0000:03:00.0 timeline:2c5 using HWSP offset:fe779000
<0> [240.232362] gem_exec-2229 1..... 238360394us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2c5 pin ring:{start:01b30000, head:0000, tail:0000}
<0> [240.232467] gem_exec-2229 1..... 238360424us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2c5 unpin
<0> [240.232555] gem_exec-2229 1..... 238360987us : __intel_context_retire: 0000:03:00.0 bcs0: context:2c5 retire runtime: { total:0ns, avg:0ns }
<0> [240.232645] gem_exec-2229 1..... 238360991us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2c5 active
<0> [240.232740] gem_exec-2229 1..... 238360992us : intel_timeline_pin: 0000:03:00.0 timeline:2c5 using HWSP offset:fe779000
<0> [240.232828] gem_exec-2229 1..... 238360996us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2c5 pin ring:{start:01b30000, head:0000, tail:0000}
<0> [240.232919] gem_exec-2229 1..... 238361007us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2c5 unpin
<0> [240.233007] gem_exec-2229 1..... 238361566us : __intel_context_retire: 0000:03:00.0 bcs0: context:2c5 retire runtime: { total:0ns, avg:0ns }
<0> [240.233097] gem_exec-2229 1..... 238361568us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2c5 active
<0> [240.233191] gem_exec-2229 1..... 238361570us : intel_timeline_pin: 0000:03:00.0 timeline:2c5 using HWSP offset:fe779000
<0> [240.233280] gem_exec-2229 1..... 238361573us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2c5 pin ring:{start:01b30000, head:0000, tail:0000}
<0> [240.233419] gem_exec-2229 1..... 238362882us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2c5:2, current 0
<0> [240.233519] gem_exec-2229 1D..1. 238362887us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2c5:2, current 0
<0> [240.233606] gem_exec-2229 1..... 238363083us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2c7 active
<0> [240.233702] gem_exec-2229 1..... 238363214us : intel_timeline_pin: 0000:03:00.0 timeline:2c7 using HWSP offset:fe778000
<0> [240.233790] gem_exec-2229 1..... 238363331us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2c7 pin ring:{start:01b40000, head:0000, tail:0000}
<0> [240.233893] gem_exec-2229 1..... 238364822us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2c7:2, current 0
<0> [240.233994] gem_exec-2229 1D..1. 238364844us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2c7:2, current 0
<0> [240.234081] gem_exec-2229 1..... 238364988us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2c9 active
<0> [240.234176] gem_exec-2229 1..... 238365127us : intel_timeline_pin: 0000:03:00.0 timeline:2c9 using HWSP offset:fe777000
<0> [240.234265] gem_exec-2229 1..... 238365254us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2c9 pin ring:{start:01b50000, head:0000, tail:0000}
<0> [240.234368] gem_exec-2229 1..... 238366551us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2c9:2, current 0
<0> [240.234500] gem_exec-2229 1D..1. 238366573us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2c9:2, current 0
<0> [240.234587] gem_exec-2229 1..... 238366713us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2cb active
<0> [240.234682] gem_exec-2229 1..... 238366843us : intel_timeline_pin: 0000:03:00.0 timeline:2cb using HWSP offset:fe776000
<0> [240.234771] gem_exec-2229 1..... 238366967us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2cb pin ring:{start:01b60000, head:0000, tail:0000}
<0> [240.234875] gem_exec-2229 1..... 238368272us : __i915_request_commit: 0000:03:00.0 vecs0: fence 2cb:2, current 0
<0> [240.234977] gem_exec-2229 1D..1. 238368276us : __i915_request_submit: 0000:03:00.0 vecs0: fence 2cb:2, current 0
<0> [240.235065] gem_exec-2229 1..... 238368417us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2cd active
<0> [240.235158] gem_exec-2229 1..... 238368549us : intel_timeline_pin: 0000:03:00.0 timeline:2cd using HWSP offset:fe775000
<0> [240.235246] gem_exec-2229 1..... 238368687us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2cd pin ring:{start:01b70000, head:0000, tail:0000}
<0> [240.235350] gem_exec-2229 1..... 238370148us : __i915_request_commit: 0000:03:00.0 vecs1: fence 2cd:2, current 0
<0> [240.235485] gem_exec-2229 1D..1. 238370153us : __i915_request_submit: 0000:03:00.0 vecs1: fence 2cd:2, current 0
<0> [240.235572] gem_exec-2229 1..... 238370309us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2cf active
<0> [240.235666] gem_exec-2229 1..... 238370482us : intel_timeline_pin: 0000:03:00.0 timeline:2cf using HWSP offset:fe774000
<0> [240.235754] gem_exec-2229 1..... 238370608us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2cf pin ring:{start:01b80000, head:0000, tail:0000}
<0> [240.235857] gem_exec-2229 1..... 238371886us : __i915_request_commit: 0000:03:00.0 ccs0: fence 2cf:2, current 0
<0> [240.235956] gem_exec-2229 1D..1. 238371891us : __i915_request_submit: 0000:03:00.0 ccs0: fence 2cf:2, current 0
<0> [240.236044] gem_exec-2229 1..... 238373284us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2d1 active
<0> [240.236138] gem_exec-2229 1..... 238373423us : intel_timeline_pin: 0000:03:00.0 timeline:2d1 using HWSP offset:fe773000
<0> [240.236227] gem_exec-2229 1..... 238373544us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2d1 pin ring:{start:01c00000, head:0000, tail:0000}
<0> [240.236330] gem_exec-2229 1..... 238374781us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2d1:2, current 0
<0> [240.236430] gem_exec-2229 1D..1. 238374785us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2d1:2, current 0
<0> [240.236552] gem_exec-2229 1..... 238374932us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2d3 active
<0> [240.236646] gem_exec-2229 1..... 238375095us : intel_timeline_pin: 0000:03:00.0 timeline:2d3 using HWSP offset:fe772000
<0> [240.236734] gem_exec-2229 1..... 238375207us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2d3 pin ring:{start:01c10000, head:0000, tail:0000}
<0> [240.236838] gem_exec-2229 1..... 238376458us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2d3:2, current 0
<0> [240.236939] gem_exec-2229 1D..1. 238376462us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2d3:2, current 0
<0> [240.237028] gem_exec-2229 1..... 238376616us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2d5 active
<0> [240.237122] gem_exec-2229 1..... 238376756us : intel_timeline_pin: 0000:03:00.0 timeline:2d5 using HWSP offset:fe771000
<0> [240.237211] gem_exec-2229 1..... 238376866us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2d5 pin ring:{start:01c20000, head:0000, tail:0000}
<0> [240.237313] gem_exec-2229 1..... 238378267us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2d5:2, current 0
<0> [240.237413] gem_exec-2229 1D..1. 238378289us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2d5:2, current 0
<0> [240.237534] gem_exec-2229 1..... 238378436us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2d7 active
<0> [240.237628] gem_exec-2229 1..... 238378597us : intel_timeline_pin: 0000:03:00.0 timeline:2d7 using HWSP offset:fe770000
<0> [240.237715] gem_exec-2229 1..... 238378705us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2d7 pin ring:{start:01c30000, head:0000, tail:0000}
<0> [240.237818] gem_exec-2229 1..... 238379960us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2d7:2, current 0
<0> [240.237917] gem_exec-2229 1D..1. 238379982us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2d7:2, current 0
<0> [240.238004] gem_exec-2229 1..... 238380123us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2d9 active
<0> [240.238099] gem_exec-2229 1..... 238380269us : intel_timeline_pin: 0000:03:00.0 timeline:2d9 using HWSP offset:fe60f000
<0> [240.238187] gem_exec-2229 1..... 238380373us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:2d9 pin ring:{start:fe610000, head:0000, tail:0000}
<0> [240.238292] gem_exec-2229 1..... 238381631us : __i915_request_commit: 0000:03:00.0 vecs0: fence 2d9:2, current 0
<0> [240.238393] gem_exec-2229 1D..1. 238381636us : __i915_request_submit: 0000:03:00.0 vecs0: fence 2d9:2, current 0
<0> [240.238480] gem_exec-2229 1..... 238381787us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2db active
<0> [240.238608] gem_exec-2229 1..... 238381986us : intel_timeline_pin: 0000:03:00.0 timeline:2db using HWSP offset:fe60e000
<0> [240.238697] gem_exec-2229 1..... 238382091us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2db pin ring:{start:fe5e0000, head:0000, tail:0000}
<0> [240.238789] gem_exec-2229 1..... 238382104us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:2db unpin
<0> [240.238878] gem_exec-2229 1..... 238382662us : __intel_context_retire: 0000:03:00.0 vecs1: context:2db retire runtime: { total:0ns, avg:0ns }
<0> [240.238968] gem_exec-2229 1..... 238382666us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2db active
<0> [240.239063] gem_exec-2229 1..... 238382668us : intel_timeline_pin: 0000:03:00.0 timeline:2db using HWSP offset:fe60e000
<0> [240.239151] gem_exec-2229 1..... 238382672us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:2db pin ring:{start:fe5e0000, head:0000, tail:0000}
<0> [240.239254] gem_exec-2229 1..... 238384026us : __i915_request_commit: 0000:03:00.0 vecs1: fence 2db:2, current 0
<0> [240.239355] gem_exec-2229 1D..1. 238384030us : __i915_request_submit: 0000:03:00.0 vecs1: fence 2db:2, current 0
<0> [240.239443] gem_exec-2229 1..... 238384179us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2dd active
<0> [240.239558] gem_exec-2229 1..... 238384328us : intel_timeline_pin: 0000:03:00.0 timeline:2dd using HWSP offset:fe60d000
<0> [240.239661] gem_exec-2229 1..... 238384450us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2dd pin ring:{start:01c40000, head:0000, tail:0000}
<0> [240.239752] gem_exec-2229 1..... 238384462us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:2dd unpin
<0> [240.239840] gem_exec-2229 1..... 238385031us : __intel_context_retire: 0000:03:00.0 ccs0: context:2dd retire runtime: { total:0ns, avg:0ns }
<0> [240.239930] gem_exec-2229 1..... 238385034us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2dd active
<0> [240.240024] gem_exec-2229 1..... 238385036us : intel_timeline_pin: 0000:03:00.0 timeline:2dd using HWSP offset:fe60d000
<0> [240.240113] gem_exec-2229 1..... 238385040us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:2dd pin ring:{start:01c40000, head:0000, tail:0000}
<0> [240.240215] gem_exec-2229 1..... 238386285us : __i915_request_commit: 0000:03:00.0 ccs0: fence 2dd:2, current 0
<0> [240.240315] gem_exec-2229 1D..1. 238386290us : __i915_request_submit: 0000:03:00.0 ccs0: fence 2dd:2, current 0
<0> [240.240407] gem_exec-2229 1..... 238386310us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2dd:2
<0> [240.240507] kworker/-10 0..... 238386473us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 2dd:2, current 2
<0> [240.240636] kworker/-2086 2d..2. 238387062us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.240725] kworker/-2082 3..... 238387204us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:2dd unpin
<0> [240.240819] kworker/-2086 2d..2. 238387614us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.240838] gem_exec-2229 1...1. 238387711us : tracing_mark_write: gem_exec_fence: finished subtest syncobj-stationary-timeline-chain-engines, SUCCESS
<0> [240.240928] kworker/-2082 3..... 238387792us : __intel_context_retire: 0000:03:00.0 ccs0: context:2dd retire runtime: { total:61412ns, avg:61412ns }
<0> [240.241026] kworker/-2086 2d..2. 238387793us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.241121] kworker/-2086 2d..2. 238387989us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.241216] kworker/-2086 2d..2. 238388140us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.241312] kworker/-2086 2d..2. 238388291us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.241407] kworker/-2086 2d..2. 238388442us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.241503] kworker/-2086 2d..2. 238388817us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [240.241627] kworker/-2082 1..... 238399940us : destroyed_worker_func: guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id))
<0> [240.241657] ---------------------------------
<4> [240.241744] ------------[ cut here ]------------
<2> [240.241746] kernel BUG at drivers/gpu/drm/i915/gt/uc/intel_guc_submission.c:3418!
<4> [240.241761] Oops: invalid opcode: 0000 [#1] SMP NOPTI
<4> [240.241766] CPU: 1 UID: 0 PID: 2082 Comm: kworker/u24:13 Tainted: G S U W L 6.19.0-rc6-CI_DRM_17872-g75950b1567cb+ #1 PREEMPT(voluntary)
<4> [240.241774] Tainted: [S]=CPU_OUT_OF_SPEC, [U]=USER, [W]=WARN, [L]=SOFTLOCKUP
<4> [240.241777] Hardware name: Intel Corporation CoffeeLake Client Platform/CoffeeLake S UDIMM RVP, BIOS CNLSFWR1.R00.X220.B00.2103302221 03/30/2021
<4> [240.241783] Workqueue: events_unbound destroyed_worker_func [i915]
<4> [240.241982] RIP: 0010:destroyed_worker_func+0x66d/0x8a0 [i915]
<4> [240.242160] Code: 00 48 c7 c2 f0 9f ab a0 48 8d 3d 00 00 00 00 e8 a9 86 85 e0 bf 01 00 00 00 e8 ff 3e 85 e0 31 f6 bf 09 00 00 00 e8 73 97 64 e0 <0f> 0b 48 c7 c1 bf bc ae a0 ba 08 02 00 00 48 c7 c6 30 a8 ab a0 48
<4> [240.242167] RSP: 0018:ffffc90002887d80 EFLAGS: 00010246
<4> [240.242171] RAX: 0000000000000000 RBX: ffff88813c960028 RCX: 0000000000000000
<4> [240.242174] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [240.242178] RBP: ffffc90002887df0 R08: 0000000000000000 R09: 0000000000000000
<4> [240.242181] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000001006
<4> [240.242184] R13: ffff88813c960d78 R14: ffff888146fef720 R15: ffff88813c960d68
<4> [240.242187] FS: 0000000000000000(0000) GS:ffff8884d975d000(0000) knlGS:0000000000000000
<4> [240.242192] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [240.242195] CR2: 000076c8fba561e0 CR3: 0000000003448002 CR4: 00000000003726f0
<4> [240.242198] Call Trace:
<4> [240.242200] <TASK>
<4> [240.242204] process_one_work+0x22e/0x6b0
<4> [240.242210] worker_thread+0x1e8/0x3d0
<4> [240.242214] ? __pfx_worker_thread+0x10/0x10
<4> [240.242217] kthread+0x11f/0x250
<4> [240.242221] ? __pfx_kthread+0x10/0x10
<4> [240.242225] ret_from_fork+0x344/0x3a0
<4> [240.242229] ? __pfx_kthread+0x10/0x10
<4> [240.242232] ret_from_fork_asm+0x1a/0x30
<4> [240.242239] </TASK>
<4> [240.242241] Modules linked in: vgem snd_hda_codec_intelhdmi snd_hda_codec_hdmi mei_lb mei_gsc i915 prime_numbers ttm drm_buddy drm_display_helper cec rc_core i2c_algo_bit kunit intel_rapl_msr intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common cmdlinepart intel_tcc_cooling spi_nor x86_pkg_temp_thermal ee1004 mtd intel_powerclamp mei_hdcp coretemp mei_pxp wmi_bmof ppdev intel_wmi_thunderbolt kvm_intel kvm snd_hda_intel snd_intel_dspcfg irqbypass snd_hda_codec ghash_clmulni_intel aesni_intel snd_hda_core rapl snd_hwdep spi_intel_pci intel_cstate spi_intel snd_pcm e1000e i2c_i801 i2c_mux snd_timer video i2c_smbus snd idma64 mei_me soundcore mei intel_pch_thermal parport_pc parport intel_pmc_core wmi pmt_telemetry binfmt_misc pmt_discovery pmt_class nls_iso8859_1 pinctrl_cannonlake intel_pmc_ssram_telemetry intel_vsec acpi_pad acpi_tad input_leds serio_raw dm_multipath msr nvme_fabrics fuse efi_pstore nfnetlink autofs4
<4> [240.242324] ---[ end trace 0000000000000000 ]---
<6> [240.244898] [IGT] gem_exec_fence: exiting, ret=0
<7> [240.245249] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<4> [240.556774] RIP: 0010:destroyed_worker_func+0x66d/0x8a0 [i915]
<4> [240.557102] Code: 00 48 c7 c2 f0 9f ab a0 48 8d 3d 00 00 00 00 e8 a9 86 85 e0 bf 01 00 00 00 e8 ff 3e 85 e0 31 f6 bf 09 00 00 00 e8 73 97 64 e0 <0f> 0b 48 c7 c1 bf bc ae a0 ba 08 02 00 00 48 c7 c6 30 a8 ab a0 48
<4> [240.557109] RSP: 0018:ffffc90002887d80 EFLAGS: 00010246
<4> [240.557113] RAX: 0000000000000000 RBX: ffff88813c960028 RCX: 0000000000000000
<4> [240.557117] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [240.557120] RBP: ffffc90002887df0 R08: 0000000000000000 R09: 0000000000000000
<4> [240.557124] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000001006
<4> [240.557127] R13: ffff88813c960d78 R14: ffff888146fef720 R15: ffff88813c960d68
<4> [240.557130] FS: 0000000000000000(0000) GS:ffff8884d975d000(0000) knlGS:0000000000000000
<4> [240.557135] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [240.557138] CR2: 000076c8fba561e0 CR3: 0000000003448003 CR4: 00000000003726f0
|