Result: 405 Warning(s)
git-log-oneline i915_display_info4 igt_runner4 runtimes4 results4.json guc_logs4.tar i915_display_info_post_exec4 boot4 dmesg4
pstore-001-dmesg4| Detail | Value |
|---|---|
| Duration | unknown |
| Hostname |
shard-dg2-10 |
| Igt-Version |
IGT-Version: 2.4-g57da2055d (x86_64) (Linux: 7.0.0-CI_DRM_18326-gc8bad7becc00+ x86_64) |
| Out |
Using IGT_SRANDOM=1776162761 for randomisation
Opened device: /dev/dri/card0
Runtime PM support: 1
PC8 residency support: 0
DMC: fw loaded: yes
i915_runtime_pm_status:
GPU idle: no
IRQs disabled: no
Usage count: 5
PCI device power state: D0 [0]
ref_tracker: intel_runtime_pm@ffff888147159828 has 1/5 users at
track_intel_runtime_pm_wakeref+0x90/0xb0 [i915]
__intel_runtime_pm_get+0x60/0x130 [i915]
i915_display_rpm_get_raw+0x17/0x30 [i915]
intel_display_rpm_get_raw+0x20/0x30 [i915]
__intel_display_power_put_async+0x3a/0x1c0 [i915]
intel_digital_port_connected_locked+0xea/0x130 [i915]
intel_dp_aux_xfer+0xd3/0x9b0 [i915]
intel_dp_aux_transfer+0x1ea/0x330 [i915]
drm_dp_dpcd_access+0xc3/0x160 [drm_display_helper]
drm_dp_dpcd_write+0xc4/0x120 [drm_display_helper]
intel_dp_set_power+0x79/0x190 [i915]
intel_ddi_post_disable+0x2fd/0x700 [i915]
intel_encoders_post_disable+0x85/0xb0 [i915]
hsw_crtc_disable+0x68/0x150 [i915]
intel_old_crtc_state_disables+0x110/0x230 [i915]
intel_atomic_commit_tail+0xcbe/0x20c0 [i915]
ref_tracker: intel_runtime_pm@ffff888147159828 has 2/5 users at
track_intel_runtime_pm_wakeref+0x90/0xb0 [i915]
__intel_runtime_pm_get+0x60/0x130 [i915]
intel_runtime_pm_get+0x13/0x20 [i915]
__intel_wakeref_get_first+0x2a/0xe0 [i915]
guc_context_pin+0xae/0xb0 [i915]
__intel_context_do_pin_ww+0x780/0xe90 [i915]
eb_pin_engine+0x383/0x3b0 [i915]
i915_gem_do_execbuffer+0xd97/0x36e0 [i915]
i915_gem_execbuffer2_ioctl+0x169/0x320 [i915]
drm_ioctl_kernel+0xb3/0x120
drm_ioctl+0x2d4/0x5a0
__x64_sys_ioctl+0xa5/0x100
x64_sys_call+0x1250/0x26e0
do_syscall_64+0xdd/0x1440
entry_SYSCALL_64_after_hwframe+0x76/0x7e
ref_tracker: intel_runtime_pm@ffff888147159828 has 1/5 users at
track_intel_runtime_pm_wakeref+0x90/0xb0 [i915]
__intel_runtime_pm_get+0x60/0x130 [i915]
i915_display_rpm_get+0x1a/0x30 [i915]
intel_display_rpm_get+0x1f/0x30 [i915]
intel_display_power_get+0x22/0x70 [i915]
__gt_unpark+0x5b/0x200 [i915]
__intel_wakeref_get_first+0x63/0xe0 [i915]
__i915_vma_active+0xc2/0xd0 [i915]
i915_active_acquire+0xab/0xd0 [i915]
i915_vma_pin_ww+0x2e5/0x1390 [i915]
i915_vma_pin+0x155/0x1f0 [i915]
gen8_ppgtt_create+0x3c9/0x9b0 [i915]
i915_ppgtt_create+0x55/0xf0 [i915]
i915_gem_create_context+0x418/0x950 [i915]
i915_gem_context_lookup.part.0+0x9d/0x1a0 [i915]
i915_gem_context_lookup+0x10e/0x220 [i915]
ref_tracker: intel_runtime_pm@ffff888147159828 has 1/5 users at
track_intel_runtime_pm_wakeref+0x90/0xb0 [i915]
__intel_runtime_pm_get+0x60/0x130 [i915]
intel_runtime_pm_get+0x13/0x20 [i915]
__intel_wakeref_get_first+0x2a/0xe0 [i915]
__i915_vma_active+0xc2/0xd0 [i915]
i915_active_acquire+0xab/0xd0 [i915]
i915_vma_pin_ww+0x2e5/0x1390 [i915]
i915_vma_pin+0x155/0x1f0 [i915]
gen8_ppgtt_create+0x3c9/0x9b0 [i915]
i915_ppgtt_create+0x55/0xf0 [i915]
i915_gem_create_context+0x418/0x950 [i915]
i915_gem_context_lookup.part.0+0x9d/0x1a0 [i915]
i915_gem_context_lookup+0x10e/0x220 [i915]
i915_gem_do_execbuffer+0x5ab/0x36e0 [i915]
i915_gem_execbuffer2_ioctl+0x169/0x320 [i915]
drm_ioctl_kernel+0xb3/0x120
Starting subtest: system-suspend-execbuf
[cmd] rtcwake: assuming RTC uses UTC ...
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Apr 14 10:32:59 2026
Subtest system-suspend-execbuf: SUCCESS (44.108s)
runner: This test was killed due to a kernel taint (0x4244).
runner: This test was killed due to exceeding disk usage limit. (Used 16853468 bytes, limit 10485760)
This test caused an abort condition: Lockdep not active
/proc/lockdep_stats contents:
lock-classes: 2386 [max: 8192]
dynamic-keys: 323
direct dependencies: 26362 [max: 524288]
indirect dependencies: 273343
all direct dependencies: 583534
dependency chains: 40037 [max: 524288]
dependency chain hlocks used: 179551 [max: 2621440]
dependency chain hlocks lost: 0
in-hardirq chains: 299
in-softirq chains: 891
in-process chains: 38847
stack-trace entries: 284291 [max: 524288]
number of stack traces: 13073
number of stack hash chains: 9029
combined max dependencies: 1805790208
hardirq-safe locks: 118
hardirq-unsafe locks: 1407
softirq-safe locks: 261
softirq-unsafe locks: 1302
irq-safe locks: 278
irq-unsafe locks: 1407
hardirq-read-safe locks: 5
hardirq-read-unsafe locks: 471
softirq-read-safe locks: 9
softirq-read-unsafe locks: 468
irq-read-safe locks: 9
irq-read-unsafe locks: 471
uncategorized locks: 392
unused locks: 1
max locking depth: 18
max bfs queue depth: 496
max lock class index: 2385
debug_locks: 0
zapped classes: 2
zapped lock chains: 172
large chain blocks: 1
|
| Err |
Starting subtest: system-suspend-execbuf Subtest system-suspend-execbuf: SUCCESS (44.108s) |
| Dmesg |
<0> [612.277299] i915_pm_-1680 1...1. 499322139us : tracing_mark_write: i915_pm_rpm: starting subtest system-suspend-execbuf
<0> [612.277991] i915_pm_-1680 1..... 499418484us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.278776] kworker/-12 0..... 499612101us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.279459] rtcwake-1684 1..... 499934064us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.280136] rtcwake-1684 1..... 499948331us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.280905] rtcwake-1684 1..... 499948368us : __gt_unpark: 0000:03:00.0
<0> [612.281569] rtcwake-1684 1..... 499948713us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.282253] rtcwake-1684 1..... 499948792us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:853, current 852
<0> [612.283037] rtcwake-1684 1D..1. 499948812us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:853, current 852
<0> [612.283729] kworker/-9 0..... 499949169us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:853, current 853
<0> [612.284408] kworker/-9 0..... 499949203us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.285194] kworker/-9 0..... 499949274us : __gt_park: 0000:03:00.0
<0> [612.285859] rtcwake-1684 1.l... 499950044us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.286528] rtcwake-1684 1.l... 499950077us : __gt_unpark: 0000:03:00.0
<0> [612.287284] rtcwake-1684 1.l... 499950206us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.287970] rtcwake-1684 1.l... 499950275us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:854, current 853
<0> [612.288653] rtcwake-1684 1Dl.1. 499950293us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:854, current 853
<0> [612.289431] kworker/-9 0..... 499950667us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:854, current 854
<0> [612.290088] kworker/-9 0..... 499950689us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.290741] kworker/-9 0..... 499950736us : __gt_park: 0000:03:00.0
<0> [612.291489] rtcwake-1684 1.l... 499952803us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.292149] rtcwake-1684 1.l... 499952827us : __gt_unpark: 0000:03:00.0
<0> [612.292805] rtcwake-1684 1.l... 499953098us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.293582] rtcwake-1684 1.l... 499953152us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:855, current 854
<0> [612.294261] rtcwake-1684 1Dl.1. 499953165us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:855, current 854
<0> [612.294934] kworker/-9 0..... 499953490us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:855, current 855
<0> [612.295690] kworker/-9 0..... 499953513us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.296347] kworker/-9 0..... 499953565us : __gt_park: 0000:03:00.0
<0> [612.296988] rtcwake-1684 1.l... 499954275us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.297723] rtcwake-1684 1.l... 499954299us : __gt_unpark: 0000:03:00.0
<0> [612.298363] rtcwake-1684 1.l... 499954398us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.299039] rtcwake-1684 1.l... 499954451us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:856, current 855
<0> [612.299822] rtcwake-1684 1Dl.1. 499954464us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:856, current 855
<0> [612.300496] kworker/-9 0..... 499954803us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:856, current 856
<0> [612.301163] kworker/-9 0..... 499954824us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.301933] kworker/-9 0..... 499954874us : __gt_park: 0000:03:00.0
<0> [612.302605] rtcwake-1684 1.l... 499955300us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.303289] rtcwake-1684 1.l... 499955325us : __gt_unpark: 0000:03:00.0
<0> [612.304081] rtcwake-1684 1.l... 499955424us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.304799] rtcwake-1684 1.l... 499955477us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:857, current 856
<0> [612.305512] rtcwake-1684 1Dl.1. 499955490us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:857, current 856
<0> [612.306319] rtcwake-1684 1.l... 499958163us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:858, current 857
<0> [612.307017] rtcwake-1684 1Dl.1. 499958174us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:858, current 857
<0> [612.307717] kworker/-9 0..... 499958497us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:857, current 858
<0> [612.308522] kworker/-9 0..... 499958500us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:858, current 858
<0> [612.309219] kworker/-9 0..... 499958516us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.309918] kworker/-9 0..... 499958554us : __gt_park: 0000:03:00.0
<0> [612.310718] rtcwake-1684 1.l... 499958997us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.311413] rtcwake-1684 1.l... 499959017us : __gt_unpark: 0000:03:00.0
<0> [612.312111] rtcwake-1684 1.l... 499959101us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.312921] rtcwake-1684 1.l... 499959144us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:859, current 858
<0> [612.313634] rtcwake-1684 1Dl.1. 499959155us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:859, current 858
<0> [612.314339] kworker/-9 0..... 499959456us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:859, current 859
<0> [612.315136] kworker/-9 0..... 499959474us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.315844] kworker/-9 0..... 499959512us : __gt_park: 0000:03:00.0
<0> [612.316539] rtcwake-1684 1.l... 499960076us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.317338] rtcwake-1684 1.l... 499960096us : __gt_unpark: 0000:03:00.0
<0> [612.318025] rtcwake-1684 1.l... 499960178us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.318748] rtcwake-1684 1.l... 499960221us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:860, current 859
<0> [612.319563] rtcwake-1684 1Dl.1. 499960232us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:860, current 859
<0> [612.320271] kworker/-9 0..... 499960552us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:860, current 860
<0> [612.320965] kworker/-9 0..... 499960568us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.321751] kworker/-9 0..... 499960618us : __gt_park: 0000:03:00.0
<0> [612.322430] rtcwake-1684 1.l... 499961015us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.323110] rtcwake-1684 1.l... 499961032us : __gt_unpark: 0000:03:00.0
<0> [612.323894] rtcwake-1684 1.l... 499961104us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.324597] rtcwake-1684 1.l... 499961141us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:861, current 860
<0> [612.325293] rtcwake-1684 1Dl.1. 499961150us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:861, current 860
<0> [612.326080] kworker/-9 0..... 499961452us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:861, current 861
<0> [612.326765] kworker/-9 0..... 499961466us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.327447] kworker/-9 0..... 499961500us : __gt_park: 0000:03:00.0
<0> [612.328223] rtcwake-1684 4..... 499962886us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.328908] rtcwake-1684 4..... 499962903us : __gt_unpark: 0000:03:00.0
<0> [612.329586] rtcwake-1684 4..... 499965058us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.330386] rtcwake-1684 4..... 499965102us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:862, current 861
<0> [612.331091] rtcwake-1684 4D..1. 499965112us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:862, current 861
<0> [612.331786] kworker/-9 0..... 499965299us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:862, current 862
<0> [612.332575] kworker/-9 0..... 499965312us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.333259] kworker/-9 0..... 499965344us : __gt_park: 0000:03:00.0
<0> [612.333939] rtcwake-1684 4..... 499973852us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.334716] rtcwake-1684 4..... 499973862us : __gt_unpark: 0000:03:00.0
<0> [612.335392] rtcwake-1684 4..... 499974108us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.336091] rtcwake-1684 4..... 499974200us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:863, current 862
<0> [612.336893] rtcwake-1684 4D..1. 499974206us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:863, current 862
<0> [612.337584] rtcwake-1684 4..... 499974363us : i915_gem_suspend: 0000:03:00.0
<0> [612.338277] kworker/-9 0..... 499975304us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:863, current 863
<0> [612.339072] kworker/-9 0..... 499975315us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.339760] kworker/-9 0..... 499975337us : __gt_park: 0000:03:00.0
<0> [612.340445] rtcwake-1684 4..... 499985887us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.341235] rtcwake-1684 4..... 499985897us : __gt_unpark: 0000:03:00.0
<0> [612.341911] rtcwake-1684 4..... 499986157us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.342619] rtcwake-1684 4..... 499986183us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:864, current 863
<0> [612.343417] rtcwake-1684 4D..1. 499986188us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:864, current 863
<0> [612.344113] kworker/-9 0..... 499986349us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:864, current 864
<0> [612.344803] kworker/-9 0..... 499986357us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.345585] kworker/-9 0..... 499986376us : __gt_park: 0000:03:00.0
<0> [612.346269] rtcwake-1684 4..... 499986536us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.346953] rtcwake-1684 4..... 499986545us : __gt_unpark: 0000:03:00.0
<0> [612.347726] rtcwake-1684 4..... 499986595us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.348435] rtcwake-1684 4..... 499986627us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:865, current 864
<0> [612.349136] rtcwake-1684 4D..1. 499986633us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:865, current 864
<0> [612.349933] kworker/-9 0..... 499986804us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:865, current 865
<0> [612.350621] kworker/-9 0..... 499986811us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.351310] kworker/-9 0..... 499986829us : __gt_park: 0000:03:00.0
<0> [612.352096] rtcwake-1684 4..... 499986987us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.352780] rtcwake-1684 4..... 499986996us : __gt_unpark: 0000:03:00.0
<0> [612.353453] rtcwake-1684 4..... 499987036us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.354254] rtcwake-1684 4..... 499987057us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:866, current 865
<0> [612.354963] rtcwake-1684 4D..1. 499987062us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:866, current 865
<0> [612.355676] kworker/-9 0..... 499987231us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:866, current 866
<0> [612.356472] kworker/-9 0..... 499987239us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.357175] kworker/-9 0..... 499987280us : __gt_park: 0000:03:00.0
<0> [612.357869] rtcwake-1684 4..... 499987512us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.358664] rtcwake-1684 4..... 499987521us : __gt_unpark: 0000:03:00.0
<0> [612.359357] rtcwake-1684 4..... 499987572us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.360079] rtcwake-1684 4..... 499987594us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:867, current 866
<0> [612.360893] rtcwake-1684 4D..1. 499987605us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:867, current 866
<0> [612.361603] kworker/-9 0..... 499987794us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:867, current 867
<0> [612.362310] kworker/-9 0..... 499987801us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.363107] kworker/-9 0..... 499987848us : __gt_park: 0000:03:00.0
<0> [612.363799] rtcwake-1684 4..... 499988040us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.364495] rtcwake-1684 4..... 499988050us : __gt_unpark: 0000:03:00.0
<0> [612.365284] rtcwake-1684 4..... 499988101us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.366003] rtcwake-1684 4..... 499988125us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:868, current 867
<0> [612.366719] rtcwake-1684 4D..1. 499988130us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:868, current 867
<0> [612.367527] kworker/-9 0..... 499988324us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:868, current 868
<0> [612.368231] kworker/-9 0..... 499988332us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.368928] kworker/-9 0..... 499988350us : __gt_park: 0000:03:00.0
<0> [612.369720] rtcwake-1684 4..... 499988527us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.370416] rtcwake-1684 4..... 499988537us : __gt_unpark: 0000:03:00.0
<0> [612.371105] rtcwake-1684 4..... 499988616us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.371919] rtcwake-1684 4..... 499988641us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:869, current 868
<0> [612.372633] rtcwake-1684 4D..1. 499988646us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:869, current 868
<0> [612.373341] kworker/-9 0..... 499988799us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:869, current 869
<0> [612.374135] kworker/-9 0..... 499988807us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.374834] kworker/-9 0..... 499988825us : __gt_park: 0000:03:00.0
<0> [612.375521] rtcwake-1684 4..... 499989052us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.376327] rtcwake-1684 4..... 499989061us : __gt_unpark: 0000:03:00.0
<0> [612.377015] rtcwake-1684 4..... 499989111us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.377734] rtcwake-1684 4..... 499989133us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:870, current 869
<0> [612.378544] rtcwake-1684 4D..1. 499989138us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:870, current 869
<0> [612.379252] kworker/-9 0..... 499989335us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:870, current 870
<0> [612.379954] kworker/-9 0..... 499989342us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.380749] kworker/-9 0..... 499989388us : __gt_park: 0000:03:00.0
<0> [612.381437] rtcwake-1684 4..... 499993108us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.382132] rtcwake-1684 4..... 499993118us : __gt_unpark: 0000:03:00.0
<0> [612.382928] rtcwake-1684 4..... 499993369us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.383646] rtcwake-1684 4..... 499993403us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:871, current 870
<0> [612.384361] rtcwake-1684 4D..1. 499993422us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:871, current 870
<0> [612.385171] kworker/-9 0..... 499994116us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:871, current 871
<0> [612.385876] kworker/-9 0..... 499994157us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.386573] kworker/-9 0..... 499994175us : __gt_park: 0000:03:00.0
<0> [612.387363] rtcwake-1684 4..... 499994389us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.388053] rtcwake-1684 4..... 499994399us : __gt_unpark: 0000:03:00.0
<0> [612.388739] rtcwake-1684 4..... 499994450us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.389545] rtcwake-1684 4..... 499994472us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:872, current 871
<0> [612.390258] rtcwake-1684 4D..1. 499994478us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:872, current 871
<0> [612.390962] kworker/-9 0..... 499994704us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:872, current 872
<0> [612.391755] kworker/-9 0..... 499994712us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.392451] kworker/-9 0..... 499994730us : __gt_park: 0000:03:00.0
<0> [612.393138] rtcwake-1684 4..... 499994912us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.393931] rtcwake-1684 4..... 499994921us : __gt_unpark: 0000:03:00.0
<0> [612.394616] rtcwake-1684 4..... 499994973us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.395327] rtcwake-1684 4..... 499994995us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:873, current 872
<0> [612.396138] rtcwake-1684 4D..1. 499995001us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:873, current 872
<0> [612.396843] kworker/-9 0..... 499995180us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:873, current 873
<0> [612.397537] kworker/-9 0..... 499995188us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.398328] kworker/-9 0..... 499995204us : __gt_park: 0000:03:00.0
<0> [612.399015] rtcwake-1684 4..... 499995419us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.399705] rtcwake-1684 4..... 499995427us : __gt_unpark: 0000:03:00.0
<0> [612.400497] rtcwake-1684 4..... 499995468us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.401209] rtcwake-1684 4..... 499995489us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:874, current 873
<0> [612.401921] rtcwake-1684 4D..1. 499995494us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:874, current 873
<0> [612.402716] kworker/-9 0..... 499995679us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:874, current 874
<0> [612.403408] kworker/-9 0..... 499995687us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.404105] kworker/-9 0..... 499995717us : __gt_park: 0000:03:00.0
<0> [612.404895] rtcwake-1684 4..... 499995946us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.405585] rtcwake-1684 4..... 499995956us : __gt_unpark: 0000:03:00.0
<0> [612.406268] rtcwake-1684 4..... 499996006us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.407078] rtcwake-1684 4..... 499996030us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:875, current 874
<0> [612.407790] rtcwake-1684 4D..1. 499996035us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:875, current 874
<0> [612.408494] kworker/-9 0..... 499996176us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:875, current 875
<0> [612.409284] kworker/-9 0..... 499996184us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.409980] kworker/-9 0..... 499996202us : __gt_park: 0000:03:00.0
<0> [612.410665] rtcwake-1684 4..... 500002936us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.411449] rtcwake-1684 4..... 500002945us : __gt_unpark: 0000:03:00.0
<0> [612.412134] rtcwake-1684 4..... 500003191us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.412846] rtcwake-1684 4..... 500003290us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:876, current 875
<0> [612.413662] rtcwake-1684 4D..1. 500003296us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:876, current 875
<0> [612.414367] kworker/-9 0..... 500004463us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:876, current 876
<0> [612.415060] kworker/-9 0..... 500004472us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.415851] kworker/-9 0..... 500004490us : __gt_park: 0000:03:00.0
<0> [612.416538] kworker/-1391 3..... 500832561us : gt_sanitize: 0000:03:00.0 force:no
<0> [612.417223] kworker/-1391 3..... 500832676us : intel_engine_stop_cs: 0000:03:00.0 rcs0:
<0> [612.418015] kworker/-1391 3..... 500832685us : intel_engine_stop_cs: 0000:03:00.0 bcs0:
<0> [612.418696] kworker/-1391 3..... 500832712us : intel_engine_stop_cs: 0000:03:00.0 vcs0:
<0> [612.419369] kworker/-1391 3..... 500832722us : intel_engine_stop_cs: 0000:03:00.0 vcs1:
<0> [612.420131] kworker/-1391 3..... 500832731us : intel_engine_stop_cs: 0000:03:00.0 vecs0:
<0> [612.420787] kworker/-1391 3..... 500832741us : intel_engine_stop_cs: 0000:03:00.0 vecs1:
<0> [612.421445] kworker/-1391 3..... 500832751us : intel_engine_stop_cs: 0000:03:00.0 ccs0:
<0> [612.422191] kworker/-1391 3..... 500832760us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff
<0> [612.422840] kworker/-1391 3..... 500832991us : intel_gt_suspend_late: 0000:03:00.0
<0> [612.423486] kworker/-12 3..... 502420637us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff
<0> [612.424241] kworker/-12 3..... 502423074us : i915_gem_resume: 0000:03:00.0
<0> [612.424884] kworker/-12 3..... 502427227us : intel_gt_resume.part.0: 0000:03:00.0
<0> [612.425522] kworker/-12 3..... 502427228us : gt_sanitize: 0000:03:00.0 force:yes
<0> [612.426246] kworker/-12 3..... 502427421us : intel_engine_stop_cs: 0000:03:00.0 rcs0:
<0> [612.426884] kworker/-12 3..... 502427432us : intel_engine_stop_cs: 0000:03:00.0 bcs0:
<0> [612.427506] kworker/-12 3..... 502427467us : intel_engine_stop_cs: 0000:03:00.0 vcs0:
<0> [612.428230] kworker/-12 3..... 502427482us : intel_engine_stop_cs: 0000:03:00.0 vcs1:
<0> [612.428841] kworker/-12 3..... 502427494us : intel_engine_stop_cs: 0000:03:00.0 vecs0:
<0> [612.429448] kworker/-12 3..... 502427508us : intel_engine_stop_cs: 0000:03:00.0 vecs1:
<0> [612.430147] kworker/-12 3..... 502427527us : intel_engine_stop_cs: 0000:03:00.0 ccs0:
<0> [612.430743] kworker/-12 3..... 502427538us : __intel_gt_reset: 0000:03:00.0 engine_mask=ffffffff
<0> [612.431337] kworker/-12 3..... 502427927us : __gt_unpark: 0000:03:00.0
<0> [612.432033] kworker/-12 3..... 502440687us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.432623] kworker/-12 3..... 502440733us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.433214] kworker/-12 3..... 502440876us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.433902] kworker/-12 3..... 502440895us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.434486] kworker/-12 3..... 502440917us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.435081] kworker/-12 3..... 502440937us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.435765] kworker/-12 3..... 502440955us : __engine_unpark: 0000:03:00.0 vcs0:
<0> [612.436345] kworker/-12 3..... 502440977us : __engine_unpark: 0000:03:00.0 vcs0: context:7 reset { seqno:7, *hwsp:7, ring:208 }
<0> [612.436939] kworker/-12 3..... 502440988us : __engine_park: 0000:03:00.0 vcs0: parked
<0> [612.437623] kworker/-12 3..... 502441006us : __engine_unpark: 0000:03:00.0 vcs1:
<0> [612.438204] kworker/-12 3..... 502441035us : __engine_unpark: 0000:03:00.0 vcs1: context:8 reset { seqno:8, *hwsp:8, ring:250 }
<0> [612.438790] kworker/-12 3..... 502441047us : __engine_park: 0000:03:00.0 vcs1: parked
<0> [612.439457] kworker/-12 3..... 502441065us : __engine_unpark: 0000:03:00.0 vecs0:
<0> [612.440036] kworker/-12 3..... 502441086us : __engine_unpark: 0000:03:00.0 vecs0: context:9 reset { seqno:8, *hwsp:8, ring:250 }
<0> [612.440618] kworker/-12 3..... 502441098us : __engine_park: 0000:03:00.0 vecs0: parked
<0> [612.441293] kworker/-12 3..... 502441115us : __engine_unpark: 0000:03:00.0 vecs1:
<0> [612.441867] kworker/-12 3..... 502441136us : __engine_unpark: 0000:03:00.0 vecs1: context:a reset { seqno:6, *hwsp:6, ring:1c0 }
<0> [612.442447] kworker/-12 3..... 502441147us : __engine_park: 0000:03:00.0 vecs1: parked
<0> [612.443113] kworker/-12 3..... 502441164us : __engine_unpark: 0000:03:00.0 ccs0:
<0> [612.443683] kworker/-12 3..... 502441197us : __engine_unpark: 0000:03:00.0 ccs0: context:b reset { seqno:7, *hwsp:7, ring:5f0 }
<0> [612.444256] kworker/-12 3..... 502441236us : __engine_park: 0000:03:00.0 ccs0: parked
<0> [612.444927] kworker/-12 3..... 502441350us : __gt_park: 0000:03:00.0
<0> [612.445493] kworker/-12 3..... 502441425us : __engine_unpark: 0000:03:00.0 bcs0:
<0> [612.446060] kworker/-12 3..... 502441436us : __gt_unpark: 0000:03:00.0
<0> [612.446730] kworker/-12 3..... 502441488us : __engine_unpark: 0000:03:00.0 bcs0: context:6 reset { seqno:1fd, *hwsp:1fd, ring:30 }
<0> [612.447316] kworker/-12 3..... 502441514us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:877, current 876
<0> [612.447904] kworker/-12 3D..1. 502441521us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:877, current 876
<0> [612.448580] kworker/-12 3..... 502441718us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:878, current 877
<0> [612.449160] kworker/-12 3D..1. 502441723us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:878, current 877
<0> [612.449735] kworker/-12 3..... 502441903us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:877, current 878
<0> [612.450407] kworker/-12 3..... 502441922us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:879, current 878
<0> [612.450981] kworker/-12 3D..1. 502441927us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:879, current 878
<0> [612.451553] kworker/-12 3..... 502442098us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:878, current 879
<0> [612.452222] kworker/-12 3..... 502442116us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:880, current 879
<0> [612.452792] kworker/-12 3D..1. 502442121us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:880, current 879
<0> [612.453359] kworker/-12 3..... 502442296us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:879, current 880
<0> [612.454026] kworker/-12 3..... 502442314us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:881, current 880
<0> [612.454599] kworker/-12 3D..1. 502442318us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:881, current 880
<0> [612.455162] kworker/-12 3..... 502442521us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:880, current 881
<0> [612.455824] kworker/-12 3..... 502442541us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:882, current 881
<0> [612.456395] kworker/-12 3D..1. 502442546us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:882, current 881
<0> [612.456955] kworker/-9 0..... 502442618us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:881, current 881
<0> [612.457623] kworker/-12 3..... 502442770us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:883, current 882
<0> [612.458188] kworker/-12 3D..1. 502442776us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:883, current 882
<0> [612.458749] kworker/-12 3..... 502443009us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:882, current 883
<0> [612.459410] kworker/-12 3..... 502443040us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:884, current 883
<0> [612.459982] kworker/-12 3D..1. 502443046us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:884, current 883
<0> [612.460556] kworker/-9 0..... 502443616us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:883, current 883
<0> [612.461236] kworker/-12 3..... 502443749us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:885, current 884
<0> [612.461826] kworker/-12 3D..1. 502443755us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:885, current 884
<0> [612.462416] kworker/-12 3..... 502443993us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:884, current 885
<0> [612.463111] kworker/-12 3..... 502444015us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:886, current 885
<0> [612.463712] kworker/-12 3D..1. 502444021us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:886, current 885
<0> [612.464310] kworker/-12 3..... 502444273us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:885, current 886
<0> [612.465013] kworker/-12 3..... 502444294us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:887, current 886
<0> [612.465615] kworker/-12 3D..1. 502444300us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:887, current 886
<0> [612.466219] kworker/-12 3..... 502444483us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:886, current 887
<0> [612.466924] kworker/-12 3..... 502444502us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:888, current 887
<0> [612.467535] kworker/-12 3D..1. 502444508us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:888, current 887
<0> [612.468146] kworker/-9 0..... 502444615us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:887, current 887
<0> [612.468863] kworker/-12 3..... 502444757us : __i915_request_commit: 0000:03:00.0 bcs0: fence 13:889, current 888
<0> [612.469490] kworker/-12 3D..1. 502444763us : __i915_request_submit: 0000:03:00.0 bcs0: fence 13:889, current 888
<0> [612.470119] kworker/-9 0..... 502445615us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:888, current 888
<0> [612.470862] kworker/-9 0..... 502446621us : i915_request_retire.part.0: 0000:03:00.0 bcs0: fence 13:889, current 889
<0> [612.471487] kworker/-9 0..... 502446630us : __engine_park: 0000:03:00.0 bcs0: parked
<0> [612.472110] kworker/-9 0..... 502446654us : __gt_park: 0000:03:00.0
<0> [612.472832] kworker/-1399 1..... 504090136us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.473456] i915_pm_-1680 3..... 504195040us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.474078] i915_pm_-1680 3.l... 504199015us : __gt_unpark: 0000:03:00.0
<0> [612.474789] i915_pm_-1680 3.l... 504200065us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.475419] i915_pm_-1680 3.l... 504200630us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.476047] i915_pm_-1680 3.l... 504200790us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.476778] i915_pm_-1680 3.l... 504200827us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.477410] i915_pm_-1680 3.l... 504200828us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0000, tail:0000}
<0> [612.478064] i915_pm_-1680 3.l... 504201829us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:2, current 0
<0> [612.478813] i915_pm_-1680 3Dl.1. 504201836us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:2, current 0
<0> [612.479461] kworker/-213 3..... 505242998us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:2, current 2
<0> [612.480101] kworker/-12 2..... 505278579us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.480838] kworker/-12 2.l... 505279235us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:54184ns, avg:54184ns }
<0> [612.481490] kworker/-126 2..... 505279252us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.482142] kworker/-126 2..... 505279266us : __gt_park: 0000:03:00.0
<0> [612.482896] kworker/-12 3..... 505629550us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.483545] i915_pm_-1680 3..... 505771602us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.484196] i915_pm_-1680 3..... 505775078us : __gt_unpark: 0000:03:00.0
<0> [612.484936] i915_pm_-1680 3.l... 505776376us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.485595] i915_pm_-1680 3.l... 505776394us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.486249] i915_pm_-1680 3.l... 505776463us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.487005] i915_pm_-1680 3.l... 505776552us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.487661] i915_pm_-1680 3.l... 505776557us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:00d8, tail:00d8}
<0> [612.488343] i915_pm_-1680 3.l... 505776736us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:4, current 2
<0> [612.489118] i915_pm_-1680 3Dl.1. 505776762us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:4, current 2
<0> [612.489791] kworker/-213 3..... 507290658us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:4, current 4
<0> [612.490450] kworker/-1399 1..... 507326388us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.491214] kworker/-1399 1..... 507327247us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:108836ns, avg:54236ns }
<0> [612.491894] kworker/-238 1..... 507327309us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.492582] kworker/-238 1..... 507327363us : __gt_park: 0000:03:00.0
<0> [612.493361] kworker/-1399 1..... 507438241us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.494041] i915_pm_-1680 3..... 507565730us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.494723] i915_pm_-1680 3..... 507569427us : __gt_unpark: 0000:03:00.0
<0> [612.495482] i915_pm_-1680 3.l... 507570721us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.496170] i915_pm_-1680 3.l... 507570739us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.496858] i915_pm_-1680 3.l... 507570810us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.497651] i915_pm_-1680 3.l... 507570900us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.498337] i915_pm_-1680 3.l... 507570906us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:01b0, tail:01b0}
<0> [612.499051] i915_pm_-1680 3.l... 507571086us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:6, current 4
<0> [612.499856] i915_pm_-1680 3Dl.1. 507571113us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:6, current 4
<0> [612.500559] kworker/-213 3..... 509274298us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:6, current 6
<0> [612.501257] kworker/-1453 2..... 509309875us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.502053] kworker/-1453 2..... 509310735us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:163124ns, avg:54236ns }
<0> [612.502760] kworker/-126 2..... 509310806us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.503470] kworker/-126 2..... 509310860us : __gt_park: 0000:03:00.0
<0> [612.504274] kworker/-1453 2..... 509421857us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.504978] i915_pm_-1680 5..... 509539266us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.505687] i915_pm_-1680 5.l... 509541063us : __gt_unpark: 0000:03:00.0
<0> [612.506482] i915_pm_-1680 5.l... 509541287us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.507196] i915_pm_-1680 5.l... 509541293us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.507909] i915_pm_-1680 5.l... 509541311us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.508717] i915_pm_-1680 5.l... 509541348us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.509430] i915_pm_-1680 5.l... 509541349us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0288, tail:0288}
<0> [612.510170] i915_pm_-1680 5.l... 509541394us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:8, current 6
<0> [612.511000] i915_pm_-1680 5Dl.1. 509541400us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:8, current 6
<0> [612.511726] kworker/-214 5..... 511257787us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:8, current 8
<0> [612.512556] kworker/-1389 3..... 511293255us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.513279] kworker/-1389 3..... 511294112us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:217516ns, avg:54236ns }
<0> [612.514149] kworker/-213 3..... 511294178us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.514880] kworker/-213 3..... 511294234us : __gt_park: 0000:03:00.0
<0> [612.515752] kworker/-1389 3..... 511405420us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.516475] i915_pm_-1680 5..... 511518931us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.517351] i915_pm_-1680 5..... 511520721us : __gt_unpark: 0000:03:00.0
<0> [612.518071] i915_pm_-1680 5..... 511520937us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.518932] i915_pm_-1680 5..... 511520942us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.519666] i915_pm_-1680 5..... 511520959us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.520513] i915_pm_-1680 5..... 511520995us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.521251] i915_pm_-1680 5..... 511520997us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0360, tail:0360}
<0> [612.522122] i915_pm_-1680 5..... 511521041us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:10, current 8
<0> [612.522881] i915_pm_-1680 5D..1. 511521047us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:10, current 8
<0> [612.523727] kworker/-214 5..... 513241115us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:10, current 10
<0> [612.524468] kworker/-1389 3..... 513276863us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.525325] kworker/-1389 3..... 513277709us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:271752ns, avg:54236ns }
<0> [612.526077] kworker/-213 3..... 513277770us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.526928] kworker/-213 3..... 513277824us : __gt_park: 0000:03:00.0
<0> [612.527670] kworker/-1389 3..... 513388905us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.528512] i915_pm_-1680 5..... 513502449us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.529251] i915_pm_-1680 5.l... 513504250us : __gt_unpark: 0000:03:00.0
<0> [612.529983] i915_pm_-1680 5.l... 513504452us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.530820] i915_pm_-1680 5.l... 513504457us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.531550] i915_pm_-1680 5.l... 513504473us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.532408] i915_pm_-1680 5.l... 513504509us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.533142] i915_pm_-1680 5.l... 513504510us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0438, tail:0438}
<0> [612.534038] i915_pm_-1680 5.l... 513504553us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:12, current 10
<0> [612.534797] i915_pm_-1680 5Dl.1. 513504559us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:12, current 10
<0> [612.535626] kworker/-214 5..... 515288549us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:12, current 12
<0> [612.536371] kworker/-1389 3..... 515324308us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.537212] kworker/-1389 3..... 515325156us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:326820ns, avg:54340ns }
<0> [612.537962] kworker/-213 3..... 515325216us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.538823] kworker/-213 3..... 515325271us : __gt_park: 0000:03:00.0
<0> [612.539562] kworker/-1389 3..... 515436029us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.540418] i915_pm_-1680 1..... 515573774us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.541156] i915_pm_-1680 1..... 515575593us : __gt_unpark: 0000:03:00.0
<0> [612.541991] i915_pm_-1680 1..... 515575796us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.542729] i915_pm_-1680 1..... 515575801us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.543576] i915_pm_-1680 1..... 515575817us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.544303] i915_pm_-1680 1..... 515575853us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.545190] i915_pm_-1680 1..... 515575854us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0510, tail:0510}
<0> [612.545947] i915_pm_-1680 1..... 515575895us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:14, current 12
<0> [612.546803] i915_pm_-1680 1D..1. 515575901us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:14, current 12
<0> [612.547553] kworker/-213 3..... 517272169us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:14, current 14
<0> [612.548382] kworker/-1389 5..... 517307780us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.549122] kworker/-1389 5..... 517308647us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:381108ns, avg:54288ns }
<0> [612.549992] kworker/-214 5..... 517308718us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.550734] kworker/-214 5..... 517308775us : __gt_park: 0000:03:00.0
<0> [612.551572] kworker/-1389 5..... 517419847us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.552312] i915_pm_-1680 1..... 517545192us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.553159] i915_pm_-1680 1..... 517546988us : __gt_unpark: 0000:03:00.0
<0> [612.553882] i915_pm_-1680 1..... 517547205us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.554745] i915_pm_-1680 1..... 517547210us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.555466] i915_pm_-1680 1..... 517547227us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.556323] i915_pm_-1680 1..... 517547263us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.557049] i915_pm_-1680 1..... 517547265us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:05e8, tail:05e8}
<0> [612.557937] i915_pm_-1680 1..... 517547310us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:16, current 14
<0> [612.558685] i915_pm_-1680 1D..1. 517547316us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:16, current 14
<0> [612.559536] kworker/-238 1..... 519255445us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:16, current 16
<0> [612.560268] kworker/-1453 2..... 519291194us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.561112] kworker/-1453 2..... 519292039us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:435760ns, avg:54340ns }
<0> [612.561852] kworker/-126 2..... 519292099us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.562689] kworker/-126 2..... 519292153us : __gt_park: 0000:03:00.0
<0> [612.563420] kworker/-1453 2..... 519403289us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.564290] i915_pm_-1680 1..... 519517710us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.565021] i915_pm_-1680 1..... 519519708us : __gt_unpark: 0000:03:00.0
<0> [612.565849] i915_pm_-1680 1..... 519519908us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.566579] i915_pm_-1680 1..... 519519913us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.567443] i915_pm_-1680 1..... 519519928us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.568161] i915_pm_-1680 1..... 519519964us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.568886] i915_pm_-1680 1..... 519519965us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:06c0, tail:06c0}
<0> [612.569726] i915_pm_-1680 1..... 519520006us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:18, current 16
<0> [612.570473] i915_pm_-1680 1D..1. 519520012us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:18, current 16
<0> [612.571334] kworker/-214 5..... 521303009us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:18, current 18
<0> [612.572065] kworker/-1453 4..... 521338476us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.572912] kworker/-1453 4..... 521339337us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:490204ns, avg:54340ns }
<0> [612.573652] kworker/-44 4..... 521339404us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.574507] kworker/-44 4..... 521339464us : __gt_park: 0000:03:00.0
<0> [612.575237] kworker/-1453 4..... 521450726us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.576095] i915_pm_-1680 1..... 521588136us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.576825] i915_pm_-1680 1..... 521590144us : __gt_unpark: 0000:03:00.0
<0> [612.577662] i915_pm_-1680 1..... 521590345us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.578393] i915_pm_-1680 1..... 521590350us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.579243] i915_pm_-1680 1..... 521590366us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.579960] i915_pm_-1680 1..... 521590402us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.580685] i915_pm_-1680 1..... 521590403us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0798, tail:0798}
<0> [612.581503] i915_pm_-1680 1..... 521590445us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:20, current 18
<0> [612.582250] i915_pm_-1680 1D..1. 521590450us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:20, current 18
<0> [612.583081] kworker/-9 0..... 523286309us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:20, current 20
<0> [612.583811] kworker/-1453 4..... 523322058us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.584540] kworker/-1453 4..... 523322911us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:545012ns, avg:54392ns }
<0> [612.585371] kworker/-44 4..... 523322972us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.586105] kworker/-44 4..... 523323026us : __gt_park: 0000:03:00.0
<0> [612.586962] kworker/-1453 4..... 523433956us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.587697] i915_pm_-1680 1..... 523559423us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.588548] i915_pm_-1680 1..... 523561168us : __gt_unpark: 0000:03:00.0
<0> [612.589267] i915_pm_-1680 1..... 523561386us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.590131] i915_pm_-1680 1..... 523561391us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.590849] i915_pm_-1680 1..... 523561408us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.591566] i915_pm_-1680 1..... 523561444us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.592384] i915_pm_-1680 1..... 523561445us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0870, tail:0870}
<0> [612.593131] i915_pm_-1680 1..... 523561490us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:22, current 20
<0> [612.593990] i915_pm_-1680 1D..1. 523561496us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:22, current 20
<0> [612.594730] kworker/-238 1..... 525269810us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:22, current 22
<0> [612.595569] kworker/-1389 5..... 525305473us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.596300] kworker/-1389 5..... 525306330us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:599612ns, avg:54444ns }
<0> [612.597167] kworker/-214 5..... 525306392us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.597902] kworker/-214 5..... 525306447us : __gt_park: 0000:03:00.0
<0> [612.598764] kworker/-1453 4..... 525417335us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.599499] i915_pm_-1680 1..... 525532850us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.600354] i915_pm_-1680 1..... 525534859us : __gt_unpark: 0000:03:00.0
<0> [612.601074] i915_pm_-1680 1..... 525535060us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.601952] i915_pm_-1680 1..... 525535065us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.602672] i915_pm_-1680 1..... 525535081us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.603532] i915_pm_-1680 1..... 525535116us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.604258] i915_pm_-1680 1..... 525535117us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0948, tail:0948}
<0> [612.605152] i915_pm_-1680 1..... 525535158us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:24, current 22
<0> [612.605901] i915_pm_-1680 1D..1. 525535164us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:24, current 22
<0> [612.606736] kworker/-238 1..... 527253270us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:24, current 24
<0> [612.607469] kworker/-1453 4..... 527288882us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.608314] kworker/-1453 4..... 527289777us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:671268ns, avg:56576ns }
<0> [612.609057] kworker/-44 4..... 527289844us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.609919] kworker/-44 4..... 527289903us : __gt_park: 0000:03:00.0
<0> [612.610652] kworker/-1453 4..... 527400950us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.611519] i915_pm_-1680 1..... 527515142us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.612252] i915_pm_-1680 1..... 527516942us : __gt_unpark: 0000:03:00.0
<0> [612.613102] i915_pm_-1680 1..... 527517163us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.613835] i915_pm_-1680 1..... 527517168us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.614676] i915_pm_-1680 1..... 527517185us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.615394] i915_pm_-1680 1..... 527517220us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.616122] i915_pm_-1680 1..... 527517221us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0a20, tail:0a20}
<0> [612.616961] i915_pm_-1680 1..... 527517264us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:26, current 24
<0> [612.617710] i915_pm_-1680 1D..1. 527517269us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:26, current 24
<0> [612.618555] kworker/-44 4..... 529300651us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:26, current 26
<0> [612.619289] kworker/-1389 5..... 529336291us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.620128] kworker/-1389 5..... 529337189us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:726648ns, avg:56420ns }
<0> [612.620872] kworker/-214 5..... 529337257us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.621724] kworker/-214 5..... 529337343us : __gt_park: 0000:03:00.0
<0> [612.622457] kworker/-12 0..... 529448323us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.623296] i915_pm_-1680 1..... 529585705us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.624031] i915_pm_-1680 1..... 529587718us : __gt_unpark: 0000:03:00.0
<0> [612.624868] i915_pm_-1680 1..... 529587920us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.625604] i915_pm_-1680 1..... 529587925us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.626464] i915_pm_-1680 1..... 529587941us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.627185] i915_pm_-1680 1..... 529587977us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.627915] i915_pm_-1680 1..... 529587979us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0af8, tail:0af8}
<0> [612.628739] i915_pm_-1680 1..... 529587996us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.629475] i915_pm_-1680 1..... 529588809us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:726648ns, avg:56420ns }
<0> [612.630334] i915_pm_-1680 1..... 529588813us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.631083] i915_pm_-1680 1..... 529588814us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.631950] i915_pm_-1680 1..... 529588818us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0af8, tail:0af8}
<0> [612.632714] i915_pm_-1680 1..... 529588858us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:28, current 26
<0> [612.633555] i915_pm_-1680 1D..1. 529588863us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:28, current 26
<0> [612.634306] kworker/-9 0..... 531283915us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:28, current 28
<0> [612.635157] kworker/-1389 5..... 531319666us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.635900] kworker/-1389 5..... 531320567us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:788996ns, avg:57148ns }
<0> [612.636746] kworker/-214 5..... 531320638us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.637492] kworker/-214 5..... 531320693us : __gt_park: 0000:03:00.0
<0> [612.638326] kworker/-1389 5..... 531431539us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.639063] i915_pm_-1680 1..... 531557074us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.639930] i915_pm_-1680 1..... 531558862us : __gt_unpark: 0000:03:00.0
<0> [612.640660] i915_pm_-1680 1..... 531559066us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.641529] i915_pm_-1680 1..... 531559071us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.642271] i915_pm_-1680 1..... 531559087us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.643130] i915_pm_-1680 1..... 531559123us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.643872] i915_pm_-1680 1..... 531559124us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0bd0, tail:0bd0}
<0> [612.644741] i915_pm_-1680 1..... 531559167us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:30, current 28
<0> [612.645499] i915_pm_-1680 1D..1. 531559173us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:30, current 28
<0> [612.646332] kworker/-44 4..... 533267444us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:30, current 30
<0> [612.647077] kworker/-1389 5..... 533303037us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.647912] kworker/-1389 5..... 533303922us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:844480ns, avg:56940ns }
<0> [612.648661] kworker/-214 5..... 533303984us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.649503] kworker/-214 5..... 533304040us : __gt_park: 0000:03:00.0
<0> [612.650237] kworker/-1389 5..... 533414913us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.651090] i915_pm_-1680 1..... 533527300us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.651826] i915_pm_-1680 1..... 533529089us : __gt_unpark: 0000:03:00.0
<0> [612.652686] i915_pm_-1680 1..... 533529321us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.653432] i915_pm_-1680 1..... 533529326us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.654303] i915_pm_-1680 1..... 533529342us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.655040] i915_pm_-1680 1..... 533529378us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.655878] i915_pm_-1680 1..... 533529379us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0ca8, tail:0ca8}
<0> [612.656642] i915_pm_-1680 1..... 533529421us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:32, current 30
<0> [612.657481] i915_pm_-1680 1D..1. 533529426us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:32, current 30
<0> [612.658231] kworker/-238 1..... 535250844us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:32, current 32
<0> [612.659075] kworker/-1389 5..... 535286427us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.659817] kworker/-1389 5..... 535287298us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:900536ns, avg:56836ns }
<0> [612.660649] kworker/-214 5..... 535287358us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.661393] kworker/-214 5..... 535287414us : __gt_park: 0000:03:00.0
<0> [612.662238] kworker/-1389 5..... 535398556us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.662975] i915_pm_-1680 1..... 535511164us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.663831] i915_pm_-1680 1..... 535512945us : __gt_unpark: 0000:03:00.0
<0> [612.664560] i915_pm_-1680 1..... 535513159us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.665437] i915_pm_-1680 1..... 535513165us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.666178] i915_pm_-1680 1..... 535513182us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.667019] i915_pm_-1680 1..... 535513218us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.667760] i915_pm_-1680 1..... 535513219us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0d80, tail:0d80}
<0> [612.668625] i915_pm_-1680 1..... 535513263us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:34, current 32
<0> [612.669380] i915_pm_-1680 1D..1. 535513269us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:34, current 32
<0> [612.670208] kworker/-44 4..... 537298197us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:34, current 34
<0> [612.670951] kworker/-1453 0..... 537333764us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.671787] kworker/-1453 0..... 537334672us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:957112ns, avg:56784ns }
<0> [612.672535] kworker/-9 0..... 537334757us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.673412] kworker/-9 0..... 537334814us : __gt_park: 0000:03:00.0
<0> [612.674145] kworker/-1453 0..... 537445892us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.674989] i915_pm_-1680 1..... 537582277us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.675724] i915_pm_-1680 1..... 537584096us : __gt_unpark: 0000:03:00.0
<0> [612.676583] i915_pm_-1680 1..... 537584328us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.677328] i915_pm_-1680 1..... 537584333us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.678179] i915_pm_-1680 1..... 537584350us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.678915] i915_pm_-1680 1..... 537584386us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.679760] i915_pm_-1680 1..... 537584387us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0e58, tail:0e58}
<0> [612.680522] i915_pm_-1680 1..... 537584429us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:36, current 34
<0> [612.681371] i915_pm_-1680 1D..1. 537584435us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:36, current 34
<0> [612.682120] kworker/-9 0..... 539281429us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:36, current 36
<0> [612.682936] kworker/-1453 0..... 539317175us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.683668] kworker/-1453 0..... 539318073us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:1013584ns, avg:56732ns }
<0> [612.684524] kworker/-9 0..... 539318137us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.685261] kworker/-9 0..... 539318194us : __gt_park: 0000:03:00.0
<0> [612.686093] kworker/-1453 0..... 539429000us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.686829] i915_pm_-1680 1..... 539553547us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.687660] i915_pm_-1680 1..... 539555369us : __gt_unpark: 0000:03:00.0
<0> [612.688380] i915_pm_-1680 1..... 539555572us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.689244] i915_pm_-1680 1..... 539555577us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.689963] i915_pm_-1680 1..... 539555593us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.690817] i915_pm_-1680 1..... 539555629us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.691541] i915_pm_-1680 1..... 539555630us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:0f30, tail:0f30}
<0> [612.692288] i915_pm_-1680 1..... 539555672us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:38, current 36
<0> [612.693132] i915_pm_-1680 1D..1. 539555678us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:38, current 36
<0> [612.693874] kworker/-238 1..... 541264845us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:38, current 38
<0> [612.694726] kworker/-1453 0..... 541300485us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.695457] kworker/-1453 0..... 541301387us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:1069744ns, avg:56680ns }
<0> [612.696321] kworker/-9 0..... 541301446us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.697058] kworker/-9 0..... 541301500us : __gt_park: 0000:03:00.0
<0> [612.697908] kworker/-1453 0..... 541412398us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.698642] i915_pm_-1680 1..... 541525713us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.699489] i915_pm_-1680 1..... 541527963us : __gt_unpark: 0000:03:00.0
<0> [612.700209] i915_pm_-1680 1..... 541528164us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 active
<0> [612.701073] i915_pm_-1680 1..... 541528168us : intel_timeline_pin: 0000:03:00.0 timeline:342 using HWSP offset:fede3000
<0> [612.701792] i915_pm_-1680 1..... 541528185us : __engine_unpark: 0000:03:00.0 rcs0:
<0> [612.702656] i915_pm_-1680 1..... 541528221us : __engine_unpark: 0000:03:00.0 rcs0: context:5 reset { seqno:9d, *hwsp:9d, ring:9e8 }
<0> [612.703381] i915_pm_-1680 1..... 541528222us : __intel_context_do_pin_ww: 0000:03:00.0 rcs0: context:342 pin ring:{start:01500000, head:1008, tail:1008}
<0> [612.704277] i915_pm_-1680 1..... 541528265us : __i915_request_commit: 0000:03:00.0 rcs0: fence 342:40, current 38
<0> [612.705025] i915_pm_-1680 1D..1. 541528270us : __i915_request_submit: 0000:03:00.0 rcs0: fence 342:40, current 38
<0> [612.705869] kworker/-238 1..... 543248310us : i915_request_retire.part.0: 0000:03:00.0 rcs0: fence 342:40, current 40
<0> [612.706602] kworker/-1389 1..... 543283929us : __intel_context_do_unpin.part.0: 0000:03:00.0 rcs0: context:342 unpin
<0> [612.707451] kworker/-1389 1.l... 543286453us : __intel_context_retire: 0000:03:00.0 rcs0: context:342 retire runtime: { total:1125540ns, avg:56524ns }
<0> [612.708194] kworker/-238 1..... 543286520us : __engine_park: 0000:03:00.0 rcs0: parked
<0> [612.709049] kworker/-238 1..... 543286575us : __gt_park: 0000:03:00.0
<0> [612.709782] kworker/-1389 1..... 543395935us : intel_gt_runtime_suspend: 0000:03:00.0
<0> [612.710568] i915_pm_-1680 1...1. 543436127us : tracing_mark_write: i915_pm_rpm: finished subtest system-suspend-execbuf, SUCCESS
<0> [612.711312] i915_pm_-1680 1..... 543518750us : intel_gt_runtime_resume: 0000:03:00.0
<0> [612.712048] kworker/-1453 5..... 543544258us : __gt_unpark: 0000:03:00.0
<0> [612.712895] kworker/-1453 5..... 543544504us : destroyed_worker_func: guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id))
<0> [612.713562] ---------------------------------
<4> [612.716600] ------------[ cut here ]------------
<2> [612.716602] kernel BUG at drivers/gpu/drm/i915/gt/uc/intel_guc_submission.c:3418!
<4> [612.717275] Oops: invalid opcode: 0000 [#1] SMP NOPTI
<4> [612.717827] CPU: 5 UID: 0 PID: 1453 Comm: kworker/u24:22 Tainted: G S U W L 7.0.0-CI_DRM_18326-gc8bad7becc00+ #1 PREEMPT(lazy)
<4> [612.718392] Tainted: [S]=CPU_OUT_OF_SPEC, [U]=USER, [W]=WARN, [L]=SOFTLOCKUP
<4> [612.719099] Hardware name: Intel Corporation CoffeeLake Client Platform/CoffeeLake S UDIMM RVP, BIOS CNLSFWR1.R00.X220.B00.2103302221 03/30/2021
<4> [612.719674] Workqueue: events_unbound destroyed_worker_func [i915]
<4> [612.720468] RIP: 0010:destroyed_worker_func+0x66d/0x8a0 [i915]
<4> [612.721343] Code: 00 48 c7 c2 50 04 1d a1 48 8d 3d 00 00 00 00 e8 d9 08 86 e0 bf 01 00 00 00 e8 5f af 85 e0 31 f6 bf 09 00 00 00 e8 f3 b2 64 e0 <0f> 0b 48 c7 c1 15 25 20 a1 ba 08 02 00 00 48 c7 c6 90 0c 1d a1 48
<4> [612.721945] RSP: 0018:ffffc9000135fd88 EFLAGS: 00010246
<4> [612.722549] RAX: 0000000000000000 RBX: ffff888147168028 RCX: 0000000000000000
<4> [612.723159] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
<4> [612.723761] RBP: ffffc9000135fdf8 R08: 0000000000000000 R09: 0000000000000000
<4> [612.724498] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000001006
<4> [612.725087] R13: ffff888147168de8 R14: ffff88810fb25320 R15: ffff888147168dd8
<4> [612.725669] FS: 0000000000000000(0000) GS:ffff8884d9917000(0000) knlGS:0000000000000000
<4> [612.726406] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4> [612.726989] CR2: 00007523533f3058 CR3: 000000000344c006 CR4: 00000000003726f0
<4> [612.727574] Call Trace:
<4> [612.728155] <TASK>
|