Results for igt@gem_exec_nop@basic-parallel

Result: Abort 296 Warning(s)

integration-manifest git-log-oneline i915_display_info7 igt_runner7 runtimes7 results7.json results7-i915-load.json guc_logs7.tar i915_display_info_post_exec7 boot7 dmesg7

pstore-001-dmesg7 pstore-002-dmesg7

DetailValue
Duration unknown
Hostname 
shard-mtlp-4
Igt-Version 
IGT-Version: 2.4-g2a9f1c1ae (x86_64) (Linux: 7.0.0-rc7-CI_DRM_18287-g9e217a8df7b2+ x86_64)
Out 
Using IGT_SRANDOM=1775601969 for randomisation
Opened device: /dev/dri/card0
Running on meteorlake
Using GuC submission
Has kernel scheduler
 - With priority sorting
 - With preemption enabled
 - With engine busy statistics
 - With timeslicing enabled
Starting subtest: basic-parallel
average (individually): 37.936us
rcs0: 3805 cycles, 525.890us
bcs0: 3823 cycles, 523.205us
vcs0: 3794 cycles, 527.229us
vcs1: 3752 cycles, 533.063us
vecs0: 3795 cycles, 527.020us
ccs0: 3792 cycles, 527.530us
Subtest basic-parallel: SUCCESS (15.707s)
runner: This test was killed due to a kernel taint (0x42c4).
runner: This test was killed due to exceeding disk usage limit. (Used 12275047 bytes, limit 10485760)

This test caused an abort condition: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         2431 [max: 8192]
 dynamic-keys:                          332
 direct dependencies:                 27072 [max: 524288]
 indirect dependencies:              251866
 all direct dependencies:            601968
 dependency chains:                   40186 [max: 524288]
 dependency chain hlocks used:       173952 [max: 2621440]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     270
 in-softirq chains:                     855
 in-process chains:                   39061
 stack-trace entries:                301546 [max: 524288]
 number of stack traces:              13463
 number of stack hash chains:          9231
 combined max dependencies:       471511920
 hardirq-safe locks:                    124
 hardirq-unsafe locks:                 1416
 softirq-safe locks:                    255
 softirq-unsafe locks:                 1315
 irq-safe locks:                        282
 irq-unsafe locks:                     1416
 hardirq-read-safe locks:                 5
 hardirq-read-unsafe locks:             484
 softirq-read-safe locks:                 8
 softirq-read-unsafe locks:             481
 irq-read-safe locks:                     9
 irq-read-unsafe locks:                 484
 uncategorized locks:                   404
 unused locks:                            1
 max locking depth:                      18
 max bfs queue depth:                   514
 max lock class index:                 2430
 debug_locks:                             0

 zapped classes:                          2
 zapped lock chains:                    179
 large chain blocks:                      1
Err 
Starting subtest: basic-parallel
Subtest basic-parallel: SUCCESS (15.707s)
Dmesg

