Result: 1345 Warning(s)
integration-manifest 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-3 |
| Igt-Version |
IGT-Version: 2.3-g33f5420a2 (x86_64) (Linux: 7.0.0-rc4-CI_DRM_18152-gbd57a29321bf+ x86_64) |
| Out |
Using IGT_SRANDOM=1773634512 for randomisation Opened device: /dev/dri/card0 Running on dg2 Using GuC submission Has kernel scheduler - With priority sorting - With preemption enabled - With engine busy statistics - With timeslicing enabled Starting subtest: u-semaphore-noskip Subtest u-semaphore-noskip: SUCCESS (0.767s) Test requirement not met in function __igt_unique____real_main3205, file ../tests/intel/gem_exec_schedule.c:3376: Test requirement: !gem_scheduler_has_static_priority(fd) runner: This test was killed due to a kernel taint (0x42c4). runner: This test was killed due to exceeding disk usage limit. (Used 22992631 bytes, limit 10485760) This test caused an abort condition: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2318 [max: 8192] dynamic-keys: 323 direct dependencies: 26344 [max: 524288] indirect dependencies: 273379 all direct dependencies: 558029 dependency chains: 39769 [max: 524288] dependency chain hlocks used: 177893 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 339 in-softirq chains: 865 in-process chains: 38565 stack-trace entries: 341276 [max: 524288] number of stack traces: 14855 number of stack hash chains: 9816 combined max dependencies: 2765438448 hardirq-safe locks: 114 hardirq-unsafe locks: 1381 softirq-safe locks: 255 softirq-unsafe locks: 1275 irq-safe locks: 271 irq-unsafe locks: 1381 hardirq-read-safe locks: 4 hardirq-read-unsafe locks: 426 softirq-read-safe locks: 8 softirq-read-unsafe locks: 423 irq-read-safe locks: 8 irq-read-unsafe locks: 426 uncategorized locks: 401 unused locks: 1 max locking depth: 19 max bfs queue depth: 494 max lock class index: 2317 debug_locks: 0 zapped classes: 642 zapped lock chains: 17600 large chain blocks: 1 |
| Err |
Starting subtest: u-semaphore-noskip Subtest u-semaphore-noskip: SUCCESS (0.767s) Received signal SIGQUIT. Stack trace: Received signal SIGQUIT. Stack trace: #0 [fatal_sig_handler+0x17b] #0 #[1f a[t_a_ls_isgiagc_thianodler+0x17b] n+0x50] #1 [__sigaction+0x50] #2 [_Fork+0x27] #3 [__libc_fork+0x2ee] #4 [__igt_fork_helper +#02x 7[d_]F ork+0x27] #5 [igt_fork_ h#a3n g[__d_eltiebcct_ofro+r0kx+709x]2 ee] ##46 [[____iiggtt_f_ournkique____real_main3205+0x3af3] #7 [main+0x2d] _helper+0x7d] #8 [__libc_init_first+0x8a] #5 [igt_fork_hang_detector+0x79] #6 [_ _#i9g t[__u_nliiqbuce__s_t_a_rrte_amla_imna+i0nx382b0]5 +0x3af3] #10 [_start +#07x 2[5]m ain+0x2d] #8 [__libc_init_first+0x8a] #9 [__libc_start_main+0x8b] #10 [_start+0x25] |
| Dmesg |
<0> [679.116868] gem_exec-2704 5...1. 661457674us : tracing_mark_write: gem_exec_schedule: starting subtest u-semaphore-noskip
<0> [679.116955] gem_exec-2704 5..... 661457825us : __gt_unpark: 0000:03:00.0
<0> [679.117040] gem_exec-2704 5..... 661458180us : __gt_park: 0000:03:00.0
<0> [679.117123] gem_exec-2704 5..... 661459710us : __gt_unpark: 0000:03:00.0
<0> [679.117204] gem_exec-2704 5.l... 661459973us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28fe active
<0> [679.117296] gem_exec-2704 5.l... 661460118us : intel_timeline_pin: 0000:03:00.0 timeline:28fe using HWSP offset:fede3000
<0> [679.117383] gem_exec-2704 5..... 661460492us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [679.117466] gem_exec-2704 5..... 661460526us : __engine_unpark: 0000:03:00.0 rcs0: context:1d92 reset { seqno:55, *hwsp:55, ring:e8 }
<0> [679.117552] gem_exec-2704 5..... 661460528us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28fe pin ring:{start:fec50000, head:0000, tail:0000}
<0> [679.117654] gem_exec-2704 5..... 661461155us : __i915_request_commit: 0000:03:00.0 rcs0: fence 28fe:2, current 0
<0> [679.117753] gem_exec-2704 5D..1. 661461161us : __i915_request_submit: 0000:03:00.0 rcs0: fence 28fe:2, current 0
<0> [679.117851] gem_exec-2704 5..... 661461274us : __i915_request_commit: 0000:03:00.0 rcs0: fence 1d92:86, current 85
<0> [679.117950] gem_exec-2704 5D..1. 661461278us : __i915_request_submit: 0000:03:00.0 rcs0: fence 1d92:86, current 85
<0> [679.118034] gem_exec-2704 5..... 661462859us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28ff active
<0> [679.118127] gem_exec-2704 5..... 661462983us : intel_timeline_pin: 0000:03:00.0 timeline:28ff using HWSP offset:fede2000
<0> [679.118212] gem_exec-2704 5..... 661463109us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:28ff pin ring:{start:fec20000, head:0000, tail:0000}
<0> [679.118302] gem_exec-2704 5..... 661463213us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.118385] gem_exec-2704 5..... 661463253us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.118485] gem_exec-2704 5..... 661463437us : __i915_request_commit: 0000:03:00.0 bcs0: fence 1da0:147, current 146
<0> [679.118583] gem_exec-2704 5D..1. 661463441us : __i915_request_submit: 0000:03:00.0 bcs0: fence 1da0:147, current 146
<0> [679.118681] kworker/-11 0..... 661463672us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 1da0:147, current 147
<0> [679.118768] kworker/-11 0..... 661463681us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [679.118863] gem_exec-2704 5..... 661464076us : __i915_request_commit: 0000:03:00.0 rcs0: fence 28ff:2, current 0
<0> [679.118962] gem_exec-2704 5D..1. 661464080us : __i915_request_submit: 0000:03:00.0 rcs0: fence 28ff:2, current 0
<0> [679.119060] gem_exec-2704 5..... 661464123us : __i915_request_commit: 0000:03:00.0 rcs0: fence 1d92:87, current 86
<0> [679.119158] gem_exec-2704 5D..1. 661464127us : __i915_request_submit: 0000:03:00.0 rcs0: fence 1d92:87, current 86
<0> [679.119250] gem_exec-2704 5d..2. 661464845us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000001
<0> [679.119336] gem_exec-2704 5..... 661466413us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2900 active
<0> [679.119428] gem_exec-2704 5..... 661466539us : intel_timeline_pin: 0000:03:00.0 timeline:2900 using HWSP offset:fede1000
<0> [679.119514] gem_exec-2704 5..... 661466666us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2900 pin ring:{start:febf0000, head:0000, tail:0000}
<0> [679.119617] gem_exec-2704 5..... 661467986us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2900:2, current 0
<0> [679.119715] gem_exec-2704 5D..1. 661467991us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2900:2, current 0
<0> [679.119813] gem_exec-2704 5..... 661468040us : __i915_request_commit: 0000:03:00.0 rcs0: fence 1d92:88, current 87
<0> [679.119912] gem_exec-2704 5D..1. 661468043us : __i915_request_submit: 0000:03:00.0 rcs0: fence 1d92:88, current 87
<0> [679.119996] gem_exec-2704 5..... 661469711us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.120089] gem_exec-2704 5..... 661469836us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.120174] gem_exec-2704 5..... 661469983us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.120263] gem_exec-2704 5..... 661470694us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.120346] gem_exec-2704 5..... 661470753us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.120447] gem_exec-2704 5..... 661470773us : __i915_request_commit: 0000:03:00.0 bcs0: fence 1da0:148, current 147
<0> [679.120545] gem_exec-2704 5D..1. 661470778us : __i915_request_submit: 0000:03:00.0 bcs0: fence 1da0:148, current 147
<0> [679.120643] kworker/-11 0..... 661470960us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 1da0:148, current 148
<0> [679.120731] kworker/-11 0..... 661470968us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [679.120813] gem_exec-2704 5..... 661471976us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.120899] gem_exec-2704 5..... 661472788us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.120986] gem_exec-2704 5..... 661472817us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.121078] gem_exec-2704 5..... 661472819us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.121163] gem_exec-2704 5..... 661472824us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.121251] gem_exec-2704 5..... 661472836us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.121336] gem_exec-2704 5..... 661473592us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.121424] gem_exec-2704 5..... 661473595us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.121516] gem_exec-2704 5..... 661473596us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.121602] gem_exec-2704 5..... 661473600us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.121691] gem_exec-2704 5..... 661474770us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.121776] gem_exec-2704 5..... 661475549us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.121863] gem_exec-2704 5..... 661475577us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.121956] gem_exec-2704 5..... 661475579us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.122041] gem_exec-2704 5..... 661475583us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.122130] gem_exec-2704 5..... 661475594us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.122215] gem_exec-2704 5..... 661476231us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.122302] gem_exec-2704 5..... 661476234us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.122394] gem_exec-2704 5..... 661476235us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.122480] gem_exec-2704 5..... 661476239us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.122568] gem_exec-2704 5..... 661477361us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.122652] gem_exec-2704 5..... 661478172us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.122739] gem_exec-2704 5..... 661478199us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.122831] gem_exec-2704 5..... 661478201us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.122916] gem_exec-2704 5..... 661478205us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.123006] gem_exec-2704 5..... 661478217us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.123091] gem_exec-2704 5..... 661478852us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.123178] gem_exec-2704 5..... 661478854us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.123270] gem_exec-2704 5..... 661478856us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.123356] gem_exec-2704 5..... 661478859us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.123444] gem_exec-2704 5..... 661478870us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.123530] gem_exec-2704 5.l... 661479739us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.123616] gem_exec-2704 5.l... 661479743us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.123708] gem_exec-2704 5.l... 661479744us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.123793] gem_exec-2704 5.l... 661479748us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.123882] gem_exec-2704 5..... 661480911us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.123966] gem_exec-2704 5..... 661481667us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.124053] gem_exec-2704 5..... 661481694us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.124146] gem_exec-2704 5..... 661481696us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.124231] gem_exec-2704 5..... 661481700us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.124319] gem_exec-2704 5..... 661481712us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.124404] gem_exec-2704 5..... 661482349us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.124491] gem_exec-2704 5..... 661482352us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.124583] gem_exec-2704 5..... 661482354us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.124669] gem_exec-2704 5..... 661482357us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.124757] gem_exec-2704 5..... 661482369us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2901 unpin
<0> [679.124842] gem_exec-2704 5.l... 661483446us : __intel_context_retire: 0000:03:00.0 rcs0: context:2901 retire runtime: { total:0ns, avg:0ns }
<0> [679.124928] gem_exec-2704 5.l... 661483449us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 active
<0> [679.125020] gem_exec-2704 5.l... 661483451us : intel_timeline_pin: 0000:03:00.0 timeline:2901 using HWSP offset:fede0000
<0> [679.125106] gem_exec-2704 5.l... 661483455us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2901 pin ring:{start:febc0000, head:0000, tail:0000}
<0> [679.125209] gem_exec-2704 5.l... 661484072us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2901:2, current 0
<0> [679.125307] gem_exec-2704 5Dl.1. 661484077us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2901:2, current 0
<0> [679.125406] gem_exec-2704 5..... 661484230us : __i915_request_commit: 0000:03:00.0 rcs0: fence 1d92:89, current 88
<0> [679.125504] gem_exec-2704 5D..1. 661484233us : __i915_request_submit: 0000:03:00.0 rcs0: fence 1d92:89, current 88
<0> [679.125588] gem_exec-2704 5..... 661485812us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2902 active
<0> [679.125680] gem_exec-2704 5..... 661485937us : intel_timeline_pin: 0000:03:00.0 timeline:2902 using HWSP offset:feb8f000
<0> [679.125765] gem_exec-2704 5..... 661486053us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2902 pin ring:{start:feb90000, head:0000, tail:0000}
<0> [679.125855] gem_exec-2704 5..... 661486147us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.125938] gem_exec-2704 5..... 661486175us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.126039] gem_exec-2704 5..... 661486199us : __i915_request_commit: 0000:03:00.0 bcs0: fence 1da0:149, current 148
<0> [679.126137] gem_exec-2704 5D..1. 661486203us : __i915_request_submit: 0000:03:00.0 bcs0: fence 1da0:149, current 148
<0> [679.126236] kworker/-11 0..... 661486409us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 1da0:149, current 149
<0> [679.126322] kworker/-11 0..... 661486416us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [679.126406] gem_exec-2704 5..... 661486892us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.126489] gem_exec-2704 5..... 661486915us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.126589] gem_exec-2704 5..... 661486935us : __i915_request_commit: 0000:03:00.0 bcs0: fence 1da0:150, current 149
<0> [679.126688] gem_exec-2704 5D..1. 661486939us : __i915_request_submit: 0000:03:00.0 bcs0: fence 1da0:150, current 149
<0> [679.126786] kworker/-11 0..... 661487116us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 1da0:150, current 150
<0> [679.126873] kworker/-11 0..... 661487124us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [679.126969] gem_exec-2704 5..... 661487602us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2902:2, current 0
<0> [679.127067] gem_exec-2704 5D..1. 661487607us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2902:2, current 0
<0> [679.127165] gem_exec-2704 5..... 661487655us : __i915_request_commit: 0000:03:00.0 rcs0: fence 1d92:90, current 89
<0> [679.127263] gem_exec-2704 5D..1. 661487658us : __i915_request_submit: 0000:03:00.0 rcs0: fence 1d92:90, current 89
<0> [679.127348] gem_exec-2704 5..... 661489407us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.127440] gem_exec-2704 5..... 661489553us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.127526] gem_exec-2704 5..... 661489668us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0000, tail:0000}
<0> [679.127628] gem_exec-2704 5..... 661490284us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:2, current 0
<0> [679.127727] gem_exec-2704 5D..1. 661490289us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:2, current 0
<0> [679.127811] gem_exec-2704 5..... 661490532us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 active
<0> [679.127903] gem_exec-2704 5..... 661490657us : intel_timeline_pin: 0000:03:00.0 timeline:2904 using HWSP offset:feb8d000
<0> [679.127990] gem_exec-2704 5..... 661490807us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.128074] gem_exec-2704 5..... 661490829us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.128160] gem_exec-2704 5..... 661490830us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 pin ring:{start:feb20000, head:0000, tail:0000}
<0> [679.128262] gem_exec-2704 5..... 661491467us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:2, current 0
<0> [679.128360] gem_exec-2704 5D..1. 661491471us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:2, current 0
<0> [679.128451] gem_exec-2704 5..... 661491492us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2904:2
<0> [679.128548] kworker/-11 0..... 661491727us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:2, current 2
<0> [679.128634] gem_exec-2704 5.l... 661493328us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 active
<0> [679.128726] gem_exec-2704 5.l... 661493465us : intel_timeline_pin: 0000:03:00.0 timeline:2905 using HWSP offset:feb8c000
<0> [679.128812] gem_exec-2704 5.l... 661493564us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 pin ring:{start:feb00000, head:0000, tail:0000}
<0> [679.128913] gem_exec-2704 5..... 661495500us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2905:2, current 0
<0> [679.129011] gem_exec-2704 5..... 661496196us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:4, current 2
<0> [679.129102] gem_exec-2704 5..... 661496304us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2905:2
<0> [679.129199] <idle>-0 0dNh3. 661506386us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2905:2, current 0
<0> [679.129297] kworker/-11 0..... 661506403us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:2, current 2
<0> [679.129396] <idle>-0 0dNh3. 661506658us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:4, current 2
<0> [679.129493] kworker/-11 0..... 661506672us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2905:2, current 2
<0> [679.129591] kworker/-11 0..... 661506780us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:4, current 4
<0> [679.129684] kworker/-2627 1d..2. 661507055us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000003
<0> [679.129777] kworker/-2627 1d..2. 661507661us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000003
<0> [679.129876] gem_exec-2704 5..... 661508519us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:4, current 2
<0> [679.129974] gem_exec-2704 5D..1. 661508524us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:4, current 2
<0> [679.130066] kworker/-2627 1d..2. 661508703us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000003
<0> [679.130151] gem_exec-2704 5..... 661508927us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.130243] gem_exec-2704 5..... 661509057us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.130329] gem_exec-2704 5..... 661509182us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.130413] gem_exec-2704 5..... 661509204us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.130499] gem_exec-2704 5..... 661509205us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:0000, tail:0000}
<0> [679.130601] gem_exec-2704 5..... 661509798us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:2, current 0
<0> [679.130699] gem_exec-2704 5D..1. 661509818us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:2, current 0
<0> [679.130789] gem_exec-2704 5..... 661509838us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2906:2
<0> [679.130887] kworker/-11 0..... 661510142us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:2, current 2
<0> [679.130972] gem_exec-2704 5..... 661510479us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.131064] gem_exec-2704 5..... 661510608us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.131150] gem_exec-2704 5..... 661510740us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:0000, tail:0000}
<0> [679.131253] gem_exec-2704 5..... 661512500us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2907:2, current 0
<0> [679.131351] gem_exec-2704 5..... 661513153us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:4, current 2
<0> [679.131442] gem_exec-2704 5..... 661513264us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2907:2
<0> [679.131539] <idle>-0 0dNh3. 661523323us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2907:2, current 0
<0> [679.131637] kworker/-11 0..... 661523340us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:4, current 4
<0> [679.131735] <idle>-0 0dNh3. 661523709us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:4, current 2
<0> [679.131833] kworker/-11 0..... 661523737us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2907:2, current 2
<0> [679.131931] kworker/-11 0..... 661523924us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:4, current 4
<0> [679.132023] kworker/-2627 1d..2. 661524218us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000403
<0> [679.132117] kworker/-2627 1d..2. 661524289us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000403
<0> [679.132210] kworker/-2627 1d..2. 661524396us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00000403
<0> [679.132309] gem_exec-2704 5..... 661524811us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:6, current 4
<0> [679.132406] gem_exec-2704 5D..1. 661524816us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:6, current 4
<0> [679.132491] gem_exec-2704 5..... 661525045us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.132584] gem_exec-2704 5..... 661525177us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.132670] gem_exec-2704 5..... 661525286us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.132753] gem_exec-2704 5..... 661525307us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.132839] gem_exec-2704 5..... 661525308us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0000, tail:0000}
<0> [679.132927] gem_exec-2704 5..... 661525331us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.133012] gem_exec-2704 5..... 661525847us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:0ns, avg:0ns }
<0> [679.133099] gem_exec-2704 5..... 661525850us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.133191] gem_exec-2704 5..... 661525852us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.133277] gem_exec-2704 5..... 661525856us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0000, tail:0000}
<0> [679.133378] gem_exec-2704 5.l... 661526521us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:2, current 0
<0> [679.133476] gem_exec-2704 5Dl.1. 661526543us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:2, current 0
<0> [679.133567] gem_exec-2704 5..... 661526569us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2908:2
<0> [679.133663] kworker/-11 0..... 661526700us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:2, current 2
<0> [679.133748] gem_exec-2704 5..... 661527021us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.133840] gem_exec-2704 5..... 661527170us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.133925] gem_exec-2704 5..... 661527276us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0000, tail:0000}
<0> [679.134013] gem_exec-2704 5..... 661527289us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.134098] gem_exec-2704 5..... 661527793us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:0ns, avg:0ns }
<0> [679.134184] gem_exec-2704 5..... 661527796us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.134276] gem_exec-2704 5..... 661527798us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.134361] gem_exec-2704 5..... 661527802us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0000, tail:0000}
<0> [679.134449] gem_exec-2704 5..... 661527813us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.134535] gem_exec-2704 5..... 661528325us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:0ns, avg:0ns }
<0> [679.134622] gem_exec-2704 5..... 661528327us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.134714] gem_exec-2704 5..... 661528329us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.134799] gem_exec-2704 5..... 661528333us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0000, tail:0000}
<0> [679.134901] gem_exec-2704 5..... 661530238us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2909:2, current 0
<0> [679.134999] gem_exec-2704 5..... 661530942us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:4, current 2
<0> [679.135090] gem_exec-2704 5..... 661531045us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2909:2
<0> [679.135187] <idle>-0 0dNH3. 661541217us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2909:2, current 0
<0> [679.135284] kworker/-11 0..... 661541237us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:6, current 6
<0> [679.135382] <idle>-0 0dNH3. 661541521us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:4, current 2
<0> [679.135480] kworker/-11 0..... 661541580us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2909:2, current 2
<0> [679.135565] kworker/-2631 5..... 661541605us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2904 unpin
<0> [679.135664] kworker/-11 0..... 661541687us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:4, current 4
<0> [679.135756] kworker/-2627 1d..2. 661541991us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.135850] kworker/-2627 1d..2. 661542073us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.135943] kworker/-2627 1d..2. 661542234us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.136028] kworker/-2631 5..... 661542390us : __intel_context_retire: 0000:03:00.0 bcs0: context:2904 retire runtime: { total:49296ns, avg:49296ns }
<0> [679.136116] kworker/-2631 5..... 661542399us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2905 unpin
<0> [679.136214] gem_exec-2704 4..... 661542562us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:8, current 6
<0> [679.136311] gem_exec-2704 4D..1. 661542567us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:8, current 6
<0> [679.136396] gem_exec-2704 4..... 661542798us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.136481] kworker/-2631 5.l... 661542931us : __intel_context_retire: 0000:03:00.0 bcs0: context:2905 retire runtime: { total:7228ns, avg:7228ns }
<0> [679.136570] kworker/-69 5..... 661542941us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [679.136660] gem_exec-2704 4..... 661542949us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.136746] gem_exec-2704 4..... 661543054us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.136829] gem_exec-2704 4..... 661543075us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.136916] gem_exec-2704 4..... 661543077us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:0000, tail:0000}
<0> [679.137018] gem_exec-2704 4..... 661543750us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:2, current 0
<0> [679.137116] gem_exec-2704 4D..1. 661543755us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:2, current 0
<0> [679.137207] gem_exec-2704 4..... 661543773us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290a:2
<0> [679.137304] kworker/-11 0..... 661543916us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:2, current 2
<0> [679.137389] gem_exec-2704 4..... 661544298us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.137481] gem_exec-2704 4..... 661544442us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.137566] gem_exec-2704 4..... 661544544us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:0000, tail:0000}
<0> [679.137668] gem_exec-2704 4..... 661546561us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290b:2, current 0
<0> [679.137766] gem_exec-2704 4..... 661547302us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:4, current 2
<0> [679.137857] gem_exec-2704 4..... 661547383us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290b:2
<0> [679.137954] <idle>-0 0dNh3. 661557467us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290b:2, current 0
<0> [679.138051] kworker/-11 0..... 661557483us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:8, current 8
<0> [679.138150] <idle>-0 0dNh3. 661557827us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:4, current 2
<0> [679.138248] kworker/-11 0..... 661557858us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290b:2, current 2
<0> [679.138347] kworker/-11 0..... 661558052us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:4, current 4
<0> [679.138440] kworker/-2631 5d..2. 661558346us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041401
<0> [679.138533] kworker/-2631 5d..2. 661558424us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041401
<0> [679.138626] kworker/-2631 5d..2. 661558537us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041401
<0> [679.138726] gem_exec-2704 4..... 661558878us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:10, current 8
<0> [679.138824] gem_exec-2704 4D..1. 661558883us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:10, current 8
<0> [679.138909] gem_exec-2704 4..... 661559116us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.138993] kworker/-2627 1..... 661559331us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.139086] gem_exec-2704 4..... 661559344us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.139173] gem_exec-2704 4..... 661559457us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [679.139256] gem_exec-2704 4..... 661559479us : __engine_unpark: 0000:03:00.0 vecs1: context:1d97 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.139342] gem_exec-2704 4..... 661559480us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0000, tail:0000}
<0> [679.139431] kworker/-2627 1..... 661559854us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:58136ns, avg:58136ns }
<0> [679.139518] kworker/-2627 1..... 661559862us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.139617] gem_exec-2704 4..... 661560138us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:2, current 0
<0> [679.139716] gem_exec-2704 4D..1. 661560143us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:2, current 0
<0> [679.139807] gem_exec-2704 4..... 661560169us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290c:2
<0> [679.139904] kworker/-11 0..... 661560259us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:2, current 2
<0> [679.139989] kworker/-2627 1..... 661560445us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:7956ns, avg:7956ns }
<0> [679.140078] kworker/-214 1..... 661560458us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.140160] gem_exec-2704 2..... 661560598us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.140252] gem_exec-2704 2..... 661560746us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.140338] gem_exec-2704 2..... 661560847us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:0000, tail:0000}
<0> [679.140440] gem_exec-2704 2..... 661562766us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290d:2, current 0
<0> [679.140539] gem_exec-2704 2..... 661563475us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:4, current 2
<0> [679.140631] gem_exec-2704 2..... 661563551us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290d:2
<0> [679.140729] <idle>-0 0dNh3. 661573720us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290d:2, current 0
<0> [679.140827] kworker/-11 0..... 661573754us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:10, current 10
<0> [679.140925] <idle>-0 0dNh3. 661573971us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:4, current 2
<0> [679.141023] kworker/-11 0..... 661573988us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290d:2, current 2
<0> [679.141122] kworker/-11 0..... 661574109us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:4, current 4
<0> [679.141215] kworker/-2627 1dl.2. 661574443us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1001
<0> [679.141308] kworker/-2627 1dl.2. 661574533us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1001
<0> [679.141401] kworker/-2627 1dl.2. 661574668us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1001
<0> [679.141500] gem_exec-2704 2..... 661575121us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:12, current 10
<0> [679.141598] gem_exec-2704 2D..1. 661575127us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:12, current 10
<0> [679.141683] gem_exec-2704 2..... 661575504us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e active
<0> [679.141775] gem_exec-2704 2..... 661575692us : intel_timeline_pin: 0000:03:00.0 timeline:290e using HWSP offset:feb83000
<0> [679.141861] gem_exec-2704 2..... 661575861us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [679.141945] gem_exec-2704 2..... 661575896us : __engine_unpark: 0000:03:00.0 ccs0: context:1d98 reset { seqno:f, *hwsp:f, ring:c70 }
<0> [679.142031] gem_exec-2704 2..... 661575897us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e pin ring:{start:fe970000, head:0000, tail:0000}
<0> [679.142133] gem_exec-2704 2..... 661576781us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:2, current 0
<0> [679.142231] gem_exec-2704 2D..1. 661576787us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:2, current 0
<0> [679.142322] gem_exec-2704 2..... 661576811us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290e:2
<0> [679.142418] kworker/-11 0..... 661576902us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:2, current 2
<0> [679.142503] gem_exec-2704 2..... 661577323us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f active
<0> [679.142587] kworker/-2627 1..... 661577329us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.142679] gem_exec-2704 2..... 661577529us : intel_timeline_pin: 0000:03:00.0 timeline:290f using HWSP offset:feb82000
<0> [679.142765] gem_exec-2704 2..... 661577678us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f pin ring:{start:fe8d0000, head:0000, tail:0000}
<0> [679.142854] kworker/-2627 1..... 661577855us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:37440ns, avg:37440ns }
<0> [679.142941] kworker/-2627 1..... 661577866us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.143026] kworker/-2627 1..... 661578400us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:8476ns, avg:8476ns }
<0> [679.143114] kworker/-214 1..... 661578413us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.143210] gem_exec-2704 2..... 661579988us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290f:2, current 0
<0> [679.143308] gem_exec-2704 2..... 661580731us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:4, current 2
<0> [679.143398] gem_exec-2704 2..... 661580805us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290f:2
<0> [679.143495] <idle>-0 0dNh3. 661590978us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290f:2, current 0
<0> [679.143593] kworker/-11 0..... 661590997us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:12, current 12
<0> [679.143692] <idle>-0 0dNh3. 661591363us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:4, current 2
<0> [679.143789] kworker/-11 0..... 661591379us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290f:2, current 2
<0> [679.143887] kworker/-11 0..... 661591782us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:4, current 4
<0> [679.143973] gem_exec-2704 2..... 661592067us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 active
<0> [679.144065] gem_exec-2704 2..... 661592070us : intel_timeline_pin: 0000:03:00.0 timeline:2904 using HWSP offset:feb8d000
<0> [679.144157] kworker/-2627 1d..2. 661592079us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0001
<0> [679.144244] gem_exec-2704 2..... 661592084us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.144328] gem_exec-2704 2..... 661592108us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.144414] gem_exec-2704 2..... 661592110us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 pin ring:{start:feb20000, head:0120, tail:0120}
<0> [679.144502] gem_exec-2704 2..... 661592137us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2904 unpin
<0> [679.144594] kworker/-2627 1dl.2. 661592263us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0003
<0> [679.144688] kworker/-2627 1dl.2. 661592413us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0003
<0> [679.144773] gem_exec-2704 2..... 661592743us : __intel_context_retire: 0000:03:00.0 bcs0: context:2904 retire runtime: { total:49296ns, avg:49296ns }
<0> [679.144861] gem_exec-2704 2..... 661592747us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 active
<0> [679.144953] gem_exec-2704 2..... 661592749us : intel_timeline_pin: 0000:03:00.0 timeline:2904 using HWSP offset:feb8d000
<0> [679.145038] gem_exec-2704 2..... 661592754us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 pin ring:{start:feb20000, head:0120, tail:0120}
<0> [679.145126] kworker/-2631 5..... 661593381us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.145225] gem_exec-2704 2..... 661593422us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:6, current 4
<0> [679.145322] gem_exec-2704 2D..1. 661593427us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:6, current 4
<0> [679.145407] kworker/-2631 5..... 661593911us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:39676ns, avg:39676ns }
<0> [679.145494] kworker/-2631 5..... 661593921us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.145592] gem_exec-2704 2.l... 661594367us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:14, current 12
<0> [679.145690] gem_exec-2704 2Dl.1. 661594372us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:14, current 12
<0> [679.145781] gem_exec-2704 2..... 661594405us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2903:e
<0> [679.145865] kworker/-2631 5..... 661594486us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:8372ns, avg:8372ns }
<0> [679.145954] kworker/-69 5..... 661594502us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.146050] kworker/-11 0..... 661594547us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:14, current 14
<0> [679.146135] gem_exec-2704 2..... 661594937us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.146227] gem_exec-2704 2..... 661595138us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.146312] gem_exec-2704 2..... 661595291us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0000, tail:0000}
<0> [679.146400] gem_exec-2704 2..... 661595307us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.146485] gem_exec-2704 2..... 661595821us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:0ns, avg:0ns }
<0> [679.146572] gem_exec-2704 2..... 661595824us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.146663] gem_exec-2704 2..... 661595826us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.146748] gem_exec-2704 2..... 661595831us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0000, tail:0000}
<0> [679.146836] gem_exec-2704 2..... 661595845us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.146921] gem_exec-2704 2..... 661596375us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:0ns, avg:0ns }
<0> [679.147008] gem_exec-2704 2..... 661596378us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.147100] gem_exec-2704 2..... 661596380us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.147185] gem_exec-2704 2..... 661596385us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0000, tail:0000}
<0> [679.147287] gem_exec-2704 2..... 661598701us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2910:2, current 0
<0> [679.147385] gem_exec-2704 2..... 661599558us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:16, current 14
<0> [679.147476] gem_exec-2704 2..... 661599680us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2910:2
<0> [679.147560] kworker/-60 4..... 661608389us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.147645] kworker/-60 4..... 661608904us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:39520ns, avg:39520ns }
<0> [679.147732] kworker/-60 4..... 661608915us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.147817] kworker/-60 4..... 661609459us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:7748ns, avg:7748ns }
<0> [679.147905] kworker/-2388 4..... 661609472us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [679.148000] <idle>-0 0dNh3. 661609845us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2910:2, current 0
<0> [679.148098] kworker/-11 0..... 661609867us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:6, current 6
<0> [679.148197] <idle>-0 0dNh3. 661610070us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:16, current 14
<0> [679.148294] kworker/-11 0..... 661610089us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2910:2, current 2
<0> [679.148393] kworker/-11 0..... 661610430us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:16, current 16
<0> [679.148486] kworker/-60 4d..2. 661610736us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400003
<0> [679.148579] kworker/-60 4d..2. 661610831us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400003
<0> [679.148673] kworker/-60 4d..2. 661610972us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400003
<0> [679.148772] gem_exec-2704 2..... 661611557us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:8, current 6
<0> [679.148870] gem_exec-2704 2D..1. 661611564us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:8, current 6
<0> [679.148955] gem_exec-2704 2..... 661611738us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.149047] gem_exec-2704 2..... 661611741us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.149133] gem_exec-2704 2..... 661611757us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.149216] gem_exec-2704 2..... 661611781us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.149302] gem_exec-2704 2..... 661611783us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:0120, tail:0120}
<0> [679.149404] gem_exec-2704 2..... 661612624us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:6, current 4
<0> [679.149502] gem_exec-2704 2D..1. 661612653us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:6, current 4
<0> [679.149593] gem_exec-2704 2..... 661612678us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2906:6
<0> [679.149691] kworker/-11 0..... 661612805us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:6, current 6
<0> [679.149777] gem_exec-2704 2..... 661613099us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.149868] gem_exec-2704 2..... 661613103us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.149954] gem_exec-2704 2..... 661613110us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:0090, tail:0090}
<0> [679.150056] gem_exec-2704 2..... 661615596us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2907:4, current 2
<0> [679.150155] gem_exec-2704 2..... 661616536us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:8, current 6
<0> [679.150246] gem_exec-2704 2..... 661616682us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2907:4
<0> [679.150343] <idle>-0 0dNh3. 661626849us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2907:4, current 2
<0> [679.150441] kworker/-11 0..... 661626869us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:8, current 8
<0> [679.150539] <idle>-0 0dNh3. 661627046us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:8, current 6
<0> [679.150637] kworker/-11 0..... 661627068us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2907:4, current 4
<0> [679.150735] kworker/-11 0..... 661627178us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:8, current 8
<0> [679.150820] kworker/-60 4..... 661627375us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290e unpin
<0> [679.150912] kworker/-2631 5d..2. 661627463us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.151006] kworker/-2631 5d..2. 661627567us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.151099] kworker/-2631 5d..2. 661627777us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.151185] kworker/-60 4..... 661628046us : __intel_context_retire: 0000:03:00.0 ccs0: context:290e retire runtime: { total:14137604ns, avg:14137604ns }
<0> [679.151274] kworker/-60 4..... 661628058us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290f unpin
<0> [679.151372] gem_exec-2704 2..... 661628256us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:10, current 8
<0> [679.151471] gem_exec-2704 2D..1. 661628262us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:10, current 8
<0> [679.151556] gem_exec-2704 2..... 661628439us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.151648] gem_exec-2704 2..... 661628442us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.151734] gem_exec-2704 2..... 661628459us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.151818] gem_exec-2704 2..... 661628484us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.151904] gem_exec-2704 2..... 661628485us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0120, tail:0120}
<0> [679.151992] kworker/-60 4..... 661628618us : __intel_context_retire: 0000:03:00.0 ccs0: context:290f retire runtime: { total:35568ns, avg:35568ns }
<0> [679.152081] kworker/-2388 4..... 661628631us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [679.152177] gem_exec-2704 2..... 661629363us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:6, current 4
<0> [679.152274] gem_exec-2704 2D..1. 661629392us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:6, current 4
<0> [679.152365] gem_exec-2704 2..... 661629417us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2908:6
<0> [679.152462] kworker/-11 0..... 661629543us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:6, current 6
<0> [679.152548] gem_exec-2704 2..... 661629838us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.152640] gem_exec-2704 2..... 661629842us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.152725] gem_exec-2704 2..... 661629849us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0090, tail:0090}
<0> [679.152828] gem_exec-2704 2..... 661632355us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2909:4, current 2
<0> [679.152926] gem_exec-2704 2..... 661633295us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:8, current 6
<0> [679.153016] gem_exec-2704 2..... 661633442us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2909:4
<0> [679.153114] <idle>-0 0dNh3. 661643538us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2909:4, current 2
<0> [679.153212] kworker/-11 0..... 661643559us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:10, current 10
<0> [679.153310] <idle>-0 0dNh3. 661643736us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:8, current 6
<0> [679.153407] kworker/-11 0..... 661643768us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2909:4, current 4
<0> [679.153505] kworker/-11 0..... 661643950us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:8, current 8
<0> [679.153598] kworker/-60 4d..2. 661644270us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.153692] kworker/-60 4d..2. 661644365us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.153785] kworker/-60 4d..2. 661644511us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.153884] gem_exec-2704 2..... 661645087us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:12, current 10
<0> [679.153982] gem_exec-2704 2D..1. 661645094us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:12, current 10
<0> [679.154067] gem_exec-2704 2..... 661645278us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.154159] gem_exec-2704 2..... 661645282us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.154246] gem_exec-2704 2..... 661645297us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.154330] gem_exec-2704 2..... 661645322us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.154416] gem_exec-2704 2..... 661645323us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:0120, tail:0120}
<0> [679.154504] kworker/-60 4..... 661645334us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.154589] kworker/-60 4..... 661645861us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:19968ns, avg:19968ns }
<0> [679.154676] kworker/-60 4..... 661645872us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.154774] gem_exec-2704 2..... 661646153us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:6, current 4
<0> [679.154872] gem_exec-2704 2D..1. 661646159us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:6, current 4
<0> [679.154963] gem_exec-2704 2..... 661646191us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290a:6
<0> [679.155060] kworker/-11 0..... 661646298us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:6, current 6
<0> [679.155145] kworker/-60 4..... 661646472us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91534456ns, avg:91534456ns }
<0> [679.155234] gem_exec-2704 2..... 661646590us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.155326] gem_exec-2704 2..... 661646594us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.155411] gem_exec-2704 2..... 661646601us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:0090, tail:0090}
<0> [679.155513] gem_exec-2704 2..... 661649081us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290b:4, current 2
<0> [679.155611] gem_exec-2704 2..... 661650066us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:8, current 6
<0> [679.155703] gem_exec-2704 2..... 661650200us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290b:4
<0> [679.155800] gem_exec-2716 0d.h3. 661660316us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290b:4, current 2
<0> [679.155897] kworker/-11 0..... 661660358us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:12, current 12
<0> [679.155996] <idle>-0 0dNh3. 661660485us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:8, current 6
<0> [679.156094] kworker/-11 0..... 661660504us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290b:4, current 4
<0> [679.156192] kworker/-11 0..... 661660720us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:8, current 8
<0> [679.156284] kworker/-60 4d..2. 661661038us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.156378] kworker/-60 4d..2. 661661137us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.156472] kworker/-60 4d..2. 661661295us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.156572] gem_exec-2704 2..... 661661893us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:14, current 12
<0> [679.156670] gem_exec-2704 2D..1. 661661899us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:14, current 12
<0> [679.156755] gem_exec-2704 2..... 661662074us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.156848] gem_exec-2704 2..... 661662077us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.156934] gem_exec-2704 2..... 661662094us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [679.157017] gem_exec-2704 2..... 661662118us : __engine_unpark: 0000:03:00.0 vecs1: context:1d97 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.157103] gem_exec-2704 2..... 661662120us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0120, tail:0120}
<0> [679.157191] gem_exec-2704 2..... 661662152us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.157275] kworker/-60 4..... 661662373us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.157360] gem_exec-2704 2.l... 661663114us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:39520ns, avg:39520ns }
<0> [679.157447] gem_exec-2704 2.l... 661663118us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.157539] gem_exec-2704 2.l... 661663120us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.157625] gem_exec-2704 2.l... 661663126us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0120, tail:0120}
<0> [679.157713] kworker/-60 4..... 661663269us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:16432ns, avg:8008ns }
<0> [679.157801] kworker/-60 4..... 661663280us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.157886] kworker/-60 4..... 661663808us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:96200ns, avg:55588ns }
<0> [679.157974] kworker/-2388 4..... 661663844us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.158070] gem_exec-2704 2..... 661664006us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:6, current 4
<0> [679.158168] gem_exec-2704 2D..1. 661664012us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:6, current 4
<0> [679.158259] gem_exec-2704 2..... 661664036us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290c:6
<0> [679.158356] kworker/-11 0..... 661664166us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:6, current 6
<0> [679.158441] gem_exec-2704 2..... 661664415us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.158534] gem_exec-2704 2..... 661664419us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.158620] gem_exec-2704 2..... 661664425us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:0090, tail:0090}
<0> [679.158708] gem_exec-2704 2..... 661664443us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.158794] gem_exec-2704 2..... 661664969us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:7748ns, avg:7748ns }
<0> [679.158880] gem_exec-2704 2..... 661664974us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.158973] gem_exec-2704 2..... 661664975us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.159058] gem_exec-2704 2..... 661664981us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:0090, tail:0090}
<0> [679.159146] gem_exec-2704 2..... 661664996us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.159231] gem_exec-2704 2..... 661665527us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:7748ns, avg:7748ns }
<0> [679.159318] gem_exec-2704 2..... 661665531us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.159410] gem_exec-2704 2..... 661665533us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.159495] gem_exec-2704 2..... 661665538us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:0090, tail:0090}
<0> [679.159597] gem_exec-2704 2..... 661668012us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290d:4, current 2
<0> [679.159695] gem_exec-2704 2..... 661668980us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:8, current 6
<0> [679.159787] gem_exec-2704 2..... 661669109us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290d:4
<0> [679.159884] <idle>-0 0dNh3. 661679288us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290d:4, current 2
<0> [679.159981] kworker/-11 0..... 661679309us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:14, current 14
<0> [679.160080] <idle>-0 0dNh3. 661679435us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:8, current 6
<0> [679.160178] kworker/-11 0..... 661679500us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290d:4, current 4
<0> [679.160263] kworker/-2631 5..... 661679511us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.160362] kworker/-11 0..... 661679622us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:8, current 8
<0> [679.160454] kworker/-60 4d..2. 661679933us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1003
<0> [679.160548] kworker/-60 4d..2. 661680043us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1003
<0> [679.160642] kworker/-60 4d..2. 661680215us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1003
<0> [679.160728] kworker/-2631 5..... 661680291us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:77688ns, avg:37752ns }
<0> [679.160816] kworker/-2631 5..... 661680302us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.160914] gem_exec-2704 2..... 661680717us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:16, current 14
<0> [679.161012] gem_exec-2704 2D..1. 661680723us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:16, current 14
<0> [679.161098] kworker/-2631 5..... 661680871us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:16536ns, avg:8424ns }
<0> [679.161186] kworker/-69 5..... 661680884us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.161269] gem_exec-2704 2..... 661680898us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e active
<0> [679.161361] gem_exec-2704 2..... 661680902us : intel_timeline_pin: 0000:03:00.0 timeline:290e using HWSP offset:feb83000
<0> [679.161448] gem_exec-2704 2..... 661680918us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [679.161531] gem_exec-2704 2..... 661680953us : __engine_unpark: 0000:03:00.0 ccs0: context:1d98 reset { seqno:f, *hwsp:f, ring:c70 }
<0> [679.161617] gem_exec-2704 2..... 661680954us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e pin ring:{start:fe970000, head:0230, tail:0230}
<0> [679.161719] gem_exec-2704 2..... 661681797us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:6, current 4
<0> [679.161818] gem_exec-2704 2D..1. 661681804us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:6, current 4
<0> [679.161909] gem_exec-2704 2..... 661681829us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290e:6
<0> [679.162006] kworker/-11 0..... 661681979us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:6, current 6
<0> [679.162092] gem_exec-2704 2..... 661682289us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f active
<0> [679.162184] gem_exec-2704 2..... 661682293us : intel_timeline_pin: 0000:03:00.0 timeline:290f using HWSP offset:feb82000
<0> [679.162270] gem_exec-2704 2..... 661682300us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f pin ring:{start:fe8d0000, head:0118, tail:0118}
<0> [679.162372] gem_exec-2704 2..... 661684762us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290f:4, current 2
<0> [679.162469] gem_exec-2704 2..... 661685683us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:8, current 6
<0> [679.162560] gem_exec-2704 2..... 661685810us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290f:4
<0> [679.162658] <idle>-0 0dNh3. 661695981us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290f:4, current 2
<0> [679.162755] kworker/-11 0..... 661696001us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:16, current 16
<0> [679.162854] <idle>-0 0dNh3. 661696123us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:8, current 6
<0> [679.162951] kworker/-11 0..... 661696139us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290f:4, current 4
<0> [679.163037] kworker/-2631 5..... 661696439us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.163135] kworker/-11 0..... 661696522us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:8, current 8
<0> [679.163228] kworker/-60 4d..2. 661696839us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0003
<0> [679.163314] gem_exec-2704 2..... 661696851us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.163406] gem_exec-2704 2..... 661696855us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.163492] gem_exec-2704 2..... 661696871us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.163575] gem_exec-2704 2..... 661696897us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.163661] gem_exec-2704 2..... 661696898us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:0240, tail:0240}
<0> [679.163757] kworker/-60 4d..2. 661696953us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0403
<0> [679.163850] kworker/-60 4d..2. 661697125us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0403
<0> [679.163936] kworker/-2631 5..... 661697259us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:81432ns, avg:39936ns }
<0> [679.164023] kworker/-2631 5..... 661697271us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.164122] gem_exec-2704 2..... 661697705us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:10, current 8
<0> [679.164220] gem_exec-2704 2D..1. 661697734us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:10, current 8
<0> [679.164305] kworker/-2631 5..... 661697872us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:15964ns, avg:8268ns }
<0> [679.164394] kworker/-69 5..... 661697888us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.164477] gem_exec-2704 2..... 661697909us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.164569] gem_exec-2704 2..... 661697912us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.164654] gem_exec-2704 2..... 661697918us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:06c0, tail:06c0}
<0> [679.164756] gem_exec-2704 2..... 661698799us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:18, current 16
<0> [679.164854] gem_exec-2704 2D..1. 661698805us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:18, current 16
<0> [679.164945] gem_exec-2704 2..... 661698830us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2903:12
<0> [679.165042] kworker/-11 0..... 661699008us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:18, current 18
<0> [679.165128] gem_exec-2704 2..... 661699320us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.165220] gem_exec-2704 2..... 661699324us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.165305] gem_exec-2704 2..... 661699330us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:00d8, tail:00d8}
<0> [679.165408] gem_exec-2704 2..... 661701832us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2910:4, current 2
<0> [679.165506] gem_exec-2704 2..... 661702762us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:20, current 18
<0> [679.165597] gem_exec-2704 2..... 661702889us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2910:4
<0> [679.165694] <idle>-0 0dNh3. 661713067us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2910:4, current 2
<0> [679.165792] kworker/-11 0..... 661713087us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:10, current 10
<0> [679.165890] <idle>-0 0dNh3. 661713293us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:20, current 18
<0> [679.165987] kworker/-11 0..... 661713312us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2910:4, current 4
<0> [679.166085] kworker/-11 0..... 661713726us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:20, current 20
<0> [679.166178] kworker/-60 4d..2. 661714048us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480403
<0> [679.166271] kworker/-60 4d..2. 661714146us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480403
<0> [679.166364] kworker/-60 4d..2. 661714305us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480403
<0> [679.166463] gem_exec-2704 2..... 661714860us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:12, current 10
<0> [679.166561] gem_exec-2704 2D..1. 661714887us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:12, current 10
<0> [679.166646] kworker/-60 4..... 661715382us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.166744] gem_exec-2704 2..... 661715895us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:18, current 16
<0> [679.166842] gem_exec-2704 2D..1. 661715901us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:18, current 16
<0> [679.166933] gem_exec-2704 2..... 661715927us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2904:12
<0> [679.167017] kworker/-60 4..... 661715966us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:76960ns, avg:39260ns }
<0> [679.167105] kworker/-60 4..... 661715977us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.167203] kworker/-11 0..... 661716107us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:18, current 18
<0> [679.167288] gem_exec-2704 2..... 661716393us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 active
<0> [679.167380] gem_exec-2704 2..... 661716396us : intel_timeline_pin: 0000:03:00.0 timeline:2905 using HWSP offset:feb8c000
<0> [679.167465] gem_exec-2704 2..... 661716403us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 pin ring:{start:feb00000, head:0090, tail:0090}
<0> [679.167553] kworker/-60 4..... 661716571us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:15444ns, avg:7696ns }
<0> [679.167641] kworker/-2388 4..... 661716608us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [679.167737] gem_exec-2704 2..... 661718856us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2905:4, current 2
<0> [679.167835] gem_exec-2704 2..... 661719783us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:20, current 18
<0> [679.167927] gem_exec-2704 2..... 661719910us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2905:4
<0> [679.168023] <idle>-0 0dNh3. 661730081us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2905:4, current 2
<0> [679.168121] kworker/-11 0..... 661730103us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:12, current 12
<0> [679.168219] <idle>-0 0dNh3. 661730195us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:20, current 18
<0> [679.168317] kworker/-11 0..... 661730210us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2905:4, current 4
<0> [679.168409] kworker/-60 4d..2. 661730551us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.168503] kworker/-60 4d..2. 661730643us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.168596] kworker/-60 4d..2. 661730788us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.168695] gem_exec-2704 2..... 661731373us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:14, current 12
<0> [679.168793] gem_exec-2704 2D..1. 661731398us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:14, current 12
<0> [679.168878] gem_exec-2704 2..... 661731574us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.168970] gem_exec-2704 2..... 661731578us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.169055] kworker/-60 4..... 661731580us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290f unpin
<0> [679.169141] gem_exec-2704 2..... 661731594us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.169224] gem_exec-2704 2..... 661731620us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.169310] gem_exec-2704 2..... 661731621us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0240, tail:0240}
<0> [679.169398] gem_exec-2704 2..... 661731654us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.169483] kworker/-60 4..... 661732669us : __intel_context_retire: 0000:03:00.0 ccs0: context:290f retire runtime: { total:72488ns, avg:35724ns }
<0> [679.169570] kworker/-60 4..... 661732680us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290e unpin
<0> [679.169655] gem_exec-2704 2.l... 661732757us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:77688ns, avg:37752ns }
<0> [679.169742] gem_exec-2704 2.l... 661732761us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.169834] gem_exec-2704 2.l... 661732764us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.169920] gem_exec-2704 2.l... 661732769us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0240, tail:0240}
<0> [679.170008] kworker/-60 4.l... 661733302us : __intel_context_retire: 0000:03:00.0 ccs0: context:290e retire runtime: { total:14215604ns, avg:12380108ns }
<0> [679.170098] kworker/-2388 4..... 661733339us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [679.170194] gem_exec-2704 2..... 661733652us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:10, current 8
<0> [679.170293] gem_exec-2704 2D..1. 661733681us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:10, current 8
<0> [679.170391] kworker/-11 0..... 661733856us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:10, current 10
<0> [679.170476] gem_exec-2704 2..... 661734149us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.170568] gem_exec-2704 2..... 661734153us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.170653] gem_exec-2704 2..... 661734169us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0120, tail:0120}
<0> [679.170741] gem_exec-2704 2..... 661734188us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.170826] gem_exec-2704 2..... 661734767us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:16536ns, avg:8424ns }
<0> [679.170913] gem_exec-2704 2..... 661734772us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.171005] gem_exec-2704 2..... 661734774us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.171090] gem_exec-2704 2..... 661734779us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0120, tail:0120}
<0> [679.171178] gem_exec-2704 2..... 661734795us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.171263] gem_exec-2704 2..... 661735385us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:16536ns, avg:8424ns }
<0> [679.171350] gem_exec-2704 2..... 661735389us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.171442] gem_exec-2704 2..... 661735391us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.171527] gem_exec-2704 2..... 661735396us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0120, tail:0120}
<0> [679.171629] gem_exec-2704 2..... 661737882us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2909:6, current 4
<0> [679.171727] gem_exec-2704 2..... 661738853us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:12, current 10
<0> [679.171811] kworker/-2631 5..... 661748401us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.171896] kworker/-2631 5..... 661748978us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91605072ns, avg:80101476ns }
<0> [679.171984] kworker/-2631 5..... 661748989us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.172082] <idle>-0 0dNh3. 661749122us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2909:6, current 4
<0> [679.172180] kworker/-11 0..... 661749146us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:14, current 14
<0> [679.172278] rcu_pree-15 0d.h2. 661749282us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:12, current 10
<0> [679.172375] kworker/-11 0..... 661749301us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2909:6, current 6
<0> [679.172473] kworker/-11 0..... 661749515us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:12, current 12
<0> [679.172559] kworker/-2631 5..... 661749717us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:39676ns, avg:19916ns }
<0> [679.172654] kworker/-60 4d..2. 661749833us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.172747] kworker/-60 4d..2. 661750082us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.172840] kworker/-60 4d..2. 661750399us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.172938] gem_exec-2704 2..... 661750693us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:16, current 14
<0> [679.173036] gem_exec-2704 2D..1. 661750719us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:16, current 14
<0> [679.173121] gem_exec-2704 2..... 661750892us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.173212] gem_exec-2704 2..... 661750895us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.173299] gem_exec-2704 2..... 661750911us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.173382] gem_exec-2704 2..... 661750935us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.173468] gem_exec-2704 2..... 661750937us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:0240, tail:0240}
<0> [679.173569] gem_exec-2704 2..... 661751712us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:10, current 8
<0> [679.173667] gem_exec-2704 2D..1. 661751719us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:10, current 8
<0> [679.173765] kworker/-11 0..... 661751857us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:10, current 10
<0> [679.173850] gem_exec-2704 2..... 661752168us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.173942] gem_exec-2704 2..... 661752172us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.174027] gem_exec-2704 2..... 661752179us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:0120, tail:0120}
<0> [679.174129] gem_exec-2704 2..... 661754470us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290b:6, current 4
<0> [679.174227] gem_exec-2704 2..... 661755358us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:12, current 10
<0> [679.174326] <idle>-0 0dNh3. 661765659us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290b:6, current 4
<0> [679.174424] kworker/-11 0..... 661765679us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:16, current 16
<0> [679.174522] <idle>-0 0dNh3. 661765892us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:12, current 10
<0> [679.174620] kworker/-11 0..... 661765917us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290b:6, current 6
<0> [679.174718] kworker/-11 0..... 661766094us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:12, current 12
<0> [679.174803] kworker/-2631 5..... 661766359us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2905 unpin
<0> [679.174895] kworker/-60 4d..2. 661766414us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.174988] kworker/-60 4d..2. 661766524us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.175081] kworker/-60 4d..2. 661766729us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.175167] kworker/-2631 5..... 661767015us : __intel_context_retire: 0000:03:00.0 bcs0: context:2905 retire runtime: { total:13676ns, avg:7124ns }
<0> [679.175268] gem_exec-2704 2..... 661767201us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:18, current 16
<0> [679.175366] gem_exec-2704 2D..1. 661767227us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:18, current 16
<0> [679.175451] gem_exec-2704 2..... 661767401us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.175543] gem_exec-2704 2..... 661767404us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.175629] gem_exec-2704 2..... 661767420us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [679.175712] gem_exec-2704 2..... 661767445us : __engine_unpark: 0000:03:00.0 vecs1: context:1d97 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.175798] gem_exec-2704 2..... 661767446us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0240, tail:0240}
<0> [679.175901] gem_exec-2704 2..... 661768257us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:10, current 8
<0> [679.176000] gem_exec-2704 2D..1. 661768263us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:10, current 8
<0> [679.176098] kworker/-11 0..... 661768455us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:10, current 10
<0> [679.176184] gem_exec-2704 2..... 661768749us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.176276] gem_exec-2704 2..... 661768753us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.176361] gem_exec-2704 2..... 661768759us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:0120, tail:0120}
<0> [679.176463] gem_exec-2704 2..... 661771166us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290d:6, current 4
<0> [679.176561] gem_exec-2704 2..... 661772075us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:12, current 10
<0> [679.176659] <idle>-0 0dNh3. 661782328us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290d:6, current 4
<0> [679.176757] kworker/-11 0..... 661782348us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:18, current 18
<0> [679.176856] <idle>-0 0dNh3. 661782557us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:12, current 10
<0> [679.176953] kworker/-11 0..... 661782585us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290d:6, current 6
<0> [679.177052] kworker/-11 0..... 661782766us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:12, current 12
<0> [679.177144] kworker/-2631 5d..2. 661783083us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.177238] kworker/-2631 5d..2. 661783198us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.177331] kworker/-2631 5d..2. 661783454us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.177431] gem_exec-2704 2..... 661783899us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:20, current 18
<0> [679.177530] gem_exec-2704 2D..1. 661783925us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:20, current 18
<0> [679.177615] gem_exec-2704 2..... 661784100us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e active
<0> [679.177707] gem_exec-2704 2..... 661784104us : intel_timeline_pin: 0000:03:00.0 timeline:290e using HWSP offset:feb83000
<0> [679.177793] gem_exec-2704 2..... 661784120us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [679.177876] gem_exec-2704 2..... 661784163us : __engine_unpark: 0000:03:00.0 ccs0: context:1d98 reset { seqno:f, *hwsp:f, ring:c70 }
<0> [679.177963] gem_exec-2704 2..... 661784165us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e pin ring:{start:fe970000, head:0460, tail:0460}
<0> [679.178065] gem_exec-2704 2..... 661784946us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:10, current 8
<0> [679.178163] gem_exec-2704 2D..1. 661784952us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:10, current 8
<0> [679.178260] kworker/-11 0..... 661785175us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:10, current 10
<0> [679.178346] kworker/-2631 5..... 661785393us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.178430] gem_exec-2704 2..... 661785423us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f active
<0> [679.178522] gem_exec-2704 2..... 661785426us : intel_timeline_pin: 0000:03:00.0 timeline:290f using HWSP offset:feb82000
<0> [679.178607] gem_exec-2704 2..... 661785433us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f pin ring:{start:fe8d0000, head:0230, tail:0230}
<0> [679.178696] kworker/-2631 5..... 661785972us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:120588ns, avg:38428ns }
<0> [679.178784] kworker/-2631 5..... 661785983us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.178869] kworker/-2631 5.l... 661787376us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:24440ns, avg:8320ns }
<0> [679.178957] kworker/-69 5..... 661787389us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.179053] gem_exec-2704 2..... 661787762us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290f:6, current 4
<0> [679.179151] gem_exec-2704 2..... 661788683us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:12, current 10
<0> [679.179250] <idle>-0 0dNh3. 661798934us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290f:6, current 4
<0> [679.179348] kworker/-11 0..... 661798955us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:20, current 20
<0> [679.179446] <idle>-0 0dNh3. 661799139us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:12, current 10
<0> [679.179545] kworker/-11 0..... 661799161us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290f:6, current 6
<0> [679.179630] gem_exec-2704 2..... 661799787us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.179722] gem_exec-2704 2..... 661799791us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.179814] kworker/-2631 5d..2. 661799807us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0403
<0> [679.179902] gem_exec-2704 2..... 661799807us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.179986] gem_exec-2704 2..... 661799840us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.180072] gem_exec-2704 2..... 661799841us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0360, tail:0360}
<0> [679.180175] kworker/-11 0..... 661799847us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:12, current 12
<0> [679.180260] gem_exec-2704 2..... 661799874us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.180353] kworker/-2631 5d..2. 661799942us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [679.180446] kworker/-2631 5d..2. 661800301us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1403
<0> [679.180532] gem_exec-2704 2.l... 661801072us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:120588ns, avg:38428ns }
<0> [679.180620] gem_exec-2704 2.l... 661801077us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.180712] gem_exec-2704 2.l... 661801079us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.180797] gem_exec-2704 2.l... 661801084us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0360, tail:0360}
<0> [679.180886] kworker/-2627 1..... 661801387us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.180985] gem_exec-2704 2..... 661801895us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:14, current 12
<0> [679.181084] gem_exec-2704 2D..1. 661801928us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:14, current 12
<0> [679.181169] kworker/-2627 1..... 661801960us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:125216ns, avg:40404ns }
<0> [679.181256] kworker/-2627 1..... 661801971us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.181342] gem_exec-2704 2..... 661802110us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.181434] gem_exec-2704 2..... 661802114us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.181519] gem_exec-2704 2..... 661802121us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0870, tail:0870}
<0> [679.181607] gem_exec-2704 2..... 661802152us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.181693] kworker/-2627 1.l... 661803909us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:24492ns, avg:8268ns }
<0> [679.181781] kworker/-214 1..... 661803927us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.181864] gem_exec-2704 2..... 661803976us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91605072ns, avg:80101476ns }
<0> [679.181952] gem_exec-2704 2..... 661803980us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.182043] gem_exec-2704 2..... 661803982us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.182129] gem_exec-2704 2..... 661803988us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0870, tail:0870}
<0> [679.182231] gem_exec-2704 2..... 661804855us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:22, current 20
<0> [679.182328] gem_exec-2704 2D..1. 661804861us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:22, current 20
<0> [679.182426] kworker/-11 0..... 661805323us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:22, current 22
<0> [679.182516] gem_exec-2704 2..... 661805345us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.182608] gem_exec-2704 2..... 661805348us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.182694] gem_exec-2704 2..... 661805355us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:01b0, tail:01b0}
<0> [679.182782] gem_exec-2704 2..... 661805373us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.182867] gem_exec-2704 2..... 661806262us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:39676ns, avg:19916ns }
<0> [679.182954] gem_exec-2704 2..... 661806266us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.183046] gem_exec-2704 2..... 661806268us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.183132] gem_exec-2704 2..... 661806274us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:01b0, tail:01b0}
<0> [679.183220] gem_exec-2704 2..... 661806290us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.183305] gem_exec-2704 2..... 661806865us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:39676ns, avg:19916ns }
<0> [679.183392] gem_exec-2704 2..... 661806869us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.183484] gem_exec-2704 2..... 661806871us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.183569] gem_exec-2704 2..... 661806876us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:01b0, tail:01b0}
<0> [679.183670] gem_exec-2704 2..... 661809361us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2910:6, current 4
<0> [679.183768] gem_exec-2704 2..... 661810303us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:24, current 22
<0> [679.183853] kworker/-2627 1..... 661818401us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.183939] kworker/-2627 1..... 661818979us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:120848ns, avg:39832ns }
<0> [679.184027] kworker/-2627 1..... 661818990us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.184112] kworker/-2627 1.l... 661819579us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:23504ns, avg:7748ns }
<0> [679.184200] kworker/-214 1..... 661819592us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [679.184295] <idle>-0 0dNh3. 661820602us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2910:6, current 4
<0> [679.184393] kworker/-11 0..... 661820623us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:14, current 14
<0> [679.184491] <idle>-0 0dNh3. 661820879us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:24, current 22
<0> [679.184589] kworker/-11 0..... 661820914us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2910:6, current 6
<0> [679.184687] kworker/-11 0..... 661821234us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:24, current 24
<0> [679.184780] kworker/-2627 1d..2. 661821543us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00401403
<0> [679.184873] kworker/-2627 1d..2. 661821643us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00401403
<0> [679.184966] kworker/-2627 1d..2. 661821835us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00401403
<0> [679.185065] gem_exec-2704 2..... 661822332us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:16, current 14
<0> [679.185163] gem_exec-2704 2D..1. 661822358us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:16, current 14
<0> [679.185262] gem_exec-2704 2..... 661823349us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:22, current 20
<0> [679.185360] gem_exec-2704 2D..1. 661823356us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:22, current 20
<0> [679.185458] kworker/-11 0..... 661823510us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:20, current 22
<0> [679.185556] kworker/-11 0..... 661823523us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:22, current 22
<0> [679.185641] gem_exec-2704 2..... 661823805us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 active
<0> [679.185733] gem_exec-2704 2..... 661823809us : intel_timeline_pin: 0000:03:00.0 timeline:2905 using HWSP offset:feb8c000
<0> [679.185818] gem_exec-2704 2..... 661823816us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 pin ring:{start:feb00000, head:0120, tail:0120}
<0> [679.185919] gem_exec-2704 2..... 661826279us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2905:6, current 4
<0> [679.186017] gem_exec-2704 2..... 661827222us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:24, current 22
<0> [679.186108] gem_exec-2704 2..... 661827351us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2905:6
<0> [679.186192] kworker/-2627 1..... 661834392us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290f unpin
<0> [679.186277] kworker/-2627 1..... 661834970us : __intel_context_retire: 0000:03:00.0 ccs0: context:290f retire runtime: { total:108732ns, avg:35776ns }
<0> [679.186364] kworker/-2627 1..... 661834982us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.186449] kworker/-2627 1..... 661835977us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92259804ns, avg:11569116ns }
<0> [679.186539] kworker/-214 1..... 661835992us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.186621] kworker/-2627 1..... 661836392us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290e unpin
<0> [679.186706] kworker/-2627 1.l... 661836974us : __intel_context_retire: 0000:03:00.0 ccs0: context:290e retire runtime: { total:14312168ns, avg:10844704ns }
<0> [679.186796] kworker/-214 1..... 661836990us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [679.186891] <idle>-0 0dNh3. 661837442us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2905:6, current 4
<0> [679.186989] kworker/-11 0..... 661837462us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:16, current 16
<0> [679.187088] <idle>-0 0dNh3. 661837638us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:24, current 22
<0> [679.187186] kworker/-11 0..... 661837657us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2905:6, current 6
<0> [679.187280] kworker/-2627 1d..2. 661838005us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001003
<0> [679.187373] kworker/-2627 1d..2. 661838094us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001003
<0> [679.187466] kworker/-2627 1d..2. 661838289us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001003
<0> [679.187565] gem_exec-2704 2..... 661838773us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:18, current 16
<0> [679.187662] gem_exec-2704 2D..1. 661838800us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:18, current 16
<0> [679.187747] gem_exec-2704 2..... 661838973us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.187840] gem_exec-2704 2..... 661838977us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.187925] gem_exec-2704 2..... 661838993us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.188009] gem_exec-2704 2..... 661839018us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.188095] gem_exec-2704 2..... 661839019us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:05a0, tail:05a0}
<0> [679.188198] gem_exec-2704 2..... 661839859us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:22, current 20
<0> [679.188297] gem_exec-2704 2D..1. 661839888us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:22, current 20
<0> [679.188394] kworker/-11 0..... 661840079us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:22, current 22
<0> [679.188480] gem_exec-2704 2..... 661840366us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.188571] gem_exec-2704 2..... 661840369us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.188657] gem_exec-2704 2..... 661840377us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:0120, tail:0120}
<0> [679.188759] gem_exec-2704 2.l... 661842831us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2907:6, current 4
<0> [679.188857] gem_exec-2704 2..... 661843812us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:24, current 22
<0> [679.188956] gem_exec-2727 0d.h3. 661854112us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2907:6, current 4
<0> [679.189053] kworker/-11 0..... 661854153us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:18, current 18
<0> [679.189152] <idle>-0 0dNh3. 661854332us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:24, current 22
<0> [679.189250] kworker/-11 0..... 661854351us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2907:6, current 6
<0> [679.189349] kworker/-11 0..... 661854532us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:24, current 24
<0> [679.189442] kworker/-2627 1d..2. 661854868us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.189536] kworker/-2627 1d..2. 661854965us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.189629] kworker/-2627 1d..2. 661855167us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00001403
<0> [679.189728] gem_exec-2704 2..... 661855657us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:20, current 18
<0> [679.189827] gem_exec-2704 2D..1. 661855684us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:20, current 18
<0> [679.189912] gem_exec-2704 2..... 661855857us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.190004] gem_exec-2704 2..... 661855860us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.190091] gem_exec-2704 2..... 661855877us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.190174] gem_exec-2704 2..... 661855918us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.190260] gem_exec-2704 2..... 661855919us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:0360, tail:0360}
<0> [679.190348] kworker/-2627 1..... 661856387us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.190447] gem_exec-2704 2..... 661856759us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:14, current 12
<0> [679.190544] gem_exec-2704 2D..1. 661856765us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:14, current 12
<0> [679.190643] kworker/-11 0..... 661856948us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:14, current 14
<0> [679.190729] kworker/-2627 1..... 661857008us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:59280ns, avg:19864ns }
<0> [679.190817] kworker/-2627 1..... 661857019us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.190901] gem_exec-2704 2..... 661857256us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.190993] gem_exec-2704 2..... 661857260us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.191079] gem_exec-2704 2..... 661857267us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:01b0, tail:01b0}
<0> [679.191168] kworker/-2627 1..... 661857607us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91676104ns, avg:70097664ns }
<0> [679.191269] gem_exec-2704 2..... 661859721us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290b:8, current 6
<0> [679.191367] gem_exec-2704 2..... 661860663us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:16, current 14
<0> [679.191466] gem_exec-2728 0d.h3. 661870934us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290b:8, current 6
<0> [679.191564] kworker/-11 0..... 661870975us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:20, current 20
<0> [679.191662] <idle>-0 0dNh3. 661871128us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:16, current 14
<0> [679.191760] kworker/-11 0..... 661871144us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290b:8, current 8
<0> [679.191858] kworker/-11 0..... 661871383us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:16, current 16
<0> [679.191952] kworker/-2627 1d..2. 661871715us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.192045] kworker/-2627 1d..2. 661871813us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.192138] kworker/-2627 1d..2. 661872006us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.192225] kworker/-2631 5..... 661872338us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2905 unpin
<0> [679.192323] gem_exec-2704 2..... 661872546us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:22, current 20
<0> [679.192421] gem_exec-2704 2D..1. 661872575us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:22, current 20
<0> [679.192506] gem_exec-2704 2..... 661872751us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.192598] gem_exec-2704 2..... 661872754us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.192685] gem_exec-2704 2..... 661872771us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [679.192768] gem_exec-2704 2..... 661872796us : __engine_unpark: 0000:03:00.0 vecs1: context:1d97 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.192854] gem_exec-2704 2..... 661872798us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0360, tail:0360}
<0> [679.192942] gem_exec-2704 2..... 661872829us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.193027] kworker/-2631 5..... 661872995us : __intel_context_retire: 0000:03:00.0 bcs0: context:2905 retire runtime: { total:20488ns, avg:7072ns }
<0> [679.193115] gem_exec-2704 2.l... 661873541us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:120848ns, avg:39832ns }
<0> [679.193202] gem_exec-2704 2.l... 661873545us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.193295] gem_exec-2704 2.l... 661873548us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.193381] gem_exec-2704 2.l... 661873553us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0360, tail:0360}
<0> [679.193483] gem_exec-2704 2..... 661874466us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:14, current 12
<0> [679.193581] gem_exec-2704 2D..1. 661874473us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:14, current 12
<0> [679.193679] kworker/-11 0..... 661874609us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:14, current 14
<0> [679.193764] gem_exec-2704 2..... 661874903us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.193856] gem_exec-2704 2..... 661874907us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.193942] gem_exec-2704 2..... 661874914us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:01b0, tail:01b0}
<0> [679.194030] gem_exec-2704 2..... 661874933us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.194115] gem_exec-2704 2..... 661875527us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:23504ns, avg:7748ns }
<0> [679.194203] gem_exec-2704 2..... 661875532us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.194294] gem_exec-2704 2..... 661875534us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.194379] gem_exec-2704 2..... 661875540us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:01b0, tail:01b0}
<0> [679.194467] gem_exec-2704 2..... 661875557us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.194552] gem_exec-2704 2..... 661876132us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:23504ns, avg:7748ns }
<0> [679.194640] gem_exec-2704 2..... 661876136us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.194731] gem_exec-2704 2..... 661876138us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.194817] gem_exec-2704 2..... 661876143us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:01b0, tail:01b0}
<0> [679.194919] gem_exec-2704 2..... 661878763us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290d:8, current 6
<0> [679.195016] gem_exec-2704 2..... 661879755us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:16, current 14
<0> [679.195101] kworker/-2631 5..... 661889556us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.195199] gem_exec-2729 0d.h2. 661889983us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290d:8, current 6
<0> [679.195297] kworker/-11 0..... 661890056us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:22, current 22
<0> [679.195395] <idle>-0 0dNh3. 661890167us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:16, current 14
<0> [679.195493] kworker/-11 0..... 661890194us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290d:8, current 8
<0> [679.195579] kworker/-2631 5.l... 661890332us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92302184ns, avg:10128248ns }
<0> [679.195668] kworker/-2631 5.l... 661890345us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.195766] kworker/-11 0..... 661890376us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:16, current 16
<0> [679.195859] kworker/-2627 1d..2. 661890731us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.195953] kworker/-2627 1d..2. 661890863us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.196039] kworker/-2631 5.l... 661891092us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:24492ns, avg:8008ns }
<0> [679.196135] kworker/-2627 1d..2. 661891102us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.196221] kworker/-69 5..... 661891151us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.196317] gem_exec-2704 2..... 661891792us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:24, current 22
<0> [679.196416] gem_exec-2704 2D..1. 661891819us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:24, current 22
<0> [679.196500] gem_exec-2704 2..... 661891999us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e active
<0> [679.196592] gem_exec-2704 2..... 661892004us : intel_timeline_pin: 0000:03:00.0 timeline:290e using HWSP offset:feb83000
<0> [679.196679] gem_exec-2704 2..... 661892023us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [679.196762] gem_exec-2704 2..... 661892058us : __engine_unpark: 0000:03:00.0 ccs0: context:1d98 reset { seqno:f, *hwsp:f, ring:c70 }
<0> [679.196847] gem_exec-2704 2..... 661892060us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e pin ring:{start:fe970000, head:0690, tail:0690}
<0> [679.196949] gem_exec-2704 2..... 661892990us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:14, current 12
<0> [679.197048] gem_exec-2704 2D..1. 661892996us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:14, current 12
<0> [679.197146] kworker/-11 0..... 661893205us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:14, current 14
<0> [679.197232] gem_exec-2704 2..... 661893511us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f active
<0> [679.197323] gem_exec-2704 2..... 661893515us : intel_timeline_pin: 0000:03:00.0 timeline:290f using HWSP offset:feb82000
<0> [679.197409] gem_exec-2704 2..... 661893522us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f pin ring:{start:fe8d0000, head:0348, tail:0348}
<0> [679.197512] gem_exec-2704 2..... 661896169us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290f:8, current 6
<0> [679.197609] gem_exec-2704 2..... 661897164us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:16, current 14
<0> [679.197694] kworker/-2627 1..... 661906394us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.197779] kworker/-2627 1..... 661906972us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:169624ns, avg:40872ns }
<0> [679.197866] kworker/-2627 1..... 661906984us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.197964] gem_exec-2730 0d.h2. 661907370us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290f:8, current 6
<0> [679.198061] kworker/-11 0..... 661907420us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:24, current 24
<0> [679.198146] kworker/-2627 1.l... 661907622us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:32032ns, avg:8164ns }
<0> [679.198247] <idle>-0 0dNh3. 661907634us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:16, current 14
<0> [679.198333] kworker/-214 1..... 661907639us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.198429] kworker/-11 0..... 661907666us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290f:8, current 8
<0> [679.198527] kworker/-11 0..... 661908042us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:16, current 16
<0> [679.198620] kworker/-2631 5d..2. 661908379us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00481003
<0> [679.198706] gem_exec-2704 2..... 661908391us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.198798] gem_exec-2704 2..... 661908394us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.198884] gem_exec-2704 2..... 661908410us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.198967] gem_exec-2704 2..... 661908435us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.199053] gem_exec-2704 2..... 661908436us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:0480, tail:0480}
<0> [679.199149] kworker/-2631 5d..2. 661908484us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.199242] kworker/-2631 5d..2. 661908681us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.199340] gem_exec-2704 2..... 661909262us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:18, current 16
<0> [679.199438] gem_exec-2704 2D..1. 661909269us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:18, current 16
<0> [679.199522] gem_exec-2704 2..... 661909446us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.199614] gem_exec-2704 2..... 661909450us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.199700] gem_exec-2704 2..... 661909458us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0a20, tail:0a20}
<0> [679.199802] gem_exec-2704 2..... 661910343us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:26, current 24
<0> [679.199900] gem_exec-2704 2D..1. 661910350us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:26, current 24
<0> [679.199998] kworker/-11 0..... 661910561us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:26, current 26
<0> [679.200084] gem_exec-2704 2..... 661910856us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.200175] gem_exec-2704 2..... 661910860us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.200261] gem_exec-2704 2..... 661910868us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0288, tail:0288}
<0> [679.200363] gem_exec-2704 2..... 661913496us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2910:8, current 6
<0> [679.200461] gem_exec-2704 2..... 661914500us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:28, current 26
<0> [679.200559] <idle>-0 0dNh3. 661924793us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2910:8, current 6
<0> [679.200657] kworker/-11 0..... 661924813us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:18, current 18
<0> [679.200756] <idle>-0 0dNh3. 661925063us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:28, current 26
<0> [679.200853] kworker/-11 0..... 661925096us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2910:8, current 8
<0> [679.200952] kworker/-11 0..... 661925442us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:28, current 28
<0> [679.201037] kworker/-2627 1..... 661925452us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.201129] kworker/-2631 5d..2. 661925783us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.201223] kworker/-2631 5d..2. 661925899us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.201316] kworker/-2631 5d..2. 661926113us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.201402] kworker/-2627 1..... 661926322us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:163384ns, avg:40144ns }
<0> [679.201490] kworker/-2627 1..... 661926332us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.201588] gem_exec-2704 2..... 661926655us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:20, current 18
<0> [679.201686] gem_exec-2704 2D..1. 661926662us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:20, current 18
<0> [679.201771] kworker/-2627 1.l... 661926897us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:30992ns, avg:7696ns }
<0> [679.201859] kworker/-214 1..... 661926910us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [679.201954] gem_exec-2704 2..... 661927658us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:26, current 24
<0> [679.202053] gem_exec-2704 2D..1. 661927664us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:26, current 24
<0> [679.202151] kworker/-11 0..... 661927817us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:24, current 26
<0> [679.202249] kworker/-11 0..... 661927830us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:26, current 26
<0> [679.202334] gem_exec-2704 2..... 661928113us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 active
<0> [679.202426] gem_exec-2704 2..... 661928117us : intel_timeline_pin: 0000:03:00.0 timeline:2905 using HWSP offset:feb8c000
<0> [679.202512] gem_exec-2704 2..... 661928124us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 pin ring:{start:feb00000, head:01b0, tail:01b0}
<0> [679.202614] gem_exec-2704 2..... 661930609us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2905:8, current 6
<0> [679.202712] gem_exec-2704 2..... 661931557us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:28, current 26
<0> [679.202803] gem_exec-2704 2..... 661931681us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2905:8
<0> [679.202900] <idle>-0 0dNh3. 661941802us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2905:8, current 6
<0> [679.202998] kworker/-11 0..... 661941823us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:20, current 20
<0> [679.203096] <idle>-0 0dNh3. 661941949us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:28, current 26
<0> [679.203194] kworker/-11 0..... 661941968us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2905:8, current 8
<0> [679.203287] kworker/-2631 5d..2. 661942292us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00441003
<0> [679.203372] kworker/-2631 5..... 661942363us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290e unpin
<0> [679.203465] kworker/-2622 0d..2. 661942412us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00441003
<0> [679.203558] kworker/-2622 0d..2. 661942581us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00441003
<0> [679.203644] kworker/-2631 5..... 661942968us : __intel_context_retire: 0000:03:00.0 ccs0: context:290e retire runtime: { total:14408160ns, avg:9501076ns }
<0> [679.203733] kworker/-2631 5..... 661942976us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290f unpin
<0> [679.203831] gem_exec-2704 2..... 661943174us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:22, current 20
<0> [679.203929] gem_exec-2704 2D..1. 661943180us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:22, current 20
<0> [679.204015] gem_exec-2704 2..... 661943367us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.204107] gem_exec-2704 2..... 661943371us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.204193] gem_exec-2704 2..... 661943387us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.204276] gem_exec-2704 2..... 661943414us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.204362] gem_exec-2704 2..... 661943415us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:06c0, tail:06c0}
<0> [679.204450] gem_exec-2704 2..... 661943447us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.204534] kworker/-2631 5..... 661943678us : __intel_context_retire: 0000:03:00.0 ccs0: context:290f retire runtime: { total:143000ns, avg:35568ns }
<0> [679.204622] kworker/-2631 5..... 661943686us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.204707] gem_exec-2704 2.l... 661944550us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92302184ns, avg:10128248ns }
<0> [679.204795] gem_exec-2704 2.l... 661944555us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.204887] gem_exec-2704 2.l... 661944557us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.204972] gem_exec-2704 2.l... 661944563us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:06c0, tail:06c0}
<0> [679.205061] kworker/-2631 5.l... 661944682us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:96347680ns, avg:12061972ns }
<0> [679.205151] kworker/-69 5..... 661944695us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [679.205234] kworker/-69 5..... 661944713us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.205330] gem_exec-2704 2..... 661945436us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:26, current 24
<0> [679.205429] gem_exec-2704 2D..1. 661945466us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:26, current 24
<0> [679.205526] kworker/-11 0..... 661945671us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:26, current 26
<0> [679.205612] gem_exec-2704 2..... 661945892us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.205704] gem_exec-2704 2..... 661945896us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.205789] gem_exec-2704 2..... 661945903us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:01b0, tail:01b0}
<0> [679.205877] gem_exec-2704 2..... 661945921us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.205962] gem_exec-2704 2..... 661946515us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:24492ns, avg:8008ns }
<0> [679.206049] gem_exec-2704 2..... 661946520us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.206141] gem_exec-2704 2..... 661946522us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.206226] gem_exec-2704 2..... 661946527us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:01b0, tail:01b0}
<0> [679.206314] gem_exec-2704 2..... 661946544us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.206399] gem_exec-2704 2..... 661947121us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:24492ns, avg:8008ns }
<0> [679.206487] gem_exec-2704 2..... 661947125us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.206579] gem_exec-2704 2..... 661947127us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.206664] gem_exec-2704 2..... 661947132us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:01b0, tail:01b0}
<0> [679.206765] gem_exec-2704 2..... 661949641us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2907:8, current 6
<0> [679.206863] gem_exec-2704 2..... 661950626us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:28, current 26
<0> [679.206948] kworker/-2622 0..... 661959380us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.207033] kworker/-2622 0..... 661959958us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:78884ns, avg:19812ns }
<0> [679.207120] kworker/-2622 0..... 661960368us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.207218] kworker/-2622 0dlh2. 661960863us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2907:8, current 6
<0> [679.207302] kworker/-2622 0.l... 661960992us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91747032ns, avg:61344296ns }
<0> [679.207404] kworker/-11 0..... 661961006us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:22, current 22
<0> [679.207502] <idle>-0 0dNh3. 661961061us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:28, current 26
<0> [679.207600] kworker/-11 0..... 661961076us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2907:8, current 8
<0> [679.207699] kworker/-11 0..... 661961293us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:28, current 28
<0> [679.207792] kworker/-2622 4d..2. 661961605us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00040403
<0> [679.207886] kworker/-2622 4d..2. 661961699us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00040403
<0> [679.207979] kworker/-2622 4d..2. 661961887us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00040403
<0> [679.208079] gem_exec-2704 2..... 661962409us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:24, current 22
<0> [679.208176] gem_exec-2704 2D..1. 661962416us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:24, current 22
<0> [679.208261] gem_exec-2704 2..... 661962589us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.208353] gem_exec-2704 2..... 661962593us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.208439] gem_exec-2704 2..... 661962609us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.208522] gem_exec-2704 2..... 661962633us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.208608] gem_exec-2704 2..... 661962635us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:06c0, tail:06c0}
<0> [679.208710] gem_exec-2704 2..... 661963484us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:26, current 24
<0> [679.208808] gem_exec-2704 2D..1. 661963514us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:26, current 24
<0> [679.208906] kworker/-11 0..... 661963691us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:26, current 26
<0> [679.208992] gem_exec-2704 2..... 661963985us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.209084] gem_exec-2704 2..... 661963989us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.209170] gem_exec-2704 2..... 661963996us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:01b0, tail:01b0}
<0> [679.209271] gem_exec-2704 2..... 661966539us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2909:8, current 6
<0> [679.209369] gem_exec-2704 2..... 661967526us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:28, current 26
<0> [679.209454] kworker/-2631 5..... 661976398us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2905 unpin
<0> [679.209539] kworker/-2631 5..... 661976977us : __intel_context_retire: 0000:03:00.0 bcs0: context:2905 retire runtime: { total:26988ns, avg:6968ns }
<0> [679.209640] <idle>-0 0dNh3. 661977843us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2909:8, current 6
<0> [679.209738] kworker/-11 0..... 661977863us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:24, current 24
<0> [679.209836] <idle>-0 0dNh3. 661978094us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:28, current 26
<0> [679.209934] kworker/-11 0..... 661978116us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2909:8, current 8
<0> [679.210032] kworker/-11 0..... 661978251us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:28, current 28
<0> [679.210125] kworker/-2631 5d..2. 661978582us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.210218] kworker/-2631 5d..2. 661978681us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.210310] kworker/-2631 5d..2. 661978877us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00041403
<0> [679.210410] gem_exec-2704 2..... 661979398us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:26, current 24
<0> [679.210509] gem_exec-2704 2D..1. 661979405us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:26, current 24
<0> [679.210594] gem_exec-2704 2..... 661979578us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.210687] gem_exec-2704 2..... 661979581us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.210773] gem_exec-2704 2..... 661979598us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [679.210856] gem_exec-2704 2..... 661979622us : __engine_unpark: 0000:03:00.0 vecs1: context:1d97 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.210942] gem_exec-2704 2..... 661979624us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0480, tail:0480}
<0> [679.211044] gem_exec-2704 2..... 661980464us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:18, current 16
<0> [679.211141] gem_exec-2704 2D..1. 661980470us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:18, current 16
<0> [679.211239] kworker/-11 0..... 661980675us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:18, current 18
<0> [679.211324] gem_exec-2704 2..... 661980966us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.211416] gem_exec-2704 2..... 661980969us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.211501] gem_exec-2704 2..... 661980976us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:0240, tail:0240}
<0> [679.211603] gem_exec-2704 2..... 661983498us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290d:10, current 8
<0> [679.211702] gem_exec-2704 2..... 661984465us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:20, current 18
<0> [679.211800] <idle>-0 0dNh3. 661994772us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290d:10, current 8
<0> [679.211898] kworker/-11 0..... 661994802us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:26, current 26
<0> [679.211997] <idle>-0 0dNh3. 661995016us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:20, current 18
<0> [679.212095] kworker/-11 0..... 661995036us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290d:10, current 10
<0> [679.212193] kworker/-11 0..... 661995137us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:20, current 20
<0> [679.212278] kworker/-2622 4..... 661995295us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.212370] kworker/-2631 5d..2. 661995462us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.212463] kworker/-2631 5d..2. 661995598us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.212556] kworker/-2631 5d..2. 661995784us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1403
<0> [679.212641] kworker/-2622 4..... 661996038us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:32448ns, avg:8008ns }
<0> [679.212728] kworker/-2622 4..... 661996329us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.212826] gem_exec-2704 2..... 661996426us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:28, current 26
<0> [679.212924] gem_exec-2704 2D..1. 661996433us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:28, current 26
<0> [679.213009] gem_exec-2704 2..... 661996630us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e active
<0> [679.213101] gem_exec-2704 2..... 661996635us : intel_timeline_pin: 0000:03:00.0 timeline:290e using HWSP offset:feb83000
<0> [679.213187] gem_exec-2704 2..... 661996655us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [679.213271] gem_exec-2704 2..... 661996693us : __engine_unpark: 0000:03:00.0 ccs0: context:1d98 reset { seqno:f, *hwsp:f, ring:c70 }
<0> [679.213357] gem_exec-2704 2..... 661996695us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e pin ring:{start:fe970000, head:08c0, tail:08c0}
<0> [679.213446] kworker/-2622 4..... 661996922us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92344304ns, avg:8867456ns }
<0> [679.213535] kworker/-2388 4..... 661996940us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.213631] gem_exec-2704 2..... 661997547us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:18, current 16
<0> [679.213729] gem_exec-2704 2D..1. 661997554us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:18, current 16
<0> [679.213826] kworker/-11 0..... 661997754us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:18, current 18
<0> [679.213911] gem_exec-2704 2..... 661998070us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f active
<0> [679.214003] gem_exec-2704 2..... 661998074us : intel_timeline_pin: 0000:03:00.0 timeline:290f using HWSP offset:feb82000
<0> [679.214088] gem_exec-2704 2..... 661998081us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f pin ring:{start:fe8d0000, head:0460, tail:0460}
<0> [679.214190] gem_exec-2704 2..... 662000675us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290f:10, current 8
<0> [679.214288] gem_exec-2704 2..... 662001639us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:20, current 18
<0> [679.214386] <idle>-0 0dNh3. 662011901us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290f:10, current 8
<0> [679.214484] kworker/-11 0..... 662011922us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:28, current 28
<0> [679.214582] <idle>-0 0dNh3. 662012113us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:20, current 18
<0> [679.214681] kworker/-11 0..... 662012129us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290f:10, current 10
<0> [679.214766] kworker/-2622 4..... 662012341us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.214864] kworker/-11 0..... 662012493us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:20, current 20
<0> [679.214957] kworker/-2631 5d..2. 662012851us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.215050] kworker/-2631 5d..2. 662012970us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.215143] kworker/-2631 5d..2. 662013198us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.215228] kworker/-2622 4..... 662013199us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:32708ns, avg:8320ns }
<0> [679.215316] kworker/-60 0..... 662013273us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.215414] gem_exec-2704 2..... 662013681us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:22, current 20
<0> [679.215512] gem_exec-2704 2D..1. 662013687us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:22, current 20
<0> [679.215597] kworker/-60 0..... 662013841us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:96390060ns, avg:10559536ns }
<0> [679.215687] kworker/-11 0..... 662013854us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.215769] gem_exec-2704 2..... 662013867us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.215861] gem_exec-2704 2..... 662013871us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.215946] gem_exec-2704 2..... 662013879us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0bd0, tail:0bd0}
<0> [679.216035] gem_exec-2704 2..... 662013910us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.216120] gem_exec-2704 2..... 662014547us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91747032ns, avg:61344296ns }
<0> [679.216208] gem_exec-2704 2..... 662014552us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.216299] gem_exec-2704 2..... 662014554us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.216384] gem_exec-2704 2..... 662014559us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0bd0, tail:0bd0}
<0> [679.216485] gem_exec-2704 2..... 662015418us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:30, current 28
<0> [679.216584] gem_exec-2704 2D..1. 662015424us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:30, current 28
<0> [679.216682] kworker/-11 0..... 662015674us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:30, current 30
<0> [679.216767] gem_exec-2704 2..... 662015889us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.216858] gem_exec-2704 2..... 662015892us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.216944] gem_exec-2704 2..... 662015900us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0360, tail:0360}
<0> [679.217032] gem_exec-2704 2..... 662015919us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.217117] gem_exec-2704 2..... 662016505us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:78884ns, avg:19812ns }
<0> [679.217205] gem_exec-2704 2..... 662016509us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.217296] gem_exec-2704 2..... 662016511us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.217381] gem_exec-2704 2..... 662016517us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0360, tail:0360}
<0> [679.217470] gem_exec-2704 2..... 662016534us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.217555] gem_exec-2704 2..... 662017111us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:78884ns, avg:19812ns }
<0> [679.217642] gem_exec-2704 2..... 662017114us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.217733] gem_exec-2704 2..... 662017116us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.217818] gem_exec-2704 2..... 662017121us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0360, tail:0360}
<0> [679.217920] gem_exec-2704 2..... 662019645us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2910:10, current 8
<0> [679.218018] gem_exec-2704 2..... 662020609us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:32, current 30
<0> [679.218103] kworker/-60 0..... 662029379us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.218188] kworker/-60 0..... 662029956us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:38948ns, avg:7748ns }
<0> [679.218289] <idle>-0 0dNh3. 662030905us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2910:10, current 8
<0> [679.218387] kworker/-11 0..... 662030925us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:22, current 22
<0> [679.218487] <idle>-0 0dNh3. 662031181us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:32, current 30
<0> [679.218585] kworker/-11 0..... 662031305us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2910:10, current 10
<0> [679.218684] kworker/-11 0..... 662031537us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:32, current 32
<0> [679.218777] kworker/-60 0d..2. 662031879us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0003
<0> [679.218870] kworker/-60 0d..2. 662031980us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0003
<0> [679.218963] kworker/-60 0d..2. 662032184us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0003
<0> [679.219062] gem_exec-2704 2..... 662032691us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:24, current 22
<0> [679.219162] gem_exec-2704 2D..1. 662032697us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:24, current 22
<0> [679.219260] gem_exec-2704 2..... 662033709us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:30, current 28
<0> [679.219359] gem_exec-2704 2D..1. 662033716us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:30, current 28
<0> [679.219457] kworker/-11 0..... 662033942us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:28, current 30
<0> [679.219556] kworker/-11 0..... 662033952us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:30, current 30
<0> [679.219641] gem_exec-2704 2..... 662034166us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 active
<0> [679.219735] gem_exec-2704 2..... 662034170us : intel_timeline_pin: 0000:03:00.0 timeline:2905 using HWSP offset:feb8c000
<0> [679.219820] gem_exec-2704 2..... 662034178us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 pin ring:{start:feb00000, head:0240, tail:0240}
<0> [679.219922] gem_exec-2704 2..... 662036698us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2905:10, current 8
<0> [679.220021] gem_exec-2704 2..... 662037667us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:32, current 30
<0> [679.220112] gem_exec-2704 2..... 662037792us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2905:a
<0> [679.220196] kworker/-60 0..... 662046324us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290f unpin
<0> [679.220282] kworker/-60 0..... 662046920us : __intel_context_retire: 0000:03:00.0 ccs0: context:290f retire runtime: { total:178984ns, avg:35620ns }
<0> [679.220370] kworker/-60 0..... 662046930us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.220455] kworker/-60 0.l... 662047542us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:93665364ns, avg:11722724ns }
<0> [679.220544] kworker/-60 0.l... 662047551us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290e unpin
<0> [679.220644] kworker/-60 0dlh2. 662047882us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2905:10, current 8
<0> [679.220742] kworker/-60 0dlh2. 662048016us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:32, current 30
<0> [679.220828] kworker/-11 0..... 662048161us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.220924] kworker/-11 0..... 662048174us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:24, current 24
<0> [679.221022] kworker/-11 0..... 662048187us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:32, current 32
<0> [679.221121] kworker/-11 0..... 662048203us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2905:10, current 10
<0> [679.221214] kworker/-2631 5d..2. 662048364us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480003
<0> [679.221300] kworker/-60 0.l... 662048444us : __intel_context_retire: 0000:03:00.0 ccs0: context:290e retire runtime: { total:14503840ns, avg:8325408ns }
<0> [679.221396] kworker/-2631 5d..2. 662048459us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480003
<0> [679.221483] kworker/-11 0..... 662048459us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [679.221573] kworker/-2631 5d..2. 662048607us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00080003
<0> [679.221672] gem_exec-2704 2.l... 662049317us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:26, current 24
<0> [679.221769] gem_exec-2704 2Dl.1. 662049324us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:26, current 24
<0> [679.221854] gem_exec-2704 2..... 662049546us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.221946] gem_exec-2704 2..... 662049549us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.222032] gem_exec-2704 2..... 662049566us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.222115] gem_exec-2704 2..... 662049591us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.222201] gem_exec-2704 2..... 662049592us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:07e0, tail:07e0}
<0> [679.222303] gem_exec-2704 2..... 662050438us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:30, current 28
<0> [679.222400] gem_exec-2704 2D..1. 662050469us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:30, current 28
<0> [679.222492] gem_exec-2704 2..... 662050494us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2906:1e
<0> [679.222589] kworker/-11 0..... 662050652us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:30, current 30
<0> [679.222675] gem_exec-2704 2..... 662050944us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.222767] gem_exec-2704 2..... 662050948us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.222853] gem_exec-2704 2..... 662050956us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:0240, tail:0240}
<0> [679.222955] gem_exec-2704 2..... 662053542us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2907:10, current 8
<0> [679.223053] gem_exec-2704 2..... 662054531us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:32, current 30
<0> [679.223144] gem_exec-2704 2..... 662054673us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2907:a
<0> [679.223241] <idle>-0 0dNh3. 662064850us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2907:10, current 8
<0> [679.223339] kworker/-11 0..... 662064870us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:26, current 26
<0> [679.223438] <idle>-0 0dNh3. 662065099us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:32, current 30
<0> [679.223537] kworker/-11 0..... 662065130us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2907:10, current 10
<0> [679.223635] kworker/-11 0..... 662065255us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:32, current 32
<0> [679.223729] kworker/-60 0d..2. 662065606us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00080403
<0> [679.223822] kworker/-60 0d..2. 662065700us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00080403
<0> [679.223916] kworker/-60 0d..2. 662065887us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00080403
<0> [679.224002] kworker/-2631 5..... 662066304us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.224101] gem_exec-2704 2..... 662066436us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:28, current 26
<0> [679.224199] gem_exec-2704 2D..1. 662066443us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:28, current 26
<0> [679.224284] gem_exec-2704 2..... 662066617us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.224376] gem_exec-2704 2..... 662066621us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.224462] gem_exec-2704 2..... 662066637us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.224545] gem_exec-2704 2..... 662066663us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.224631] gem_exec-2704 2..... 662066665us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:07e0, tail:07e0}
<0> [679.224720] kworker/-2631 5..... 662066899us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91817336ns, avg:53685060ns }
<0> [679.224808] kworker/-2631 5..... 662066911us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.224907] gem_exec-2704 2..... 662067523us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:30, current 28
<0> [679.224992] kworker/-2631 5..... 662067530us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:98696ns, avg:19812ns }
<0> [679.225093] gem_exec-2704 2D..1. 662067553us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:30, current 28
<0> [679.225185] gem_exec-2704 2..... 662067578us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2908:1e
<0> [679.225282] kworker/-11 0..... 662067727us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:30, current 30
<0> [679.225368] gem_exec-2704 2..... 662068016us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.225461] gem_exec-2704 2..... 662068020us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.225546] gem_exec-2704 2..... 662068026us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:0240, tail:0240}
<0> [679.225648] gem_exec-2704 2..... 662070566us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2909:10, current 8
<0> [679.225746] gem_exec-2704 2..... 662071556us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:32, current 30
<0> [679.225837] gem_exec-2704 2..... 662071696us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2909:a
<0> [679.225934] <idle>-0 0dNh3. 662081873us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2909:10, current 8
<0> [679.226032] kworker/-11 0..... 662081893us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:28, current 28
<0> [679.226130] <idle>-0 0dNh3. 662082122us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:32, current 30
<0> [679.226228] kworker/-11 0..... 662082146us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2909:10, current 10
<0> [679.226326] kworker/-11 0..... 662082337us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:32, current 32
<0> [679.226419] kworker/-60 0d..2. 662082672us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00081403
<0> [679.226512] kworker/-60 0d..2. 662082771us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00081403
<0> [679.226606] kworker/-60 0d..2. 662082966us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00081403
<0> [679.226692] kworker/-2631 5..... 662083304us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2905 unpin
<0> [679.226791] gem_exec-2704 2..... 662083545us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:30, current 28
<0> [679.226890] gem_exec-2704 2D..1. 662083552us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:30, current 28
<0> [679.226975] gem_exec-2704 2..... 662083730us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.227067] gem_exec-2704 2..... 662083733us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.227154] gem_exec-2704 2..... 662083750us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.227237] gem_exec-2704 2..... 662083776us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.227323] gem_exec-2704 2..... 662083777us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:07e0, tail:07e0}
<0> [679.227411] gem_exec-2704 2..... 662083809us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.227496] kworker/-2631 5..... 662083967us : __intel_context_retire: 0000:03:00.0 bcs0: context:2905 retire runtime: { total:33436ns, avg:6916ns }
<0> [679.227583] kworker/-2631 5..... 662083978us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2904 unpin
<0> [679.227669] gem_exec-2704 2.l... 662084916us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:93665364ns, avg:11722724ns }
<0> [679.227757] gem_exec-2704 2.l... 662084920us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.227849] gem_exec-2704 2.l... 662084923us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.227936] gem_exec-2704 2.l... 662084928us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:07e0, tail:07e0}
<0> [679.228024] kworker/-2631 5..... 662085014us : __intel_context_retire: 0000:03:00.0 bcs0: context:2904 retire runtime: { total:93918292ns, avg:11776752ns }
<0> [679.228114] kworker/-69 5..... 662085027us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [679.228210] gem_exec-2704 2..... 662085800us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:30, current 28
<0> [679.228308] gem_exec-2704 2D..1. 662085806us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:30, current 28
<0> [679.228399] gem_exec-2704 2..... 662085831us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290a:1e
<0> [679.228495] kworker/-11 0..... 662085997us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:30, current 30
<0> [679.228581] gem_exec-2704 2..... 662086290us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.228672] gem_exec-2704 2..... 662086294us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.228758] gem_exec-2704 2..... 662086301us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:0240, tail:0240}
<0> [679.228846] gem_exec-2704 2..... 662086319us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.228931] gem_exec-2704 2..... 662086895us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:32032ns, avg:8164ns }
<0> [679.229018] gem_exec-2704 2..... 662086899us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.229110] gem_exec-2704 2..... 662086901us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.229195] gem_exec-2704 2..... 662086906us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:0240, tail:0240}
<0> [679.229284] gem_exec-2704 2..... 662086922us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.229369] gem_exec-2704 2..... 662087514us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:32032ns, avg:8164ns }
<0> [679.229456] gem_exec-2704 2..... 662087518us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.229549] gem_exec-2704 2..... 662087520us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.229634] gem_exec-2704 2..... 662087525us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:0240, tail:0240}
<0> [679.229737] gem_exec-2704 2..... 662090030us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290b:10, current 8
<0> [679.229836] gem_exec-2704 2..... 662090999us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:32, current 30
<0> [679.229927] gem_exec-2704 2..... 662091120us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290b:a
<0> [679.230011] kworker/-2631 5..... 662099381us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.230095] kworker/-2631 5..... 662099960us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:40144ns, avg:7956ns }
<0> [679.230182] kworker/-2631 5..... 662100446us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.230267] kworker/-2631 5..... 662101030us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92386216ns, avg:7764276ns }
<0> [679.230356] kworker/-69 5..... 662101046us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.230452] <idle>-0 0dNh3. 662101265us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290b:10, current 8
<0> [679.230550] kworker/-11 0..... 662101285us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:30, current 30
<0> [679.230649] <idle>-0 0dNh3. 662101499us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:32, current 30
<0> [679.230747] kworker/-11 0..... 662101522us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290b:10, current 10
<0> [679.230846] kworker/-11 0..... 662101706us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:32, current 32
<0> [679.230939] kworker/-2631 5d..2. 662102038us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1001
<0> [679.231032] kworker/-2631 5d..2. 662102133us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1001
<0> [679.231125] kworker/-2631 5d..2. 662102329us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 000c1001
<0> [679.231224] gem_exec-2704 2..... 662102852us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:32, current 30
<0> [679.231322] gem_exec-2704 2D..1. 662102859us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:32, current 30
<0> [679.231407] gem_exec-2704 2..... 662103034us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e active
<0> [679.231499] gem_exec-2704 2..... 662103038us : intel_timeline_pin: 0000:03:00.0 timeline:290e using HWSP offset:feb83000
<0> [679.231585] gem_exec-2704 2..... 662103055us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [679.231669] gem_exec-2704 2..... 662103089us : __engine_unpark: 0000:03:00.0 ccs0: context:1d98 reset { seqno:f, *hwsp:f, ring:c70 }
<0> [679.231755] gem_exec-2704 2..... 662103090us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290e pin ring:{start:fe970000, head:0af0, tail:0af0}
<0> [679.231857] gem_exec-2704 2..... 662103933us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:22, current 20
<0> [679.231956] gem_exec-2704 2D..1. 662103939us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:22, current 20
<0> [679.232047] gem_exec-2704 2..... 662103965us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290e:16
<0> [679.232144] kworker/-11 0..... 662104146us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:22, current 22
<0> [679.232229] gem_exec-2704 2..... 662104389us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f active
<0> [679.232321] gem_exec-2704 2..... 662104393us : intel_timeline_pin: 0000:03:00.0 timeline:290f using HWSP offset:feb82000
<0> [679.232406] gem_exec-2704 2..... 662104399us : __intel_context_do_pin_ww: 0000:03:00.0 ccs0: context:290f pin ring:{start:fe8d0000, head:0578, tail:0578}
<0> [679.232508] gem_exec-2704 2..... 662106904us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290f:12, current 10
<0> [679.232606] gem_exec-2704 2..... 662107866us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:24, current 22
<0> [679.232697] gem_exec-2704 2..... 662107989us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:290f:c
<0> [679.232780] kworker/-60 0..... 662117498us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.232879] kworker/-60 0d.h2. 662118094us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290f:12, current 10
<0> [679.232964] kworker/-60 0..... 662118116us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:96439044ns, avg:9245704ns }
<0> [679.233052] kworker/-60 0..... 662118128us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.233151] kworker/-60 0d.h2. 662118293us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:24, current 22
<0> [679.233244] kworker/-2631 5d..2. 662118999us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1001
<0> [679.233330] kworker/-60 0..... 662119072us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:40924ns, avg:8320ns }
<0> [679.233431] kworker/-11 0..... 662119090us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:32, current 32
<0> [679.233530] kworker/-11 0..... 662119108us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:24, current 24
<0> [679.233628] kworker/-11 0..... 662119113us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290f:12, current 12
<0> [679.233721] kworker/-2631 5d..2. 662119132us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1001
<0> [679.233808] kworker/-11 0..... 662119284us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.233898] kworker/-2631 5d..2. 662119364us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0001
<0> [679.233996] gem_exec-2704 2..... 662119937us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:26, current 24
<0> [679.234094] gem_exec-2704 2D..1. 662119944us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:26, current 24
<0> [679.234179] gem_exec-2704 2..... 662120135us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 active
<0> [679.234270] gem_exec-2704 2..... 662120140us : intel_timeline_pin: 0000:03:00.0 timeline:2903 using HWSP offset:feb8e000
<0> [679.234355] gem_exec-2704 2..... 662120158us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2903 pin ring:{start:feb50000, head:0d80, tail:0d80}
<0> [679.234457] gem_exec-2704 2..... 662121001us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:34, current 32
<0> [679.234555] gem_exec-2704 2D..1. 662121007us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:34, current 32
<0> [679.234646] gem_exec-2704 2..... 662121034us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2903:22
<0> [679.234743] kworker/-11 0..... 662121125us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:34, current 34
<0> [679.234828] gem_exec-2704 2..... 662121456us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 active
<0> [679.234919] gem_exec-2704 2..... 662121459us : intel_timeline_pin: 0000:03:00.0 timeline:2910 using HWSP offset:feb81000
<0> [679.235004] gem_exec-2704 2..... 662121467us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:2910 pin ring:{start:fe8a0000, head:0438, tail:0438}
<0> [679.235106] gem_exec-2704 2..... 662123967us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2910:12, current 10
<0> [679.235204] gem_exec-2704 2..... 662124931us : __i915_request_commit: 0000:03:00.0 rcs0: fence 2903:36, current 34
<0> [679.235294] gem_exec-2704 2..... 662125068us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2910:c
<0> [679.235391] <idle>-0 0dNh3. 662135252us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2910:12, current 10
<0> [679.235489] kworker/-11 0..... 662135272us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:26, current 26
<0> [679.235588] <idle>-0 0dNh3. 662135669us : __i915_request_submit: 0000:03:00.0 rcs0: fence 2903:36, current 34
<0> [679.235686] kworker/-11 0..... 662135703us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2910:12, current 12
<0> [679.235784] kworker/-11 0..... 662136013us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 2903:36, current 36
<0> [679.235877] kworker/-2631 5d..2. 662136375us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0001
<0> [679.235970] kworker/-2631 5d..2. 662136472us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0001
<0> [679.236055] kworker/-2622 4..... 662136485us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.236148] kworker/-2631 5d..2. 662136661us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c0001
<0> [679.236234] kworker/-2622 4..... 662137070us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:93708836ns, avg:10262824ns }
<0> [679.236322] kworker/-2622 4..... 662137082us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.236421] gem_exec-2704 2..... 662137174us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:28, current 26
<0> [679.236519] gem_exec-2704 2D..1. 662137180us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:28, current 26
<0> [679.236604] gem_exec-2704 2..... 662137357us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 active
<0> [679.236695] gem_exec-2704 2..... 662137360us : intel_timeline_pin: 0000:03:00.0 timeline:2904 using HWSP offset:feb8d000
<0> [679.236781] gem_exec-2704 2..... 662137378us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [679.236864] gem_exec-2704 2..... 662137404us : __engine_unpark: 0000:03:00.0 bcs0: context:1d93 reset { seqno:2c, *hwsp:2c, ring:c80 }
<0> [679.236950] gem_exec-2704 2..... 662137406us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2904 pin ring:{start:feb20000, head:0900, tail:0900}
<0> [679.237038] kworker/-2622 4..... 662137640us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:39728ns, avg:8112ns }
<0> [679.237127] kworker/-2388 4..... 662137654us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.237223] gem_exec-2704 2..... 662138336us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:34, current 32
<0> [679.237321] gem_exec-2704 2D..1. 662138342us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:34, current 32
<0> [679.237413] gem_exec-2704 2..... 662138369us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2904:22
<0> [679.237510] kworker/-11 0..... 662138452us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2904:34, current 34
<0> [679.237595] gem_exec-2704 2..... 662138829us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 active
<0> [679.237686] gem_exec-2704 2..... 662138833us : intel_timeline_pin: 0000:03:00.0 timeline:2905 using HWSP offset:feb8c000
<0> [679.237771] gem_exec-2704 2..... 662138840us : __intel_context_do_pin_ww: 0000:03:00.0 bcs0: context:2905 pin ring:{start:feb00000, head:02d0, tail:02d0}
<0> [679.237874] gem_exec-2704 2..... 662141473us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2905:12, current 10
<0> [679.237972] gem_exec-2704 2..... 662142495us : __i915_request_commit: 0000:03:00.0 bcs0: fence 2904:36, current 34
<0> [679.238064] gem_exec-2704 2..... 662142619us : intel_rps_boost.part.0: 0000:03:00.0 boost fence:2905:c
<0> [679.238160] <idle>-0 0dNh3. 662152790us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2905:12, current 10
<0> [679.238258] kworker/-11 0..... 662152812us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:28, current 28
<0> [679.238357] <idle>-0 0dNh3. 662153063us : __i915_request_submit: 0000:03:00.0 bcs0: fence 2904:36, current 34
<0> [679.238455] kworker/-11 0..... 662153082us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 2905:12, current 12
<0> [679.238549] kworker/-2622 4d..2. 662153396us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480003
<0> [679.238642] kworker/-2622 4d..2. 662153491us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480003
<0> [679.238736] kworker/-2622 4d..2. 662153639us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00480003
<0> [679.238835] gem_exec-2704 2..... 662154294us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:30, current 28
<0> [679.238934] gem_exec-2704 2D..1. 662154300us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:30, current 28
<0> [679.239019] kworker/-2622 4..... 662154361us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.239104] gem_exec-2704 2..... 662154479us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.239196] gem_exec-2704 2..... 662154482us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.239282] gem_exec-2704 2..... 662154499us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [679.239365] gem_exec-2704 2..... 662154525us : __engine_unpark: 0000:03:00.0 vcs0: context:1d94 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.239452] gem_exec-2704 2..... 662154527us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:0900, tail:0900}
<0> [679.239539] gem_exec-2704 2..... 662154558us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.239624] kworker/-2622 4..... 662155233us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:95420312ns, avg:11942216ns }
<0> [679.239713] kworker/-2622 4..... 662155245us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290f unpin
<0> [679.239797] gem_exec-2704 2.l... 662155600us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92386216ns, avg:7764276ns }
<0> [679.239886] gem_exec-2704 2.l... 662155604us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 active
<0> [679.239978] gem_exec-2704 2.l... 662155607us : intel_timeline_pin: 0000:03:00.0 timeline:2906 using HWSP offset:feb8b000
<0> [679.240063] gem_exec-2704 2.l... 662155612us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2906 pin ring:{start:feae0000, head:0900, tail:0900}
<0> [679.240152] kworker/-2622 4..... 662155956us : __intel_context_retire: 0000:03:00.0 ccs0: context:290f retire runtime: { total:212628ns, avg:35360ns }
<0> [679.240241] kworker/-2388 4..... 662155969us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [679.240337] gem_exec-2704 2..... 662156514us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:34, current 32
<0> [679.240435] gem_exec-2704 2D..1. 662156544us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:34, current 32
<0> [679.240533] kworker/-11 0..... 662156698us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:34, current 34
<0> [679.240618] gem_exec-2704 2..... 662156994us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.240710] gem_exec-2704 2..... 662156998us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.240795] gem_exec-2704 2..... 662157005us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:02d0, tail:02d0}
<0> [679.240884] gem_exec-2704 2..... 662157023us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.240969] gem_exec-2704 2..... 662157556us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:40144ns, avg:7956ns }
<0> [679.241056] gem_exec-2704 2..... 662157560us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.241149] gem_exec-2704 2..... 662157563us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.241234] gem_exec-2704 2..... 662157568us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:02d0, tail:02d0}
<0> [679.241323] gem_exec-2704 2..... 662157585us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.241408] gem_exec-2704 2..... 662158107us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:40144ns, avg:7956ns }
<0> [679.241495] gem_exec-2704 2..... 662158111us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 active
<0> [679.241588] gem_exec-2704 2..... 662158113us : intel_timeline_pin: 0000:03:00.0 timeline:2907 using HWSP offset:feb8a000
<0> [679.241673] gem_exec-2704 2..... 662158118us : __intel_context_do_pin_ww: 0000:03:00.0 vcs0: context:2907 pin ring:{start:feac0000, head:02d0, tail:02d0}
<0> [679.241775] gem_exec-2704 2..... 662160730us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2907:12, current 10
<0> [679.241874] gem_exec-2704 2..... 662161742us : __i915_request_commit: 0000:03:00.0 vcs0: fence 2906:36, current 34
<0> [679.241959] kworker/-2622 4..... 662170534us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2903 unpin
<0> [679.242044] kworker/-2622 4..... 662171058us : __intel_context_retire: 0000:03:00.0 rcs0: context:2903 retire runtime: { total:91873444ns, avg:46981428ns }
<0> [679.242132] kworker/-2622 4..... 662171069us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:2910 unpin
<0> [679.242217] kworker/-2622 4..... 662171600us : __intel_context_retire: 0000:03:00.0 rcs0: context:2910 retire runtime: { total:118404ns, avg:19812ns }
<0> [679.242318] <idle>-0 0dNh3. 662172052us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2907:12, current 10
<0> [679.242416] kworker/-11 0..... 662172090us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:30, current 30
<0> [679.242516] <idle>-0 0dNh3. 662172355us : __i915_request_submit: 0000:03:00.0 vcs0: fence 2906:36, current 34
<0> [679.242614] kworker/-11 0..... 662172374us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2907:12, current 12
<0> [679.242712] kworker/-11 0..... 662172577us : i915_request_retire.part.0: 0000:03:00.0 vcs0: fence 2906:36, current 36
<0> [679.242805] kworker/-2622 4d..2. 662172907us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.242898] kworker/-2622 4d..2. 662173002us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.242991] kworker/-2622 4d..2. 662173252us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00400403
<0> [679.243090] gem_exec-2704 2..... 662173729us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:32, current 30
<0> [679.243188] gem_exec-2704 2D..1. 662173735us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:32, current 30
<0> [679.243273] gem_exec-2704 2..... 662173910us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 active
<0> [679.243364] gem_exec-2704 2..... 662173913us : intel_timeline_pin: 0000:03:00.0 timeline:2908 using HWSP offset:feb89000
<0> [679.243450] gem_exec-2704 2..... 662173929us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [679.243533] gem_exec-2704 2..... 662173953us : __engine_unpark: 0000:03:00.0 vcs1: context:1d95 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.243619] gem_exec-2704 2..... 662173955us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2908 pin ring:{start:feaa0000, head:0900, tail:0900}
<0> [679.243721] gem_exec-2704 2..... 662174867us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:34, current 32
<0> [679.243819] gem_exec-2704 2D..1. 662174896us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:34, current 32
<0> [679.243918] kworker/-11 0..... 662175044us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:34, current 34
<0> [679.244003] gem_exec-2704 2..... 662175338us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 active
<0> [679.244095] gem_exec-2704 2..... 662175342us : intel_timeline_pin: 0000:03:00.0 timeline:2909 using HWSP offset:feb88000
<0> [679.244180] gem_exec-2704 2..... 662175348us : __intel_context_do_pin_ww: 0000:03:00.0 vcs1: context:2909 pin ring:{start:fea80000, head:02d0, tail:02d0}
<0> [679.244282] gem_exec-2704 2..... 662177938us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2909:12, current 10
<0> [679.244380] gem_exec-2704 2..... 662178963us : __i915_request_commit: 0000:03:00.0 vcs1: fence 2908:36, current 34
<0> [679.244465] kworker/-2631 5..... 662188394us : __intel_context_do_unpin.part.0: 0000:03:00.0 bcs0: context:2905 unpin
<0> [679.244563] irq/9-ac-68 0d.h3. 662189208us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2909:12, current 10
<0> [679.244661] kworker/-11 0..... 662189296us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:32, current 32
<0> [679.244759] irq/9-ac-68 0d.h3. 662189464us : __i915_request_submit: 0000:03:00.0 vcs1: fence 2908:36, current 34
<0> [679.244852] kworker/-2622 1d..2. 662189977us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00401403
<0> [679.244950] kworker/-11 0..... 662190016us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2908:36, current 36
<0> [679.245049] kworker/-11 0..... 662190033us : i915_request_retire.part.0: 0000:03:00.0 vcs1: fence 2909:12, current 12
<0> [679.245142] kworker/-2622 1d..2. 662190098us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00401403
<0> [679.245228] kworker/-2631 5..... 662190154us : __intel_context_retire: 0000:03:00.0 bcs0: context:2905 retire runtime: { total:40352ns, avg:6916ns }
<0> [679.245323] kworker/-2622 1d..2. 662190317us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00401403
<0> [679.245422] gem_exec-2704 2..... 662190909us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:34, current 32
<0> [679.245519] gem_exec-2704 2D..1. 662190916us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:34, current 32
<0> [679.245604] gem_exec-2704 2..... 662191096us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a active
<0> [679.245696] gem_exec-2704 2..... 662191099us : intel_timeline_pin: 0000:03:00.0 timeline:290a using HWSP offset:feb87000
<0> [679.245782] gem_exec-2704 2..... 662191115us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [679.245865] gem_exec-2704 2..... 662191139us : __engine_unpark: 0000:03:00.0 vecs0: context:1d96 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.245951] gem_exec-2704 2..... 662191141us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290a pin ring:{start:fea60000, head:0900, tail:0900}
<0> [679.246053] gem_exec-2704 2..... 662192059us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:34, current 32
<0> [679.246151] gem_exec-2704 2D..1. 662192065us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:34, current 32
<0> [679.246249] kworker/-11 0..... 662192225us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:34, current 34
<0> [679.246335] gem_exec-2704 2..... 662192515us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b active
<0> [679.246427] gem_exec-2704 2..... 662192519us : intel_timeline_pin: 0000:03:00.0 timeline:290b using HWSP offset:feb86000
<0> [679.246513] gem_exec-2704 2..... 662192526us : __intel_context_do_pin_ww: 0000:03:00.0 vecs0: context:290b pin ring:{start:fea40000, head:02d0, tail:02d0}
<0> [679.246615] gem_exec-2704 2..... 662195160us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290b:12, current 10
<0> [679.246713] gem_exec-2704 2..... 662196140us : __i915_request_commit: 0000:03:00.0 vecs0: fence 290a:36, current 34
<0> [679.246811] <idle>-0 0dNh3. 662206410us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290b:12, current 10
<0> [679.246909] kworker/-11 0..... 662206432us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:34, current 34
<0> [679.247008] <idle>-0 0dNh3. 662206747us : __i915_request_submit: 0000:03:00.0 vecs0: fence 290a:36, current 34
<0> [679.247106] kworker/-11 0..... 662206766us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290b:12, current 12
<0> [679.247205] kworker/-11 0..... 662206970us : i915_request_retire.part.0: 0000:03:00.0 vecs0: fence 290a:36, current 36
<0> [679.247298] kworker/-2622 1d..2. 662207304us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00441403
<0> [679.247391] kworker/-2622 1d..2. 662207408us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00441403
<0> [679.247484] kworker/-2622 1d..2. 662207606us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 00441403
<0> [679.247584] gem_exec-2704 2..... 662208165us : __i915_request_commit: 0000:03:00.0 ccs0: fence 290e:36, current 34
<0> [679.247682] gem_exec-2704 2D..1. 662208172us : __i915_request_submit: 0000:03:00.0 ccs0: fence 290e:36, current 34
<0> [679.247767] kworker/-2631 5..... 662208333us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2906 unpin
<0> [679.247851] gem_exec-2704 2..... 662208347us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c active
<0> [679.247943] gem_exec-2704 2..... 662208351us : intel_timeline_pin: 0000:03:00.0 timeline:290c using HWSP offset:feb85000
<0> [679.248029] gem_exec-2704 2..... 662208367us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [679.248112] gem_exec-2704 2..... 662208393us : __engine_unpark: 0000:03:00.0 vecs1: context:1d97 reset { seqno:f, *hwsp:f, ring:448 }
<0> [679.248198] gem_exec-2704 2..... 662208395us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290c pin ring:{start:fea20000, head:0900, tail:0900}
<0> [679.248286] kworker/-2631 5..... 662208865us : __intel_context_retire: 0000:03:00.0 vcs0: context:2906 retire runtime: { total:92426048ns, avg:6798740ns }
<0> [679.248374] kworker/-2631 5..... 662208877us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs0: context:2907 unpin
<0> [679.248472] gem_exec-2704 2..... 662209281us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:34, current 32
<0> [679.248570] gem_exec-2704 2D..1. 662209288us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:34, current 32
<0> [679.248655] kworker/-2631 5..... 662209481us : __intel_context_retire: 0000:03:00.0 vcs0: context:2907 retire runtime: { total:47996ns, avg:7904ns }
<0> [679.248743] kworker/-69 5..... 662209499us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [679.248839] kworker/-11 0..... 662209567us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:34, current 34
<0> [679.248924] gem_exec-2704 2..... 662209717us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d active
<0> [679.249016] gem_exec-2704 2..... 662209721us : intel_timeline_pin: 0000:03:00.0 timeline:290d using HWSP offset:feb84000
<0> [679.249101] gem_exec-2704 2..... 662209728us : __intel_context_do_pin_ww: 0000:03:00.0 vecs1: context:290d pin ring:{start:fea00000, head:02d0, tail:02d0}
<0> [679.249203] gem_exec-2704 2..... 662212338us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290d:12, current 10
<0> [679.249302] gem_exec-2704 2..... 662213329us : __i915_request_commit: 0000:03:00.0 vecs1: fence 290c:36, current 34
<0> [679.249399] <idle>-0 0dNh3. 662223613us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290d:12, current 10
<0> [679.249497] kworker/-11 0..... 662223633us : i915_request_retire.part.0: 0000:03:00.0 ccs0: fence 290e:36, current 36
<0> [679.249595] <idle>-0 0dNh3. 662223873us : __i915_request_submit: 0000:03:00.0 vecs1: fence 290c:36, current 34
<0> [679.249693] kworker/-11 0..... 662223902us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290d:12, current 12
<0> [679.249791] kworker/-11 0..... 662224089us : i915_request_retire.part.0: 0000:03:00.0 vecs1: fence 290c:36, current 36
<0> [679.249884] kworker/-60 0d..2. 662224451us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.249969] kworker/-2631 5..... 662224532us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2908 unpin
<0> [679.250061] kworker/-60 0d..2. 662224715us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.250154] kworker/-60 0d..2. 662224969us : intel_gt_invalidate_tlb_full.part.0: 0000:03:00.0 invalidated engines 004c1003
<0> [679.250240] kworker/-2631 5..... 662225200us : __intel_context_retire: 0000:03:00.0 vcs1: context:2908 retire runtime: { total:96476744ns, avg:8094684ns }
<0> [679.250328] kworker/-2631 5..... 662225218us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290a unpin
<0> [679.250347] gem_exec-2704 2...1. 662225389us : tracing_mark_write: gem_exec_schedule: finished subtest u-semaphore-noskip, SUCCESS
<0> [679.250432] kworker/-2631 5..... 662225784us : __intel_context_retire: 0000:03:00.0 vecs0: context:290a retire runtime: { total:93748980ns, avg:8984976ns }
<0> [679.250520] kworker/-2631 5..... 662225795us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290c unpin
<0> [679.250604] kworker/-2631 5..... 662226421us : __intel_context_retire: 0000:03:00.0 vecs1: context:290c retire runtime: { total:95460716ns, avg:10454496ns }
<0> [679.250692] kworker/-2631 5..... 662226432us : __intel_context_do_unpin.part.0: 0000:03:00.0 ccs0: context:290e unpin
<0> [679.250777] kworker/-2631 5.l... 662227001us : __intel_context_retire: 0000:03:00.0 ccs0: context:290e retire runtime: { total:108683692ns, avg:19057220ns }
<0> [679.250864] kworker/-2631 5.l... 662227013us : __intel_context_do_unpin.part.0: 0000:03:00.0 vcs1: context:2909 unpin
<0> [679.250950] kworker/-69 5..... 662227154us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [679.251031] kworker/-2631 5.l... 662227611us : __intel_context_retire: 0000:03:00.0 vcs1: context:2909 retire runtime: { total:50752ns, avg:8476ns }
<0> [679.251118] kworker/-2631 5.l... 662227624us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs0: context:290b unpin
<0> [679.251203] kworker/-69 5..... 662228153us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [679.251285] kworker/-2631 5.l... 662228218us : __intel_context_retire: 0000:03:00.0 vecs0: context:290b retire runtime: { total:47424ns, avg:8060ns }
<0> [679.251371] kworker/-2631 5.l... 662228229us : __intel_context_do_unpin.part.0: 0000:03:00.0 vecs1: context:290d unpin
<0> [679.251456] kworker/-2631 5.l... 662228794us : __intel_context_retire: 0000:03:00.0 vecs1: context:290d retire runtime: { total:48880ns, avg:8008ns }
<0> [679.251544] kworker/-69 5..... 662228818us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [679.251626] kworker/-69 5..... 662228828us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [679.251726] kworker/-60 0..... 662241384us : destroyed_worker_func: guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id))
<0> [679.251742] ---------------------------------
<4> [679.253948] ------------[ cut here ]------------
<2> [679.253952] kernel BUG at drivers/gpu/drm/i915/gt/uc/intel_guc_submission.c:3418!
<4> [679.253968] Oops: invalid opcode: 0000 [#1] SMP NOPTI
<4> [679.253974] CPU: 1 UID: 0 PID: 60 Comm: kworker/u24:2 Tainted: G S U W L 7.0.0-rc4-CI_DRM_18152-gbd57a29321bf+ #1 PREEMPT(lazy)
<4> [679.253981] Tainted: [S]=CPU_OUT_OF_SPEC, [U]=USER, [W]=WARN, [L]=SOFTLOCKUP
<4> [679.253984] Hardware name: Intel Corporation CoffeeLake Client Platform/CoffeeLake S UDIMM RVP, BIOS CNLSFWR1.R00.X220.B00.2103302221 03/30/2021
<4> [679.253990] Workqueue: events_unbound destroyed_worker_func [i915]
<4> [679.254212] RIP: 0010:destroyed_worker_func+0x66d/0x8a0 [i915]
<4> [679.254388] Code: 00 48 c7 c2 70 b2 ab a0 48 8d 3d 00 00 00 00 e8 39 0d 86 e0 bf 01 00 00 00 e8 bf b3 85 e0 31 f6 bf 09 00 00 00 e8 43 c3 64 e0 <0f> 0b 48 c7 c1 fe d0 ae a0 ba 08 02 00 00 48 c7 c6 b0 ba ab a0 48
<4> [679.254395] RSP: 0018:ffffc900002b7d88 EFLAGS: 00010246
<4> [679.254399] RAX: 0000000000000000 RBX: ffff8881c5dec028 RCX: 0000000000000000
<4> [679.254403] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [679.254406] RBP: ffffc900002b7df8 R08: 0000000000000000 R09: 0000000000000000
<4> [679.254409] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000001007
<4> [679.254412] R13: ffff8881c5decde8 R14: ffff88814235e520 R15: ffff8881c5decdd8
<4> [679.254416] FS: 0000000000000000(0000) GS:ffff8884d971b000(0000) knlGS:0000000000000000
<4> [679.254420] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [679.254423] CR2: 000064f9940f0ac0 CR3: 000000010f621006 CR4: 00000000003726f0
<4> [679.254427] Call Trace:
<4> [679.254429] <TASK>
<4> [679.254432] process_one_work+0x239/0x760
<4> [679.254440] worker_thread+0x200/0x3f0
<4> [679.254443] ? __pfx_worker_thread+0x10/0x10
<4> [679.254446] kthread+0x10d/0x150
<4> [679.254450] ? __pfx_kthread+0x10/0x10
<4> [679.254454] ret_from_fork+0x3d4/0x480
<4> [679.254458] ? __pfx_kthread+0x10/0x10
<4> [679.254461] ret_from_fork_asm+0x1a/0x30
<4> [679.254468] </TASK>
<4> [679.254469] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_intelhdmi snd_hda_codec_hdmi mei_lb mei_gsc prime_numbers drm_buddy ttm drm_display_helper cec rc_core i2c_algo_bit kunit intel_rapl_msr intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common intel_tcc_cooling x86_pkg_temp_thermal cmdlinepart intel_powerclamp ee1004 spi_nor coretemp mtd ppdev mei_hdcp mei_pxp intel_wmi_thunderbolt wmi_bmof kvm_intel kvm irqbypass ghash_clmulni_intel snd_intel_dspcfg aesni_intel snd_hda_codec rapl snd_hda_core intel_cstate snd_hwdep i2c_i801 snd_pcm spi_intel_pci i2c_mux e1000e video i2c_smbus spi_intel snd_timer snd soundcore parport_pc parport binfmt_misc intel_pmc_core pmt_telemetry pmt_discovery pmt_class intel_pmc_ssram_telemetry pinctrl_cannonlake wmi intel_vsec acpi_tad acpi_pad mei_me nls_iso8859_1 mei idma64 intel_pch_thermal input_leds serio_raw dm_multipath msr nvme_fabrics fuse efi_pstore nfnetlink autofs4 [last unloaded: i915]
<4> [679.254544] ---[ end trace 0000000000000000 ]---
<4> [679.553865] RIP: 0010:destroyed_worker_func+0x66d/0x8a0 [i915]
<4> [679.554198] Code: 00 48 c7 c2 70 b2 ab a0 48 8d 3d 00 00 00 00 e8 39 0d 86 e0 bf 01 00 00 00 e8 bf b3 85 e0 31 f6 bf 09 00 00 00 e8 43 c3 64 e0 <0f> 0b 48 c7 c1 fe d0 ae a0 ba 08 02 00 00 48 c7 c6 b0 ba ab a0 48
<4> [679.554206] RSP: 0018:ffffc900002b7d88 EFLAGS: 00010246
<4> [679.554210] RAX: 0000000000000000 RBX: ffff8881c5dec028 RCX: 0000000000000000
<4> [679.554214] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [679.554217] RBP: ffffc900002b7df8 R08: 0000000000000000 R09: 0000000000000000
<4> [679.554220] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000001007
<4> [679.554224] R13: ffff8881c5decde8 R14: ffff88814235e520 R15: ffff8881c5decdd8
<4> [679.554227] FS: 0000000000000000(0000) GS:ffff8884d971b000(0000) knlGS:0000000000000000
<4> [679.554231] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [679.554235] CR2: 000064f9940f0ac0 CR3: 000000000344c001 CR4: 00000000003726f0
<7> [679.864415] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
|