Results for igt@gem_exec_nop@basic-parallel

Result: Abort 112 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-8
Igt-Version 
IGT-Version: 2.4-g2a9f1c1ae (x86_64) (Linux: 7.0.0-rc7-CI_DRM_18293-gc278536ae69b+ x86_64)
Out 
Using IGT_SRANDOM=1775691495 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): 36.898us
rcs0: 3967 cycles, 504.161us
bcs0: 3878 cycles, 515.852us
vcs0: 4437 cycles, 450.783us
vcs1: 3910 cycles, 511.594us
vecs0: 4308 cycles, 464.263us
ccs0: 4107 cycles, 487.032us
Subtest basic-parallel: SUCCESS (16.107s)
runner: This test was killed due to a kernel taint (0x42c4).
runner: This test was killed due to exceeding disk usage limit. (Used 15663402 bytes, limit 10485760)

This test caused an abort condition: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         2467 [max: 8192]
 dynamic-keys:                          332
 direct dependencies:                 27836 [max: 524288]
 indirect dependencies:              261924
 all direct dependencies:            621716
 dependency chains:                   40476 [max: 524288]
 dependency chain hlocks used:       172050 [max: 2621440]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     303
 in-softirq chains:                     846
 in-process chains:                   39327
 stack-trace entries:                306905 [max: 524288]
 number of stack traces:              13676
 number of stack hash chains:          9330
 combined max dependencies:      1536553472
 hardirq-safe locks:                    127
 hardirq-unsafe locks:                 1414
 softirq-safe locks:                    261
 softirq-unsafe locks:                 1310
 irq-safe locks:                        293
 irq-unsafe locks:                     1414
 hardirq-read-safe locks:                 4
 hardirq-read-unsafe locks:             515
 softirq-read-safe locks:                 9
 softirq-read-unsafe locks:             512
 irq-read-safe locks:                     9
 irq-read-unsafe locks:                 515
 uncategorized locks:                   402
 unused locks:                            1
 max locking depth:                      16
 max bfs queue depth:                   512
 max lock class index:                 2466
 debug_locks:                             0

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