Scroll to first warning
<6> [285.518980] Console: switching to colour dummy device 80x25
<6> [285.519058] [IGT] gem_exec_nop: executing
<7> [285.523158] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [285.524516] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [285.542188] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [285.543165] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [285.549819] [IGT] gem_exec_nop: starting subtest basic-parallel
<7> [285.552543] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [285.838218] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [285.876662] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [286.161968] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [286.199752] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [286.244135] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [286.244273] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [286.244361] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [286.244510] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [287.491048] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [287.716452] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 turning VDD off
<7> [287.716932] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:507:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<7> [287.717265] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [287.717672] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [287.718049] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [289.294012] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [291.522555] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [293.261787] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [295.490576] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [297.294368] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [297.580596] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [297.645297] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [299.705286] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [301.256813] [IGT] gem_exec_nop: finished subtest basic-parallel, SUCCESS
<6> [301.364766] [IGT] gem_exec_nop: exiting, ret=0
<7> [301.366667] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<0> [301.657297] kworker/-1068 18..... 182240451us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:7e unpin
<0> [301.657375] kworker/-1068 18..... 182240529us : __intel_context_retire: 0000:00:02.0 bcs0: context:7e retire runtime: { total:79508ns, avg:79508ns }
<0> [301.657469] kworker/-180 18..... 182244903us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 7f:2, current 2
<0> [301.657547] kworker/-1068 18..... 182245081us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:7f unpin
<0> [301.657626] kworker/-1068 18..... 182245172us : __intel_context_retire: 0000:00:02.0 bcs0: context:7f retire runtime: { total:19344ns, avg:19344ns }
<0> [301.657717] kworker/-180 18..... 182248664us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 80:2, current 2
<0> [301.657797] kworker/-1068 18..... 182248791us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:80 unpin
<0> [301.657876] kworker/-1068 18..... 182248879us : __intel_context_retire: 0000:00:02.0 bcs0: context:80 retire runtime: { total:31824ns, avg:31824ns }
<0> [301.657968] kworker/-180 18..... 182252628us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 81:2, current 2
<0> [301.658047] kworker/-1068 18..... 182253009us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:81 unpin
<0> [301.658125] kworker/-1068 18..... 182253075us : __intel_context_retire: 0000:00:02.0 bcs0: context:81 retire runtime: { total:29640ns, avg:29640ns }
<0> [301.658216] kworker/-180 18..... 182256512us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 82:2, current 2
<0> [301.658295] kworker/-1068 18..... 182256809us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:82 unpin
<0> [301.658373] kworker/-1068 18..... 182256886us : __intel_context_retire: 0000:00:02.0 bcs0: context:82 retire runtime: { total:25012ns, avg:25012ns }
<0> [301.658465] kworker/-180 18..... 182262172us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 83:2, current 2
<0> [301.658543] kworker/-1068 18..... 182262397us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:83 unpin
<0> [301.658620] kworker/-1068 18..... 182262462us : __intel_context_retire: 0000:00:02.0 bcs0: context:83 retire runtime: { total:22776ns, avg:22776ns }
<0> [301.658712] kworker/-180 18..... 182265383us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 84:2, current 2
<0> [301.658791] kworker/-1068 18..... 182265497us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:84 unpin
<0> [301.658870] kworker/-1068 18..... 182265585us : __intel_context_retire: 0000:00:02.0 bcs0: context:84 retire runtime: { total:17888ns, avg:17888ns }
<0> [301.658961] kworker/-180 18..... 182269185us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 85:2, current 2
<0> [301.659043] kworker/-1068 18..... 182269419us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:85 unpin
<0> [301.659121] kworker/-1068 18..... 182269484us : __intel_context_retire: 0000:00:02.0 bcs0: context:85 retire runtime: { total:26104ns, avg:26104ns }
<0> [301.659208] kms_curs-2482 18..... 182271410us : intel_timeline_pin: 0000:00:02.0 timeline:86 using HWSP offset:fedcc000
<0> [301.659286] kms_curs-2482 18..... 182271823us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:86 pin ring:{start:fedba000, head:0000, tail:0000}
<0> [301.659377] kms_curs-2482 18..... 182272918us : __i915_request_commit: 0000:00:02.0 bcs0: fence 86:2, current 0
<0> [301.659468] kms_curs-2482 18D..1. 182272924us : __i915_request_submit: 0000:00:02.0 bcs0: fence 86:2, current 0
<0> [301.659557] kms_curs-2482 18..... 182272986us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:22, current 21
<0> [301.659647] kms_curs-2482 18D..1. 182272990us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:22, current 21
<0> [301.659732] kms_curs-2482 18..... 182273012us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:86:2
<0> [301.659819] kworker/-180 18..... 182273065us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 86:2, current 2
<0> [301.659898] kworker/-541 19..... 182273315us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:86 unpin
<0> [301.659977] kworker/-541 19..... 182273397us : __intel_context_retire: 0000:00:02.0 bcs0: context:86 retire runtime: { total:17576ns, avg:17576ns }
<0> [301.660057] kms_curs-2482 16..... 182277614us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:87 active
<0> [301.660141] kms_curs-2482 16..... 182278969us : intel_timeline_pin: 0000:00:02.0 timeline:87 using HWSP offset:fedb9000
<0> [301.660220] kms_curs-2482 16..... 182279585us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:87 pin ring:{start:fedc8000, head:0000, tail:0000}
<0> [301.660310] kms_curs-2482 16..... 182280992us : __i915_request_commit: 0000:00:02.0 bcs0: fence 87:2, current 0
<0> [301.660398] kms_curs-2482 16D..1. 182280997us : __i915_request_submit: 0000:00:02.0 bcs0: fence 87:2, current 0
<0> [301.660488] kms_curs-2482 16..... 182281058us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:23, current 22
<0> [301.660576] kms_curs-2482 16D..1. 182281063us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:23, current 22
<0> [301.660660] kms_curs-2482 16..... 182281084us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:87:2
<0> [301.660746] kworker/-180 18..... 182281325us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 87:2, current 2
<0> [301.660828] kworker/-541 19..... 182281525us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:87 unpin
<0> [301.660906] kworker/-541 19..... 182281647us : __intel_context_retire: 0000:00:02.0 bcs0: context:87 retire runtime: { total:24076ns, avg:24076ns }
<0> [301.660986] kms_curs-2482 16..... 182283312us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:88 active
<0> [301.661070] kms_curs-2482 16..... 182284561us : intel_timeline_pin: 0000:00:02.0 timeline:88 using HWSP offset:fedc2000
<0> [301.661148] kms_curs-2482 16..... 182284979us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:88 pin ring:{start:fecf0000, head:0000, tail:0000}
<0> [301.661239] kms_curs-2482 16..... 182286366us : __i915_request_commit: 0000:00:02.0 bcs0: fence 88:2, current 0
<0> [301.661327] kms_curs-2482 16D..1. 182286371us : __i915_request_submit: 0000:00:02.0 bcs0: fence 88:2, current 0
<0> [301.661415] kms_curs-2482 16..... 182286432us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:24, current 23
<0> [301.661506] kms_curs-2482 16D..1. 182286436us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:24, current 23
<0> [301.661590] kms_curs-2482 16..... 182286458us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:88:2
<0> [301.661676] kworker/-180 18..... 182286763us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 88:2, current 2
<0> [301.661755] kworker/-541 19..... 182286941us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:88 unpin
<0> [301.661834] kworker/-541 19..... 182287072us : __intel_context_retire: 0000:00:02.0 bcs0: context:88 retire runtime: { total:40248ns, avg:40248ns }
<0> [301.661914] kms_curs-2482 16..... 182289351us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:89 active
<0> [301.661998] kms_curs-2482 16..... 182290439us : intel_timeline_pin: 0000:00:02.0 timeline:89 using HWSP offset:feda6000
<0> [301.662077] kms_curs-2482 16..... 182290861us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:89 pin ring:{start:03114000, head:0000, tail:0000}
<0> [301.662168] kms_curs-2482 16..... 182291981us : __i915_request_commit: 0000:00:02.0 bcs0: fence 89:2, current 0
<0> [301.662256] kms_curs-2482 16D..1. 182291987us : __i915_request_submit: 0000:00:02.0 bcs0: fence 89:2, current 0
<0> [301.662344] kms_curs-2482 16..... 182292050us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:25, current 24
<0> [301.662432] kms_curs-2482 16D..1. 182292054us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:25, current 24
<0> [301.662516] kms_curs-2482 16..... 182292076us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:89:2
<0> [301.662602] kworker/-180 18..... 182292345us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 89:2, current 2
<0> [301.662680] kworker/-541 19..... 182292454us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:89 unpin
<0> [301.662759] kworker/-541 19..... 182292583us : __intel_context_retire: 0000:00:02.0 bcs0: context:89 retire runtime: { total:32136ns, avg:32136ns }
<0> [301.662839] kms_curs-2482 16..... 182295523us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8a active
<0> [301.662923] kms_curs-2482 16..... 182296567us : intel_timeline_pin: 0000:00:02.0 timeline:8a using HWSP offset:fedc7000
<0> [301.663002] kms_curs-2482 16..... 182296984us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8a pin ring:{start:fedc8000, head:0000, tail:0000}
<0> [301.663083] kms_curs-2482 16..... 182297001us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8a unpin
<0> [301.663160] kms_curs-2482 16.l... 182297288us : __intel_context_retire: 0000:00:02.0 bcs0: context:8a retire runtime: { total:0ns, avg:0ns }
<0> [301.663239] kms_curs-2482 16.l... 182297291us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8a active
<0> [301.663322] kms_curs-2482 16.l... 182297293us : intel_timeline_pin: 0000:00:02.0 timeline:8a using HWSP offset:fedc7000
<0> [301.663399] kms_curs-2482 16.l... 182297298us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8a pin ring:{start:fedc8000, head:0000, tail:0000}
<0> [301.663492] kms_curs-2482 16..... 182298556us : __i915_request_commit: 0000:00:02.0 bcs0: fence 8a:2, current 0
<0> [301.663580] kms_curs-2482 16D..1. 182298562us : __i915_request_submit: 0000:00:02.0 bcs0: fence 8a:2, current 0
<0> [301.663669] kms_curs-2482 16..... 182298623us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:26, current 25
<0> [301.663757] kms_curs-2482 16D..1. 182298627us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:26, current 25
<0> [301.663840] kms_curs-2482 16..... 182298650us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:8a:2
<0> [301.663926] kworker/-180 18..... 182298745us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 8a:2, current 2
<0> [301.664006] kworker/-541 19..... 182298969us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8a unpin
<0> [301.664085] kworker/-541 19..... 182299102us : __intel_context_retire: 0000:00:02.0 bcs0: context:8a retire runtime: { total:31720ns, avg:31720ns }
<0> [301.664165] kms_curs-2482 17..... 182302347us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8b active
<0> [301.664248] kms_curs-2482 17..... 182303465us : intel_timeline_pin: 0000:00:02.0 timeline:8b using HWSP offset:fedc1000
<0> [301.664328] kms_curs-2482 17..... 182303932us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8b pin ring:{start:fedc3000, head:0000, tail:0000}
<0> [301.664408] kms_curs-2482 17..... 182303949us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8b unpin
<0> [301.664490] kms_curs-2482 17..... 182304075us : __intel_context_retire: 0000:00:02.0 bcs0: context:8b retire runtime: { total:0ns, avg:0ns }
<0> [301.664569] kms_curs-2482 17..... 182304078us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8b active
<0> [301.664653] kms_curs-2482 17..... 182304080us : intel_timeline_pin: 0000:00:02.0 timeline:8b using HWSP offset:fedc1000
<0> [301.664731] kms_curs-2482 17..... 182304085us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8b pin ring:{start:fedc3000, head:0000, tail:0000}
<0> [301.664811] kms_curs-2482 17..... 182304098us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8b unpin
<0> [301.664888] kms_curs-2482 17..... 182304233us : __intel_context_retire: 0000:00:02.0 bcs0: context:8b retire runtime: { total:0ns, avg:0ns }
<0> [301.664967] kms_curs-2482 17..... 182304236us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8b active
<0> [301.665051] kms_curs-2482 17..... 182304237us : intel_timeline_pin: 0000:00:02.0 timeline:8b using HWSP offset:fedc1000
<0> [301.665129] kms_curs-2482 17..... 182304241us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8b pin ring:{start:fedc3000, head:0000, tail:0000}
<0> [301.665220] kms_curs-2482 8..... 182305404us : __i915_request_commit: 0000:00:02.0 bcs0: fence 8b:2, current 0
<0> [301.665309] kms_curs-2482 8D..1. 182305408us : __i915_request_submit: 0000:00:02.0 bcs0: fence 8b:2, current 0
<0> [301.665398] kms_curs-2482 8..... 182305458us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:27, current 26
<0> [301.665488] kms_curs-2482 8D..1. 182305461us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:27, current 26
<0> [301.665572] kms_curs-2482 8..... 182305480us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:8b:2
<0> [301.665659] kworker/-180 18..... 182305705us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 8b:2, current 2
<0> [301.665739] kworker/-541 19..... 182305823us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8b unpin
<0> [301.665818] kworker/-541 19..... 182305952us : __intel_context_retire: 0000:00:02.0 bcs0: context:8b retire runtime: { total:25272ns, avg:25272ns }
<0> [301.665898] kms_curs-2482 8..... 182307046us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8c active
<0> [301.665982] kms_curs-2482 18..... 182308091us : intel_timeline_pin: 0000:00:02.0 timeline:8c using HWSP offset:fedc0000
<0> [301.666061] kms_curs-2482 18..... 182308765us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8c pin ring:{start:fedba000, head:0000, tail:0000}
<0> [301.666141] kms_curs-2482 18..... 182308794us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8c unpin
<0> [301.666218] kms_curs-2482 18..... 182308921us : __intel_context_retire: 0000:00:02.0 bcs0: context:8c retire runtime: { total:0ns, avg:0ns }
<0> [301.666297] kms_curs-2482 18..... 182308925us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8c active
<0> [301.666380] kms_curs-2482 18..... 182308926us : intel_timeline_pin: 0000:00:02.0 timeline:8c using HWSP offset:fedc0000
<0> [301.666461] kms_curs-2482 18..... 182308931us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8c pin ring:{start:fedba000, head:0000, tail:0000}
<0> [301.666540] kms_curs-2482 18..... 182308944us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8c unpin
<0> [301.666618] kms_curs-2482 18..... 182309070us : __intel_context_retire: 0000:00:02.0 bcs0: context:8c retire runtime: { total:0ns, avg:0ns }
<0> [301.666697] kms_curs-2482 18..... 182309073us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8c active
<0> [301.666780] kms_curs-2482 18..... 182309074us : intel_timeline_pin: 0000:00:02.0 timeline:8c using HWSP offset:fedc0000
<0> [301.666858] kms_curs-2482 18..... 182309078us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8c pin ring:{start:fedba000, head:0000, tail:0000}
<0> [301.666949] kms_curs-2482 18..... 182310336us : __i915_request_commit: 0000:00:02.0 bcs0: fence 8c:2, current 0
<0> [301.667038] kms_curs-2482 18D..1. 182310342us : __i915_request_submit: 0000:00:02.0 bcs0: fence 8c:2, current 0
<0> [301.667127] kms_curs-2482 18..... 182310405us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:28, current 27
<0> [301.667216] kms_curs-2482 18D..1. 182310409us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:28, current 27
<0> [301.667300] kms_curs-2482 18..... 182310432us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:8c:2
<0> [301.667388] kworker/-180 18..... 182310563us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 8c:2, current 2
<0> [301.667470] kworker/-541 19..... 182310780us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8c unpin
<0> [301.667549] kworker/-541 19..... 182310906us : __intel_context_retire: 0000:00:02.0 bcs0: context:8c retire runtime: { total:26728ns, avg:26728ns }
<0> [301.667630] kms_curs-2482 16..... 182313234us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8d active
<0> [301.667714] kms_curs-2482 16..... 182314468us : intel_timeline_pin: 0000:00:02.0 timeline:8d using HWSP offset:fedc8000
<0> [301.667793] kms_curs-2482 16..... 182314885us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8d pin ring:{start:fedc9000, head:0000, tail:0000}
<0> [301.667885] kms_curs-2482 16..... 182316057us : __i915_request_commit: 0000:00:02.0 bcs0: fence 8d:2, current 0
<0> [301.667973] kms_curs-2482 16D..1. 182316063us : __i915_request_submit: 0000:00:02.0 bcs0: fence 8d:2, current 0
<0> [301.668061] kms_curs-2482 16..... 182316139us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:29, current 28
<0> [301.668150] kms_curs-2482 16D..1. 182316144us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:29, current 28
<0> [301.668235] kms_curs-2482 16..... 182316168us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:8d:2
<0> [301.668323] kworker/-180 18..... 182316344us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 8d:2, current 2
<0> [301.668403] kworker/-541 19..... 182316471us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8d unpin
<0> [301.668485] kworker/-541 19..... 182316600us : __intel_context_retire: 0000:00:02.0 bcs0: context:8d retire runtime: { total:26624ns, avg:26624ns }
<0> [301.668566] kms_curs-2482 16..... 182318956us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8e active
<0> [301.668650] kms_curs-2482 16..... 182320116us : intel_timeline_pin: 0000:00:02.0 timeline:8e using HWSP offset:fedc2000
<0> [301.668729] kms_curs-2482 16..... 182320836us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8e pin ring:{start:fedc3000, head:0000, tail:0000}
<0> [301.668822] kms_curs-2482 6..... 182321970us : __i915_request_commit: 0000:00:02.0 bcs0: fence 8e:2, current 0
<0> [301.668911] kms_curs-2482 6D..1. 182321977us : __i915_request_submit: 0000:00:02.0 bcs0: fence 8e:2, current 0
<0> [301.669000] kms_curs-2482 6..... 182322049us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:30, current 29
<0> [301.669088] kms_curs-2482 6D..1. 182322053us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:30, current 29
<0> [301.669172] kms_curs-2482 6..... 182322078us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:8e:2
<0> [301.669259] kworker/-180 18..... 182322189us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 8e:2, current 2
<0> [301.669338] kworker/-541 19..... 182322385us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8e unpin
<0> [301.669417] kworker/-541 19..... 182322514us : __intel_context_retire: 0000:00:02.0 bcs0: context:8e retire runtime: { total:27508ns, avg:27508ns }
<0> [301.669500] kms_curs-2482 6..... 182324610us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8f active
<0> [301.669584] kms_curs-2482 18..... 182325403us : intel_timeline_pin: 0000:00:02.0 timeline:8f using HWSP offset:fedc7000
<0> [301.669663] kms_curs-2482 18..... 182325834us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:8f pin ring:{start:fedb5000, head:0000, tail:0000}
<0> [301.669756] kms_curs-2482 2..... 182327215us : __i915_request_commit: 0000:00:02.0 bcs0: fence 8f:2, current 0
<0> [301.669844] kms_curs-2482 2D..1. 182327228us : __i915_request_submit: 0000:00:02.0 bcs0: fence 8f:2, current 0
<0> [301.669934] kms_curs-2482 2..... 182327350us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:31, current 30
<0> [301.670022] kms_curs-2482 2D..1. 182327358us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:31, current 30
<0> [301.670105] kms_curs-2482 2..... 182327398us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:8f:2
<0> [301.670192] kworker/-180 18..... 182327478us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 8f:2, current 2
<0> [301.670272] kworker/-541 19..... 182327687us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:8f unpin
<0> [301.670351] kworker/-541 19..... 182327815us : __intel_context_retire: 0000:00:02.0 bcs0: context:8f retire runtime: { total:54808ns, avg:54808ns }
<0> [301.670444] kworker/-180 18..... 182332667us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 90:2, current 2
<0> [301.670524] kworker/-541 19..... 182332903us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:90 unpin
<0> [301.670602] kworker/-541 19..... 182333031us : __intel_context_retire: 0000:00:02.0 bcs0: context:90 retire runtime: { total:51740ns, avg:51740ns }
<0> [301.670694] kworker/-180 18..... 182336768us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 91:2, current 2
<0> [301.670772] kworker/-541 17..... 182337011us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:91 unpin
<0> [301.670850] kworker/-541 17..... 182337160us : __intel_context_retire: 0000:00:02.0 bcs0: context:91 retire runtime: { total:52676ns, avg:52676ns }
<0> [301.670941] kworker/-180 18..... 182340755us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 92:2, current 2
<0> [301.671019] kworker/-2376 8..... 182340877us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:92 unpin
<0> [301.671097] kworker/-2376 8..... 182340950us : __intel_context_retire: 0000:00:02.0 bcs0: context:92 retire runtime: { total:37960ns, avg:37960ns }
<0> [301.671189] kworker/-180 18..... 182344974us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 93:2, current 2
<0> [301.671269] kworker/-2376 8..... 182345283us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:93 unpin
<0> [301.671348] kworker/-2376 8..... 182345353us : __intel_context_retire: 0000:00:02.0 bcs0: context:93 retire runtime: { total:24180ns, avg:24180ns }
<0> [301.671442] kworker/-180 18..... 182349361us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 94:2, current 2
<0> [301.671521] kworker/-2376 8..... 182349576us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:94 unpin
<0> [301.671600] kworker/-2376 8..... 182349648us : __intel_context_retire: 0000:00:02.0 bcs0: context:94 retire runtime: { total:28756ns, avg:28756ns }
<0> [301.671691] kworker/-180 18..... 182353344us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 95:2, current 2
<0> [301.671770] kworker/-2376 8..... 182353576us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:95 unpin
<0> [301.671848] kworker/-2376 8..... 182353649us : __intel_context_retire: 0000:00:02.0 bcs0: context:95 retire runtime: { total:35828ns, avg:35828ns }
<0> [301.671928] kms_curs-2482 2..... 182356605us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:96 active
<0> [301.672012] kms_curs-2482 2..... 182357941us : intel_timeline_pin: 0000:00:02.0 timeline:96 using HWSP offset:fedc1000
<0> [301.672090] kms_curs-2482 2..... 182358433us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:96 pin ring:{start:fedb4000, head:0000, tail:0000}
<0> [301.672183] kms_curs-2482 2..... 182359263us : __i915_request_commit: 0000:00:02.0 bcs0: fence 96:2, current 0
<0> [301.672272] kms_curs-2482 2D..1. 182359266us : __i915_request_submit: 0000:00:02.0 bcs0: fence 96:2, current 0
<0> [301.672361] kms_curs-2482 2..... 182359305us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:38, current 37
<0> [301.672451] kms_curs-2482 2D..1. 182359308us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:38, current 37
<0> [301.672536] kms_curs-2482 2..... 182359322us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:96:2
<0> [301.672623] kworker/-180 18..... 182359697us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 96:2, current 2
<0> [301.672703] kworker/-2376 8..... 182359875us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:96 unpin
<0> [301.672781] kworker/-2376 8..... 182359952us : __intel_context_retire: 0000:00:02.0 bcs0: context:96 retire runtime: { total:32084ns, avg:32084ns }
<0> [301.672862] kms_curs-2482 2..... 182361619us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:97 active
<0> [301.672945] kms_curs-2482 2..... 182362713us : intel_timeline_pin: 0000:00:02.0 timeline:97 using HWSP offset:fedc8000
<0> [301.673025] kms_curs-2482 2..... 182363104us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:97 pin ring:{start:fecfa000, head:0000, tail:0000}
<0> [301.673117] kms_curs-2482 2..... 182363851us : __i915_request_commit: 0000:00:02.0 bcs0: fence 97:2, current 0
<0> [301.673206] kms_curs-2482 2D..1. 182363855us : __i915_request_submit: 0000:00:02.0 bcs0: fence 97:2, current 0
<0> [301.673295] kms_curs-2482 2..... 182363895us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:39, current 38
<0> [301.673383] kms_curs-2482 2D..1. 182363897us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:39, current 38
<0> [301.673470] kms_curs-2482 2..... 182363912us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:97:2
<0> [301.673558] kworker/-180 18..... 182364071us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 97:2, current 2
<0> [301.673637] kworker/-2376 8..... 182364437us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:97 unpin
<0> [301.673716] kworker/-2376 8..... 182364516us : __intel_context_retire: 0000:00:02.0 bcs0: context:97 retire runtime: { total:80496ns, avg:80496ns }
<0> [301.673796] kms_curs-2482 2..... 182365270us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:98 active
<0> [301.673880] kms_curs-2482 2..... 182366530us : intel_timeline_pin: 0000:00:02.0 timeline:98 using HWSP offset:fedc6000
<0> [301.673959] kms_curs-2482 2..... 182366882us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:98 pin ring:{start:fedc9000, head:0000, tail:0000}
<0> [301.674051] kms_curs-2482 2..... 182367736us : __i915_request_commit: 0000:00:02.0 bcs0: fence 98:2, current 0
<0> [301.674139] kms_curs-2482 2D..1. 182367739us : __i915_request_submit: 0000:00:02.0 bcs0: fence 98:2, current 0
<0> [301.674228] kms_curs-2482 2..... 182367776us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:40, current 39
<0> [301.674316] kms_curs-2482 2D..1. 182367779us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:40, current 39
<0> [301.674400] kms_curs-2482 2..... 182367793us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:98:2
<0> [301.674488] kworker/-180 18..... 182368059us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 98:2, current 2
<0> [301.674567] kworker/-2376 8..... 182368453us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:98 unpin
<0> [301.674645] kworker/-2376 8..... 182368538us : __intel_context_retire: 0000:00:02.0 bcs0: context:98 retire runtime: { total:79196ns, avg:79196ns }
<0> [301.674726] kms_curs-2482 2..... 182369673us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:99 active
<0> [301.674810] kms_curs-2482 2..... 182370826us : intel_timeline_pin: 0000:00:02.0 timeline:99 using HWSP offset:fedc0000
<0> [301.674890] kms_curs-2482 2..... 182371356us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:99 pin ring:{start:fedc2000, head:0000, tail:0000}
<0> [301.674982] kms_curs-2482 2..... 182372056us : __i915_request_commit: 0000:00:02.0 bcs0: fence 99:2, current 0
<0> [301.675069] kms_curs-2482 2D..1. 182372059us : __i915_request_submit: 0000:00:02.0 bcs0: fence 99:2, current 0
<0> [301.675159] kms_curs-2482 2..... 182372100us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:41, current 40
<0> [301.675247] kms_curs-2482 2D..1. 182372102us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:41, current 40
<0> [301.675330] kms_curs-2482 2..... 182372118us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:99:2
<0> [301.675417] kworker/-180 18..... 182372485us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 99:2, current 2
<0> [301.675499] kworker/-2376 8..... 182372714us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:99 unpin
<0> [301.675578] kworker/-2376 8..... 182372850us : __intel_context_retire: 0000:00:02.0 bcs0: context:99 retire runtime: { total:128960ns, avg:128960ns }
<0> [301.675658] kms_curs-2482 2..... 182374036us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9a active
<0> [301.675742] kms_curs-2482 2..... 182374720us : intel_timeline_pin: 0000:00:02.0 timeline:9a using HWSP offset:fedb8000
<0> [301.675820] kms_curs-2482 2..... 182375065us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9a pin ring:{start:fedba000, head:0000, tail:0000}
<0> [301.675901] kms_curs-2482 2..... 182375075us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9a unpin
<0> [301.675980] kms_curs-2482 2.l... 182375323us : __intel_context_retire: 0000:00:02.0 bcs0: context:9a retire runtime: { total:0ns, avg:0ns }
<0> [301.676059] kms_curs-2482 2.l... 182375326us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9a active
<0> [301.676146] kms_curs-2482 2.l... 182375327us : intel_timeline_pin: 0000:00:02.0 timeline:9a using HWSP offset:fedb8000
<0> [301.676224] kms_curs-2482 2.l... 182375329us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9a pin ring:{start:fedba000, head:0000, tail:0000}
<0> [301.676316] kms_curs-2482 2.l... 182375951us : __i915_request_commit: 0000:00:02.0 bcs0: fence 9a:2, current 0
<0> [301.676404] kms_curs-2482 2Dl.1. 182375955us : __i915_request_submit: 0000:00:02.0 bcs0: fence 9a:2, current 0
<0> [301.676496] kms_curs-2482 2..... 182376067us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:42, current 41
<0> [301.676584] kms_curs-2482 2D..1. 182376070us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:42, current 41
<0> [301.676674] kworker/-180 18..... 182376161us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 9a:2, current 2
<0> [301.676754] kms_curs-2482 2..... 182377840us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9b active
<0> [301.676838] kms_curs-2482 2..... 182378821us : intel_timeline_pin: 0000:00:02.0 timeline:9b using HWSP offset:fedc7000
<0> [301.676917] kms_curs-2482 2..... 182379216us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9b pin ring:{start:fedb4000, head:0000, tail:0000}
<0> [301.676998] kms_curs-2482 2..... 182379226us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9b unpin
<0> [301.677076] kms_curs-2482 2..... 182379292us : __intel_context_retire: 0000:00:02.0 bcs0: context:9b retire runtime: { total:0ns, avg:0ns }
<0> [301.677157] kms_curs-2482 2..... 182379294us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9b active
<0> [301.677241] kms_curs-2482 2..... 182379295us : intel_timeline_pin: 0000:00:02.0 timeline:9b using HWSP offset:fedc7000
<0> [301.677320] kms_curs-2482 2..... 182379297us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9b pin ring:{start:fedb4000, head:0000, tail:0000}
<0> [301.677401] kms_curs-2482 2..... 182379306us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9b unpin
<0> [301.677484] kms_curs-2482 2..... 182379371us : __intel_context_retire: 0000:00:02.0 bcs0: context:9b retire runtime: { total:0ns, avg:0ns }
<0> [301.677564] kms_curs-2482 2..... 182379373us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9b active
<0> [301.677647] kms_curs-2482 2..... 182379373us : intel_timeline_pin: 0000:00:02.0 timeline:9b using HWSP offset:fedc7000
<0> [301.677725] kms_curs-2482 2..... 182379375us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9b pin ring:{start:fedb4000, head:0000, tail:0000}
<0> [301.677816] kms_curs-2482 2..... 182380045us : __i915_request_commit: 0000:00:02.0 bcs0: fence 9b:2, current 0
<0> [301.677905] kms_curs-2482 2D..1. 182380048us : __i915_request_submit: 0000:00:02.0 bcs0: fence 9b:2, current 0
<0> [301.677993] kms_curs-2482 2..... 182380086us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:43, current 42
<0> [301.678082] kms_curs-2482 2D..1. 182380089us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:43, current 42
<0> [301.678166] kms_curs-2482 2..... 182380103us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:9b:2
<0> [301.678253] kworker/-180 18..... 182380240us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 9b:2, current 2
<0> [301.678333] kms_curs-2482 2..... 182381395us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9c active
<0> [301.678417] kms_curs-2482 2..... 182382526us : intel_timeline_pin: 0000:00:02.0 timeline:9c using HWSP offset:feda7000
<0> [301.678504] kms_curs-2482 2..... 182382852us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9c pin ring:{start:fedc9000, head:0000, tail:0000}
<0> [301.678586] kms_curs-2482 2..... 182382868us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9c unpin
<0> [301.678664] kms_curs-2482 2..... 182382934us : __intel_context_retire: 0000:00:02.0 bcs0: context:9c retire runtime: { total:0ns, avg:0ns }
<0> [301.678743] kms_curs-2482 2..... 182382937us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9c active
<0> [301.678827] kms_curs-2482 2..... 182382937us : intel_timeline_pin: 0000:00:02.0 timeline:9c using HWSP offset:feda7000
<0> [301.678905] kms_curs-2482 2..... 182382940us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9c pin ring:{start:fedc9000, head:0000, tail:0000}
<0> [301.678986] kms_curs-2482 2..... 182382947us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9c unpin
<0> [301.679065] kms_curs-2482 2..... 182383012us : __intel_context_retire: 0000:00:02.0 bcs0: context:9c retire runtime: { total:0ns, avg:0ns }
<0> [301.679144] kms_curs-2482 2..... 182383014us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9c active
<0> [301.679228] kms_curs-2482 2..... 182383015us : intel_timeline_pin: 0000:00:02.0 timeline:9c using HWSP offset:feda7000
<0> [301.679306] kms_curs-2482 2..... 182383017us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9c pin ring:{start:fedc9000, head:0000, tail:0000}
<0> [301.679398] kms_curs-2482 2..... 182383824us : __i915_request_commit: 0000:00:02.0 bcs0: fence 9c:2, current 0
<0> [301.679494] kms_curs-2482 2D..1. 182383827us : __i915_request_submit: 0000:00:02.0 bcs0: fence 9c:2, current 0
<0> [301.679582] kms_curs-2482 2..... 182384013us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:7, current 43
<0> [301.679671] kms_curs-2482 2..... 182384014us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:7, current 43
<0> [301.679761] kms_curs-2482 2..... 182384015us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:44, current 43
<0> [301.679849] kms_curs-2482 2D..1. 182384018us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:44, current 43
<0> [301.679929] kms_curs-2482 2..... 182386251us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9d active
<0> [301.680014] kms_curs-2482 2..... 182386978us : intel_timeline_pin: 0000:00:02.0 timeline:9d using HWSP offset:fedc8000
<0> [301.680094] kms_curs-2482 2..... 182387318us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9d pin ring:{start:fedc2000, head:0000, tail:0000}
<0> [301.680185] kms_curs-2482 2.l... 182388416us : __i915_request_commit: 0000:00:02.0 bcs0: fence 9d:2, current 0
<0> [301.680272] kms_curs-2482 2Dl.1. 182388420us : __i915_request_submit: 0000:00:02.0 bcs0: fence 9d:2, current 0
<0> [301.680360] kms_curs-2482 2..... 182388585us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:8, current 44
<0> [301.680454] kms_curs-2482 2..... 182388585us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:8, current 44
<0> [301.680542] kms_curs-2482 2..... 182388713us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:45, current 44
<0> [301.680630] kms_curs-2482 2D..1. 182388715us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:45, current 44
<0> [301.680713] kms_curs-2482 2..... 182388730us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:9d:2
<0> [301.680801] kworker/-180 18..... 182388826us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 9d:2, current 2
<0> [301.680880] kworker/-541 17..... 182388999us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9d unpin
<0> [301.680960] kworker/-541 17..... 182389071us : __intel_context_retire: 0000:00:02.0 bcs0: context:9d retire runtime: { total:38220ns, avg:38220ns }
<0> [301.681041] kms_curs-2482 2..... 182390224us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9e active
<0> [301.681126] kms_curs-2482 2..... 182391094us : intel_timeline_pin: 0000:00:02.0 timeline:9e using HWSP offset:fedc6000
<0> [301.681205] kms_curs-2482 2..... 182391439us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:9e pin ring:{start:fedb4000, head:0000, tail:0000}
<0> [301.681297] kms_curs-2482 2..... 182392147us : __i915_request_commit: 0000:00:02.0 bcs0: fence 9e:2, current 0
<0> [301.681386] kms_curs-2482 2D..1. 182392150us : __i915_request_submit: 0000:00:02.0 bcs0: fence 9e:2, current 0
<0> [301.681482] kms_curs-2482 2..... 182392195us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:9, current 45
<0> [301.681571] kms_curs-2482 2..... 182392196us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:9, current 45
<0> [301.681659] kms_curs-2482 2..... 182392201us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:46, current 45
<0> [301.681747] kms_curs-2482 2D..1. 182392204us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:46, current 45
<0> [301.681832] kms_curs-2482 2..... 182392218us : intel_rps_boost.part.0: 0000:00:02.0 boost fence:9e:2
<0> [301.681919] kworker/-180 18..... 182392415us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 9e:2, current 2
<0> [301.681999] kworker/-541 17..... 182392537us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:9e unpin
<0> [301.682079] kworker/-541 17..... 182392608us : __intel_context_retire: 0000:00:02.0 bcs0: context:9e retire runtime: { total:37440ns, avg:37440ns }
Created at 2026-04-07 23:57:58