Scroll to first warning
<6> [270.920593] Console: switching to colour dummy device 80x25
<6> [270.920658] [IGT] gem_exec_nop: executing
<7> [270.924530] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [270.925827] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [270.940761] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [270.941801] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [270.948513] [IGT] gem_exec_nop: starting subtest basic-parallel
<7> [270.951919] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [271.235133] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [271.275060] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [271.396650] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX B/DDI B/PHY B: Too many retries, giving up. First error: -6
<7> [271.421483] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC1/DDI TC1/PHY TC1: Too many retries, giving up. First error: -6
<7> [271.445994] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC3/DDI TC3/PHY TC3: Too many retries, giving up. First error: -6
<7> [271.470283] i915 0000:00:02.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC4/DDI TC4/PHY TC4: Too many retries, giving up. First error: -6
<7> [271.558628] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [271.597871] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [271.662300] 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> [272.423446] 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> [272.486755] 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> [272.488805] 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> [273.406049] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [274.010525] 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> [274.011297] 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> [274.011861] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [274.012552] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [274.013125] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [275.134056] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [277.349672] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [279.081205] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [281.365481] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [283.102221] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [283.388842] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [283.451639] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [285.507827] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [287.055786] [IGT] gem_exec_nop: finished subtest basic-parallel, SUCCESS
<6> [287.162606] [IGT] gem_exec_nop: exiting, ret=0
<7> [287.164499] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<3> [287.171933] guc_lrc_desc_unpin:3418 GEM_BUG_ON(!ctx_id_mapped(guc, ce->guc_id.id))
<0> [287.172077] Dumping ftrace buffer:
<0> [287.172097] ---------------------------------
<0> [287.172621] kworker/-1019 17..... 47188391us : i915_request_retire.part.0: 0000:00:02.0 rcs'0: fence 5:1, current 1
<0> [287.172995] kworker/-1019 17..... 47188691us : i915_request_retire.part.0: 0000:00:02.0 bcs'0: fence 6:1, current 1
<0> [287.173328] kworker/-1019 17..... 47189042us : i915_request_retire.part.0: 0000:00:02.0 ccs'0: fence 7:1, current 1
<0> [287.173680] kworker/-1019 17..... 47256610us : i915_request_retire.part.0: 0000:00:02.0 other'6: fence 14:2, current 2
<0> [287.174022] kworker/-1019 17..... 47257952us : i915_request_retire.part.0: 0000:00:02.0 vcs'0: fence c:1, current 1
<0> [287.174209] kworker/-1019 17..... 47258491us : i915_request_retire.part.0: 0000:00:02.0 vcs'2: fence d:1, current 1
<0> [287.174387] kworker/-1019 17..... 47258994us : i915_request_retire.part.0: 0000:00:02.0 vecs'0: fence e:1, current 1
<0> [287.174568] kworker/-1019 17..... 47259702us : i915_request_retire.part.0: 0000:00:02.0 other'6: fence f:1, current 1
<0> [287.174736] kworker/-1085 8..... 47290601us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:13 unpin
<0> [287.174892] kworker/-1085 8..... 47290707us : __intel_context_retire: 0000:00:02.0 vecs0: context:13 retire runtime: { total:12116ns, avg:12116ns }
<0> [287.175126] kworker/-1085 8..... 47290731us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:12 unpin
<0> [287.175310] kworker/-1085 8..... 47290825us : __intel_context_retire: 0000:00:02.0 vcs1: context:12 retire runtime: { total:12844ns, avg:12844ns }
<0> [287.175477] kworker/-1085 8..... 47290841us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:11 unpin
<0> [287.175631] kworker/-1085 8..... 47290934us : __intel_context_retire: 0000:00:02.0 vcs0: context:11 retire runtime: { total:12792ns, avg:12792ns }
<0> [287.175751] kworker/-1085 8..... 47290971us : __intel_context_do_unpin.part.0: 0000:00:02.0 other0: context:14 unpin
<0> [287.175829] kworker/-1085 8..... 47291063us : __intel_context_retire: 0000:00:02.0 other0: context:14 retire runtime: { total:10660ns, avg:10660ns }
<0> [287.175916] kworker/-175 8..... 47291113us : __engine_park: 0000:00:02.0 vecs0: parked
<0> [287.175994] kworker/-175 8..... 47291151us : __engine_park: 0000:00:02.0 vcs1: parked
<0> [287.176103] kworker/-175 8..... 47291167us : __engine_park: 0000:00:02.0 vcs0: parked
<0> [287.176191] i915_mod-2078 10.l... 47313974us : __gt_unpark: 0000:00:02.0
<0> [287.176269] kworker/-376 11..... 47315057us : __gt_park: 0000:00:02.0
<0> [287.176358] kworker/-1019 17..... 47396665us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:1, current 1
<0> [287.176448] kworker/-1019 17..... 47397028us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.176558] kworker/-1019 17..... 47397547us : __gt_park: 0000:00:02.0
<0> [287.176812] kworker/-1019 17..... 47403026us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:2, current 2
<0> [287.176945] kworker/-1019 17..... 47403036us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.177115] kworker/-1019 17..... 47403073us : __gt_park: 0000:00:02.0
<0> [287.177275] kworker/-1019 17..... 47423749us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:3, current 3
<0> [287.177359] kworker/-1019 17..... 47423767us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.177448] kworker/-1019 17..... 47423798us : __gt_park: 0000:00:02.0
<0> [287.177532] kworker/-1019 17..... 47440022us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:4, current 4
<0> [287.177606] kworker/-1019 17..... 47440036us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.177679] kworker/-1019 17..... 47440073us : __gt_park: 0000:00:02.0
<0> [287.177762] kworker/-1019 17..... 47469479us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:5, current 5
<0> [287.177839] kworker/-1019 17..... 47469488us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.177923] kworker/-1019 17..... 47469514us : __gt_park: 0000:00:02.0
<0> [287.178017] kworker/-1019 17..... 47549231us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:6, current 6
<0> [287.178092] kworker/-1019 17..... 47549261us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.178165] kworker/-1019 17..... 47549331us : __gt_park: 0000:00:02.0
<0> [287.178247] kworker/-1019 17..... 47645083us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:7, current 7
<0> [287.178323] kworker/-1019 17..... 47645130us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.178410] kworker/-1019 17..... 47645236us : __gt_park: 0000:00:02.0
<0> [287.178497] kworker/-1019 17..... 47658816us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:8, current 8
<0> [287.178600] kworker/-1019 17..... 47658836us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.178724] kworker/-1019 17..... 47658878us : __gt_park: 0000:00:02.0
<0> [287.178877] kworker/-1019 17..... 47669919us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:9, current 9
<0> [287.179005] kworker/-1019 17..... 47669938us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.179157] kworker/-1019 17..... 47669991us : __gt_park: 0000:00:02.0
<0> [287.179320] kworker/-1019 17..... 47680807us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:10, current 10
<0> [287.179438] kworker/-1019 17..... 47680824us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.179516] kworker/-1019 17..... 47680870us : __gt_park: 0000:00:02.0
<0> [287.179659] kworker/-1019 17..... 47691621us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:11, current 11
<0> [287.179799] kworker/-1019 17..... 47691634us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.179951] kworker/-1019 17..... 47691682us : __gt_park: 0000:00:02.0
<0> [287.180033] kworker/-1019 17..... 47702252us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:12, current 12
<0> [287.180108] kworker/-1019 17..... 47702264us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.180184] kworker/-1019 17..... 47702293us : __gt_park: 0000:00:02.0
<0> [287.180266] kworker/-1019 17..... 47713362us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:13, current 13
<0> [287.180341] kworker/-1019 17..... 47713371us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.180435] kworker/-1019 17..... 47713391us : __gt_park: 0000:00:02.0
<0> [287.180535] kworker/-1019 17..... 47723893us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:14, current 14
<0> [287.180610] kworker/-1019 17..... 47723906us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.180684] kworker/-1019 17..... 47723939us : __gt_park: 0000:00:02.0
<0> [287.180767] kworker/-1019 17..... 47734447us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:15, current 15
<0> [287.180841] kworker/-1019 17..... 47734461us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.180914] kworker/-1019 17..... 47734482us : __gt_park: 0000:00:02.0
<0> [287.180997] kworker/-1019 17..... 47744979us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:16, current 16
<0> [287.181071] kworker/-1019 17..... 47744992us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.181145] kworker/-1019 17..... 47745028us : __gt_park: 0000:00:02.0
<0> [287.181230] kworker/-1019 17..... 47755535us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:17, current 17
<0> [287.181306] kworker/-1019 17..... 47755547us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.181384] kworker/-1019 17..... 47755578us : __gt_park: 0000:00:02.0
<0> [287.181471] kworker/-1019 17..... 47766119us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:18, current 18
<0> [287.181547] kworker/-1019 17..... 47766129us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.181620] kworker/-1019 17..... 47766154us : __gt_park: 0000:00:02.0
<0> [287.181701] kworker/-1019 17..... 47776646us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:19, current 19
<0> [287.181775] kworker/-1019 17..... 47776658us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.181854] kworker/-1019 17..... 47776693us : __gt_park: 0000:00:02.0
<0> [287.181936] kworker/-1019 17..... 47787205us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:20, current 20
<0> [287.182011] kworker/-1019 17..... 47787217us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.182086] kworker/-1019 17..... 47787270us : __gt_park: 0000:00:02.0
<0> [287.182168] kworker/-1019 17..... 47797765us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:21, current 21
<0> [287.182243] kworker/-1019 17..... 47797776us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.182317] kworker/-1019 17..... 47797797us : __gt_park: 0000:00:02.0
<0> [287.182407] kworker/-1019 17..... 47808343us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:22, current 22
<0> [287.182485] kworker/-1019 17..... 47808356us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.182558] kworker/-1019 17..... 47808390us : __gt_park: 0000:00:02.0
<0> [287.182640] kworker/-1019 17..... 47818872us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:23, current 23
<0> [287.182715] kworker/-1019 17..... 47818885us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.182789] kworker/-1019 17..... 47818914us : __gt_park: 0000:00:02.0
<0> [287.182873] kworker/-1019 17..... 47829470us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:24, current 24
<0> [287.182962] kworker/-1019 17..... 47829485us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.183051] kworker/-1019 17..... 47829511us : __gt_park: 0000:00:02.0
<0> [287.183134] kworker/-1019 17..... 47840034us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:25, current 25
<0> [287.183209] kworker/-1019 17..... 47840047us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.183284] kworker/-1019 17..... 47840080us : __gt_park: 0000:00:02.0
<0> [287.183369] kworker/-1019 17..... 47850570us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:26, current 26
<0> [287.183447] kworker/-1019 17..... 47850584us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.183521] kworker/-1019 17..... 47850612us : __gt_park: 0000:00:02.0
<0> [287.183602] kworker/-1019 17..... 47861195us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:27, current 27
<0> [287.183675] kworker/-1019 17..... 47861204us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.183747] kworker/-1019 17..... 47861228us : __gt_park: 0000:00:02.0
<0> [287.183828] kworker/-1019 17..... 47872360us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:28, current 28
<0> [287.183901] kworker/-1019 17..... 47872373us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.183973] kworker/-1019 17..... 47872419us : __gt_park: 0000:00:02.0
<0> [287.184054] kworker/-1019 17..... 47884994us : i915_request_retire.part.0: 0000:00:02.0 other0: fence 10:29, current 29
<0> [287.184128] kworker/-1019 17..... 47885009us : __engine_park: 0000:00:02.0 other0: parked
<0> [287.184201] kworker/-1019 17..... 47885032us : __gt_park: 0000:00:02.0
<0> [287.184276] kworker/-176 10..... 48453822us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [287.184356] kworker/-303 10..... 48456863us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [287.184439] setfont-2104 9..... 48457687us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [287.184523] kworker/-331 9..... 48467835us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [287.184600] kworker/-168 5..... 48497679us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [287.184677] kworker/-305 5..... 48500966us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [287.184749] i915_mod-2078 17..... 48544814us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:18 pin ring:{start:fedc3000, head:0000, tail:0000}
<0> [287.184837] i915_mod-2078 17..... 48546225us : __i915_request_commit: 0000:00:02.0 rcs0: fence 18:2, current 0
<0> [287.184922] i915_mod-2078 17D..1. 48546234us : __i915_request_submit: 0000:00:02.0 rcs0: fence 18:2, current 0
<0> [287.184995] i915_mod-2078 17..... 48546435us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:19 active
<0> [287.185083] i915_mod-2078 17..... 48547366us : intel_timeline_pin: 0000:00:02.0 timeline:19 using HWSP offset:fedbd000
<0> [287.185158] i915_mod-2078 17..... 48547785us : __engine_unpark: 0000:00:02.0 bcs0:
<0> [287.185231] i915_mod-2078 17..... 48547797us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:1, *hwsp:1, ring:88 }
<0> [287.185305] i915_mod-2078 17..... 48547798us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:19 pin ring:{start:fedbf000, head:0000, tail:0000}
<0> [287.185398] i915_mod-2078 17..... 48548283us : __i915_request_commit: 0000:00:02.0 bcs0: fence 19:2, current 0
<0> [287.185486] i915_mod-2078 17D..1. 48548289us : __i915_request_submit: 0000:00:02.0 bcs0: fence 19:2, current 0
<0> [287.185560] i915_mod-2078 17..... 48548328us : __gt_unpark: 0000:00:02.0
<0> [287.185642] kworker/-1019 17..... 48555123us : i915_request_retire.part.0: 0000:00:02.0 ccs0: fence 1d:2, current 2
<0> [287.185714] kworker/-160 18..... 48556738us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:1d unpin
<0> [287.185805] kworker/-160 18..... 48556833us : __intel_context_retire: 0000:00:02.0 ccs0: context:1d retire runtime: { total:76908ns, avg:76908ns }
<0> [287.185891] kworker/-171 18..... 48557068us : __engine_park: 0000:00:02.0 ccs0: parked
<0> [287.185973] kworker/-224 21..... 49657883us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 1a:2, current 2
<0> [287.186059] kworker/-105 20..... 49658883us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 17:2, current 2
<0> [287.186144] kworker/-224 21..... 49658927us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 1b:2, current 2
<0> [287.186228] kworker/-224 21..... 49658993us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 1c:2, current 2
<0> [287.186313] kworker/-105 20.l... 49662118us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 5:2, current 2
<0> [287.186402] kworker/-105 20..... 49665017us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 18:2, current 2
<0> [287.186490] kworker/-105 20..... 49665200us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 19:2, current 2
<0> [287.186565] kworker/-175 8..... 57272624us : __gt_park: 0000:00:02.0
<0> [287.186647] kworker/-1019 17..... 57455007us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 20:2, current 2
<0> [287.186720] kworker/-1083 6..... 57490774us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:20 unpin
<0> [287.186793] kworker/-1083 6..... 57490867us : __intel_context_retire: 0000:00:02.0 rcs0: context:20 retire runtime: { total:122824ns, avg:122824ns }
<0> [287.186869] kworker/-174 6..... 57490891us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [287.186942] kworker/-174 6..... 57490933us : __gt_park: 0000:00:02.0
<0> [287.187013] gem_exec-2354 12..... 57491813us : __gt_unpark: 0000:00:02.0
<0> [287.187085] gem_exec-2354 10..... 57493178us : __gt_park: 0000:00:02.0
<0> [287.187156] gem_exec-2354 10..... 57515964us : __gt_unpark: 0000:00:02.0
<0> [287.187230] gem_exec-2354 10..... 57516470us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:21 active
<0> [287.187310] gem_exec-2354 10..... 57519295us : intel_timeline_pin: 0000:00:02.0 timeline:21 using HWSP offset:fedd0000
<0> [287.187387] gem_exec-2354 10..... 57521163us : __engine_unpark: 0000:00:02.0 bcs0:
<0> [287.187461] gem_exec-2354 10..... 57521174us : __engine_unpark: 0000:00:02.0 bcs0: context:6 reset { seqno:1, *hwsp:1, ring:88 }
<0> [287.187537] gem_exec-2354 10..... 57521176us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:21 pin ring:{start:fedc3000, head:0000, tail:0000}
<0> [287.187624] gem_exec-2354 10..... 57522339us : __i915_request_commit: 0000:00:02.0 bcs0: fence 21:2, current 0
<0> [287.187708] gem_exec-2354 10D..1. 57522344us : __i915_request_submit: 0000:00:02.0 bcs0: fence 21:2, current 0
<0> [287.187799] kworker/-1019 17..... 57522784us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 21:2, current 2
<0> [287.187871] kworker/-1083 6..... 57558472us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:21 unpin
<0> [287.187943] kworker/-1083 6..... 57558570us : __intel_context_retire: 0000:00:02.0 bcs0: context:21 retire runtime: { total:31044ns, avg:31044ns }
<0> [287.188018] kworker/-174 6..... 57558592us : __engine_park: 0000:00:02.0 bcs0: parked
<0> [287.188091] kworker/-174 6..... 57558632us : __gt_park: 0000:00:02.0
<0> [287.188109] gem_exec-2354 10...1. 57559225us : tracing_mark_write: gem_exec_fence: finished subtest bcs0, SUCCESS
<0> [287.188193] kworker/-1019 17..... 57582448us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 22:2, current 2
<0> [287.188266] kworker/-1083 6..... 57618487us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:22 unpin
<0> [287.188358] kworker/-1083 6..... 57618594us : __intel_context_retire: 0000:00:02.0 vcs0: context:22 retire runtime: { total:13520ns, avg:13520ns }
<0> [287.188451] kworker/-174 6..... 57618631us : __engine_park: 0000:00:02.0 vcs0: parked
<0> [287.188525] kworker/-174 6..... 57618676us : __gt_park: 0000:00:02.0
<0> [287.188606] kworker/-1019 17..... 57634604us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 23:2, current 2
<0> [287.188682] kworker/-168 5..... 57635895us : __gt_park: 0000:00:02.0
<0> [287.188750] kworker/-1083 6..... 57669170us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:23 unpin
<0> [287.188822] kworker/-1083 6..... 57669268us : __intel_context_retire: 0000:00:02.0 vcs1: context:23 retire runtime: { total:17316ns, avg:17316ns }
<0> [287.188897] kworker/-174 6..... 57669289us : __engine_park: 0000:00:02.0 vcs1: parked
<0> [287.188969] kworker/-174 6..... 57669322us : __gt_park: 0000:00:02.0
<0> [287.189051] kworker/-1019 17..... 57685767us : i915_request_retire.part.0: 0000:00:02.0 vecs0: fence 24:2, current 2
<0> [287.189127] kworker/-168 5..... 57686911us : __gt_park: 0000:00:02.0
<0> [287.189196] kworker/-1083 6..... 57719993us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:24 unpin
<0> [287.189269] kworker/-1083 6..... 57720096us : __intel_context_retire: 0000:00:02.0 vecs0: context:24 retire runtime: { total:14872ns, avg:14872ns }
<0> [287.189345] kworker/-174 6..... 57720115us : __engine_park: 0000:00:02.0 vecs0: parked
<0> [287.189422] kworker/-174 6..... 57720147us : __gt_park: 0000:00:02.0
<0> [287.189506] kworker/-1019 17..... 57737256us : i915_request_retire.part.0: 0000:00:02.0 ccs0: fence 25:2, current 2
<0> [287.189579] kworker/-1083 6..... 57772620us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:25 unpin
<0> [287.189651] kworker/-1083 6..... 57772778us : __intel_context_retire: 0000:00:02.0 ccs0: context:25 retire runtime: { total:124124ns, avg:124124ns }
<0> [287.189726] kworker/-174 6..... 57772803us : __engine_park: 0000:00:02.0 ccs0: parked
<0> [287.189797] kworker/-174 6..... 57772840us : __gt_park: 0000:00:02.0
<0> [287.189873] kworker/-1083 6..... 58275466us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:26 unpin
<0> [287.189945] kworker/-1083 6..... 58275562us : __intel_context_retire: 0000:00:02.0 rcs0: context:26 retire runtime: { total:2520128ns, avg:2520128ns }
<0> [287.190021] kworker/-174 6..... 58275591us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [287.190090] kworker/-1083 6..... 58276552us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:28 unpin
<0> [287.190162] kworker/-1083 6.l... 58276651us : __intel_context_retire: 0000:00:02.0 bcs0: context:28 retire runtime: { total:902044ns, avg:902044ns }
<0> [287.190237] kworker/-174 6..... 58276703us : __engine_park: 0000:00:02.0 bcs0: parked
<0> [287.190306] kworker/-1083 6..... 58284386us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:30 unpin
<0> [287.190390] kworker/-1083 6.l... 58284477us : __intel_context_retire: 0000:00:02.0 ccs0: context:30 retire runtime: { total:50562720ns, avg:50562720ns }
<0> [287.190470] kworker/-174 6..... 58284724us : __engine_park: 0000:00:02.0 ccs0: parked
<0> [287.190543] kworker/-105 20..... 59659862us : __gt_park: 0000:00:02.0
<0> [287.190617] gem_exec-2354 18..... 59693157us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [287.190695] kworker/-276 18..... 59706936us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [287.190773] gem_exec-2354 10..... 59710577us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes
<0> [287.190850] kworker/-303 10..... 59718662us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no
<0> [287.190956] kworker/-160 0..... 60179151us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:32 unpin
<0> [287.191050] kworker/-160 0..... 60179249us : __intel_context_retire: 0000:00:02.0 rcs0: context:32 retire runtime: { total:54912ns, avg:54912ns }
<0> [287.191125] kworker/-11 0..... 60179308us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [287.191198] kworker/-160 0..... 60188145us : __gt_unpark: 0000:00:02.0
<0> [287.191266] kworker/-160 17..... 60453434us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:34 unpin
<0> [287.191340] kworker/-160 17..... 60453508us : __intel_context_retire: 0000:00:02.0 rcs0: context:34 retire runtime: { total:60476ns, avg:60476ns }
<0> [287.191420] kworker/-160 17..... 60453843us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:35 unpin
<0> [287.191494] kworker/-160 17..... 60453929us : __intel_context_retire: 0000:00:02.0 rcs0: context:35 retire runtime: { total:85956ns, avg:85956ns }
<0> [287.191569] kworker/-1019 17..... 60453960us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [287.191642] kworker/-1019 17..... 60455256us : __gt_park: 0000:00:02.0
<0> [287.191713] kworker/-160 18..... 60461385us : __gt_unpark: 0000:00:02.0
<0> [287.191784] kworker/-171 18..... 60462583us : __gt_park: 0000:00:02.0
<0> [287.191855] syncobj_-2368 8..... 60609376us : __gt_unpark: 0000:00:02.0
Created at 2026-04-09 01:04:48