Result: 694 Warning(s)
integration-manifest git-log-oneline i915_display_info3 igt_runner3 runtimes3 results3.json results3-i915-load.json i915_display_info_post_exec3 boot3 dmesg3
| Detail | Value |
|---|---|
| Duration | 0.09 seconds |
| Hostname |
shard-mtlp-6 |
| Igt-Version |
IGT-Version: 2.1-gc4a9bee16 (x86_64) (Linux: 6.16.0-CI_DRM_16966-g922b0dea064e+ x86_64) |
| Out |
Starting dynamic subtest: vecs0 Dynamic subtest vecs0: SUCCESS (0.090s) |
| Err |
Starting dynamic subtest: vecs0 Dynamic subtest vecs0: SUCCESS (0.090s) |
| Dmesg |
<6> [520.690171] [IGT] gem_exec_schedule: starting dynamic subtest vecs0
<0> [520.690225] gem_exec-2386 20..... 287529413us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52930, current 52931
<0> [520.690357] gem_exec-2386 20..... 287529436us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:52932, current 52932
<0> [520.690489] gem_exec-2393 0..... 287529521us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53000, current 52934
<0> [520.690618] gem_exec-2385 0..... 287534677us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53004, current 53024
<0> [520.690749] gem_exec-2385 0..... 287534682us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53006, current 53024
<7> [520.690725] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.690878] gem_exec-2385 0..... 287534687us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53008, current 53024
<0> [520.690988] gem_exec-2385 0..... 287534690us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53010, current 53025
<0> [520.691119] gem_exec-2385 0..... 287534692us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53012, current 53025
<0> [520.691248] gem_exec-2385 0..... 287534694us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53014, current 53025
<0> [520.691377] gem_exec-2385 0..... 287534696us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53016, current 53025
<0> [520.691506] gem_exec-2385 0..... 287534699us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53018, current 53025
<0> [520.691636] gem_exec-2385 0..... 287534701us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53020, current 53026
<0> [520.691765] gem_exec-2385 0..... 287534722us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53022, current 53027
<0> [520.691895] gem_exec-2385 0..... 287534726us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53024, current 53027
<0> [520.692024] gem_exec-2385 0..... 287534729us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53026, current 53027
<0> [520.692154] gem_exec-2385 0..... 287534731us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53028, current 53028
<0> [520.692284] gem_exec-2399 21..... 287534769us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53034, current 53030
<0> [520.692414] gem_exec-2399 21D..1. 287534790us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53034, current 53031
<0> [520.692543] gem_exec-2399 21..... 287534839us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53030, current 53032
<0> [520.692667] gem_exec-2399 21..... 287535709us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:583 active
<0> [520.692794] gem_exec-2399 21..... 287535719us : intel_timeline_pin: 0000:00:02.0 timeline:583 using HWSP offset:f8cfa000
<0> [520.692915] gem_exec-2399 21..... 287535739us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:583 pin ring:{start:f8df9000, head:0210, tail:0210}
<0> [520.693052] gem_exec-2399 21..... 287535871us : __i915_request_commit: 0000:00:02.0 vcs1: fence 583:8, current 6
<0> [520.693182] gem_exec-2399 21D..1. 287535885us : __i915_request_submit: 0000:00:02.0 vcs1: fence 583:8, current 6
<0> [520.693313] gem_exec-2399 0..... 287536498us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53084, current 53072
<0> [520.693443] gem_exec-2399 0..... 287536502us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53072, current 53072
<0> [520.693574] gem_exec-2399 0..... 287536909us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53106, current 53078
<0> [520.693697] gem_exec-2371 0..... 287537069us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:501 active
<0> [520.693824] gem_exec-2371 0..... 287537072us : intel_timeline_pin: 0000:00:02.0 timeline:501 using HWSP offset:f9cc7000
<0> [520.693946] gem_exec-2371 0..... 287537078us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:501 pin ring:{start:f9d30000, head:0438, tail:0438}
<0> [520.694084] gem_exec-2371 0..... 287537111us : __i915_request_commit: 0000:00:02.0 ccs0: fence 501:8, current 6
<0> [520.694215] gem_exec-2371 0D..1. 287537115us : __i915_request_submit: 0000:00:02.0 ccs0: fence 501:8, current 6
<0> [520.694346] gem_exec-2369 20..... 287537255us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53118, current 53078
<0> [520.694477] gem_exec-2374 21..... 287537959us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53150, current 53078
<0> [520.694608] gem_exec-2374 21..... 287538245us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence 65d:6, current 8
<0> [520.694740] gem_exec-2374 21..... 287538301us : __i915_request_commit: 0000:00:02.0 vcs0: fence 65d:10, current 8
<0> [520.694871] gem_exec-2374 21D..1. 287538314us : __i915_request_submit: 0000:00:02.0 vcs0: fence 65d:10, current 8
<0> [520.695004] gem_exec-2399 0..... 287540122us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53194, current 53118
<0> [520.695134] gem_exec-2403 0.N... 287546845us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53140, current 53163
<0> [520.695265] gem_exec-2395 0..... 287547605us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53240, current 53186
<0> [520.695395] gem_exec-2395 0D..1. 287547611us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53240, current 53186
<0> [520.695525] gem_exec-2408 0..... 287547769us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53250, current 53192
<0> [520.695655] gem_exec-2408 0D..1. 287547774us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53250, current 53192
<0> [520.695785] gem_exec-2408 0.N... 287547815us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53194, current 53194
<0> [520.695916] gem_exec-2370 0..... 287548660us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53280, current 53214
<0> [520.696048] gem_exec-2370 0D..1. 287548665us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53280, current 53214
<7> [520.696047] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.696171] gem_exec-2370 0..... 287548808us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:2a6 active
<0> [520.696282] gem_exec-2370 0..... 287548812us : intel_timeline_pin: 0000:00:02.0 timeline:2a6 using HWSP offset:fec65000
<0> [520.696403] gem_exec-2370 0..... 287548820us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:2a6 pin ring:{start:fec6c000, head:0210, tail:0210}
<0> [520.696541] gem_exec-2370 0..... 287548861us : __i915_request_commit: 0000:00:02.0 vecs0: fence 2a6:8, current 6
<0> [520.696672] gem_exec-2370 0D..1. 287548866us : __i915_request_submit: 0000:00:02.0 vecs0: fence 2a6:8, current 6
<0> [520.696803] gem_exec-2370 0..... 287549555us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53310, current 53252
<0> [520.696933] gem_exec-2370 0..... 287549559us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53252, current 53252
<0> [520.697068] gem_exec-2409 0..... 287558072us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53410, current 53378
<0> [520.697198] gem_exec-2409 0D..1. 287558079us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53410, current 53378
<0> [520.697329] gem_exec-2409 0..... 287558087us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53378, current 53378
<0> [520.697460] gem_exec-2409 0..... 287558338us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence cfa:2, current 4
<0> [520.697590] gem_exec-2409 0..... 287558361us : __i915_request_commit: 0000:00:02.0 bcs0: fence cfa:6, current 4
<0> [520.697720] gem_exec-2409 0D..1. 287558366us : __i915_request_submit: 0000:00:02.0 bcs0: fence cfa:6, current 4
<0> [520.697850] gem_exec-2409 0..... 287559432us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53452, current 53398
<0> [520.697973] gem_exec-2383 0..... 287560195us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:741 active
<7> [520.697993] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.698100] gem_exec-2383 0..... 287560197us : intel_timeline_pin: 0000:00:02.0 timeline:741 using HWSP offset:f5178000
<0> [520.698202] gem_exec-2383 0..... 287560203us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:741 pin ring:{start:f51dc000, head:02d0, tail:02d0}
<0> [520.698339] gem_exec-2383 0..... 287560238us : __i915_request_commit: 0000:00:02.0 ccs0: fence 741:6, current 4
<0> [520.698470] gem_exec-2383 0D..1. 287560242us : __i915_request_submit: 0000:00:02.0 ccs0: fence 741:6, current 4
<0> [520.698600] gem_exec-2409 0..... 287562940us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53500, current 53440
<0> [520.698730] gem_exec-2409 0..... 287562945us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53440, current 53440
<0> [520.698861] gem_exec-2399 0..... 287566270us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53566, current 53494
<0> [520.698992] gem_exec-2373 0..... 287566877us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:846 active
<0> [520.699128] gem_exec-2373 0..... 287566879us : intel_timeline_pin: 0000:00:02.0 timeline:846 using HWSP offset:f2fbf000
<0> [520.699256] gem_exec-2373 0..... 287566885us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:846 pin ring:{start:f3017000, head:0420, tail:0420}
<0> [520.699402] gem_exec-2373 0..... 287566918us : __i915_request_commit: 0000:00:02.0 bcs0: fence 846:14, current 12
<0> [520.699542] gem_exec-2373 0D..1. 287566921us : __i915_request_submit: 0000:00:02.0 bcs0: fence 846:14, current 12
<0> [520.699682] gem_exec-2375 0..... 287568249us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53630, current 53552
<0> [520.699821] gem_exec-2373 0..... 287571738us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53666, current 53595
<0> [520.699966] gem_exec-2373 0D..1. 287571759us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53666, current 53596
<0> [520.700100] gem_exec-2373 0..... 287571794us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53594, current 53597
<0> [520.700236] gem_exec-2373 0..... 287571802us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53596, current 53598
<0> [520.700375] gem_exec-2373 0..... 287571814us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53598, current 53598
<0> [520.700506] gem_exec-2373 0..... 287572709us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53696, current 53633
<7> [520.700487] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.700637] gem_exec-2373 0..... 287572715us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53634, current 53634
<0> [520.700749] gem_exec-2375 0..... 287579202us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53766, current 53768
<0> [520.700880] gem_exec-2375 0..... 287579219us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53770, current 53768
<0> [520.701013] gem_exec-2375 0D..1. 287579225us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53770, current 53768
<0> [520.701135] gem_exec-2375 0..... 287579356us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:608 active
<0> [520.701261] gem_exec-2375 0..... 287579360us : intel_timeline_pin: 0000:00:02.0 timeline:608 using HWSP offset:f7b8a000
<0> [520.701382] gem_exec-2375 0..... 287579370us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:608 pin ring:{start:f7c81000, head:0370, tail:0370}
<0> [520.701519] gem_exec-2375 0..... 287579418us : __i915_request_commit: 0000:00:02.0 vcs1: fence 608:12, current 10
<0> [520.701650] gem_exec-2375 0D..1. 287579424us : __i915_request_submit: 0000:00:02.0 vcs1: fence 608:12, current 10
<0> [520.701782] gem_exec-2387 0..... 287580196us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53800, current 53774
<0> [520.701913] gem_exec-2387 0..... 287580260us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53802, current 53774
<0> [520.702048] gem_exec-2406 0..... 287580320us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53804, current 53774
<0> [520.702169] gem_exec-2406 0..... 287580397us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:569 active
<0> [520.702296] gem_exec-2406 0..... 287580401us : intel_timeline_pin: 0000:00:02.0 timeline:569 using HWSP offset:f8fd7000
<0> [520.702417] gem_exec-2406 0..... 287580410us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:569 pin ring:{start:f90d1000, head:0210, tail:0210}
<0> [520.702554] gem_exec-2406 0..... 287580451us : __i915_request_commit: 0000:00:02.0 vecs0: fence 569:8, current 6
<0> [520.702686] gem_exec-2406 0D..1. 287580456us : __i915_request_submit: 0000:00:02.0 vecs0: fence 569:8, current 6
<7> [520.702683] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.702818] gem_exec-2406 0..... 287580826us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53836, current 53776
<0> [520.702930] gem_exec-2406 0..... 287580830us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53776, current 53776
<0> [520.703069] gem_exec-2390 0..... 287584900us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53886, current 53840
<0> [520.703200] gem_exec-2390 0D..1. 287584906us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53886, current 53840
<0> [520.703332] gem_exec-2390 0..... 287585147us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53834, current 53842
<0> [520.703463] gem_exec-2390 0..... 287585149us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53836, current 53842
<0> [520.703596] gem_exec-2390 0..... 287585152us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53838, current 53842
<0> [520.703735] gem_exec-2390 0..... 287585154us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53840, current 53842
<0> [520.703873] gem_exec-2390 0..... 287585155us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53842, current 53842
<0> [520.704007] gem_exec-2390 0..... 287585221us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:75b active
<0> [520.704135] gem_exec-2390 0..... 287585225us : intel_timeline_pin: 0000:00:02.0 timeline:75b using HWSP offset:f4db5000
<0> [520.704256] gem_exec-2390 0..... 287585235us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:75b pin ring:{start:f4eb9000, head:05a0, tail:05a0}
<0> [520.704380] gem_exec-2390 0..... 287585249us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:75b unpin
<0> [520.704500] gem_exec-2390 0..... 287585378us : __intel_context_retire: 0000:00:02.0 rcs0: context:75b retire runtime: { total:604836ns, avg:144955ns }
<0> [520.704622] gem_exec-2390 0..... 287585381us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:75b active
<0> [520.704748] gem_exec-2390 0..... 287585382us : intel_timeline_pin: 0000:00:02.0 timeline:75b using HWSP offset:f4db5000
<0> [520.704867] gem_exec-2390 0..... 287585386us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:75b pin ring:{start:f4eb9000, head:05a0, tail:05a0}
<0> [520.704990] gem_exec-2390 0..... 287585397us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:75b unpin
<7> [520.704926] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.705109] gem_exec-2390 0..... 287585527us : __intel_context_retire: 0000:00:02.0 rcs0: context:75b retire runtime: { total:604836ns, avg:144955ns }
<0> [520.705230] gem_exec-2390 0..... 287585530us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:75b active
<0> [520.705356] gem_exec-2390 0..... 287585531us : intel_timeline_pin: 0000:00:02.0 timeline:75b using HWSP offset:f4db5000
<0> [520.705473] gem_exec-2390 0..... 287585534us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:75b pin ring:{start:f4eb9000, head:05a0, tail:05a0}
<0> [520.705610] gem_exec-2390 0..... 287585570us : __i915_request_commit: 0000:00:02.0 rcs0: fence 75b:10, current 8
<0> [520.705742] gem_exec-2390 0D..1. 287585756us : __i915_request_submit: 0000:00:02.0 rcs0: fence 75b:10, current 8
<0> [520.705861] kworker/-545 0..... 287586378us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:6fb unpin
<0> [520.705981] kworker/-545 0..... 287586507us : __intel_context_retire: 0000:00:02.0 rcs0: context:6fb retire runtime: { total:500850ns, avg:112625ns }
<0> [520.706103] kworker/-545 0..... 287586519us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:c00 unpin
<0> [520.706221] kworker/-545 0..... 287586648us : __intel_context_retire: 0000:00:02.0 rcs0: context:c00 retire runtime: { total:228006ns, avg:100223ns }
<0> [520.706342] kworker/-545 0..... 287586656us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:9ff unpin
<0> [520.706460] kworker/-545 0.N... 287586796us : __intel_context_retire: 0000:00:02.0 rcs0: context:9ff retire runtime: { total:355471ns, avg:70702ns }
<0> [520.706596] gem_exec-2394 0..... 287591074us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:53980, current 53940
<0> [520.706730] gem_exec-2394 0D..1. 287591080us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:53980, current 53940
<0> [520.706863] gem_exec-2371 0..... 287591630us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54016, current 53956
<0> [520.706997] gem_exec-2371 0D..1. 287591637us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54016, current 53956
<0> [520.707128] gem_exec-2371 0..... 287591644us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:53956, current 53956
<0> [520.707268] gem_exec-2371 0..... 287591933us : __i915_request_commit: 0000:00:02.0 ccs0: fence 650:10, current 8
<0> [520.707406] gem_exec-2371 0D..1. 287591946us : __i915_request_submit: 0000:00:02.0 ccs0: fence 650:10, current 8
<0> [520.707546] gem_exec-2367 0..... 287593745us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54088, current 54012
<7> [520.707516] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.707686] gem_exec-2367 0..... 287593910us : __i915_request_commit: 0000:00:02.0 bcs0: fence a2c:10, current 8
<0> [520.707819] gem_exec-2367 0Dn.1. 287594384us : __i915_request_submit: 0000:00:02.0 bcs0: fence a2c:10, current 8
<0> [520.707950] gem_exec-2369 0..... 287600843us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54122, current 54122
<0> [520.708081] gem_exec-2369 0..... 287601593us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence 6c6:6, current 8
<0> [520.708212] gem_exec-2369 0..... 287601619us : __i915_request_commit: 0000:00:02.0 vcs1: fence 6c6:10, current 8
<0> [520.708341] gem_exec-2369 0D..1. 287601624us : __i915_request_submit: 0000:00:02.0 vcs1: fence 6c6:10, current 8
<0> [520.708470] gem_exec-2369 0..... 287602403us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54202, current 54196
<0> [520.708600] gem_exec-2386 0..... 287603426us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54256, current 54200
<0> [520.708730] gem_exec-2386 0..... 287603622us : __i915_request_commit: 0000:00:02.0 vcs0: fence d17:4, current 2
<0> [520.708859] gem_exec-2386 0D..1. 287603626us : __i915_request_submit: 0000:00:02.0 vcs0: fence d17:4, current 2
<0> [520.708990] gem_exec-2386 0..... 287610076us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54310, current 54310
<0> [520.709120] gem_exec-2386 0..... 287610095us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54314, current 54310
<0> [520.709251] gem_exec-2386 0D..1. 287610102us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54314, current 54310
<0> [520.709382] gem_exec-2408 0..... 287611456us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54382, current 54326
<0> [520.709513] gem_exec-2390 0..... 287616006us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54438, current 54406
<0> [520.709635] gem_exec-2390 0..... 287616211us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:78c active
<0> [520.709761] gem_exec-2390 0..... 287616214us : intel_timeline_pin: 0000:00:02.0 timeline:78c using HWSP offset:f4722000
<0> [520.709887] gem_exec-2390 0..... 287616223us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:78c pin ring:{start:f4814000, head:02d0, tail:02d0}
<0> [520.710031] gem_exec-2390 0..... 287616267us : __i915_request_commit: 0000:00:02.0 rcs0: fence 78c:6, current 4
<0> [520.710162] gem_exec-2390 0D..1. 287616521us : __i915_request_submit: 0000:00:02.0 rcs0: fence 78c:6, current 4
<7> [520.710096] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.710292] gem_exec-2390 0..... 287616832us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54462, current 54410
<0> [520.710423] gem_exec-2395 0..... 287617134us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54488, current 54410
<0> [520.710554] gem_exec-2390 0..... 287619393us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54520, current 54448
<0> [520.710684] gem_exec-2395 0..... 287622423us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54562, current 54530
<0> [520.710815] gem_exec-2395 0D..1. 287622431us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54562, current 54530
<0> [520.710936] gem_exec-2395 0..... 287622649us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c19 active
<0> [520.711065] gem_exec-2395 0..... 287622651us : intel_timeline_pin: 0000:00:02.0 timeline:c19 using HWSP offset:eaefa000
<0> [520.711185] gem_exec-2395 0..... 287622658us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:c19 pin ring:{start:eafeb000, head:0168, tail:0168}
<0> [520.711321] gem_exec-2395 0..... 287622700us : __i915_request_commit: 0000:00:02.0 ccs0: fence c19:4, current 2
<0> [520.711452] gem_exec-2395 0D..1. 287622705us : __i915_request_submit: 0000:00:02.0 ccs0: fence c19:4, current 2
<0> [520.711583] gem_exec-2395 0..... 287623287us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54612, current 54557
<0> [520.711715] gem_exec-2395 0..... 287623626us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54640, current 54562
<0> [520.711846] gem_exec-2409 0.N... 287628211us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54596, current 54629
<0> [520.711978] gem_exec-2382 0..... 287631340us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54770, current 54698
<0> [520.712109] gem_exec-2403 0..... 287636409us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54820, current 54754
<0> [520.712238] gem_exec-2403 0D..1. 287636414us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54820, current 54754
<0> [520.712360] gem_exec-2403 0..... 287637225us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:a7d active
<0> [520.712486] gem_exec-2403 0..... 287637228us : intel_timeline_pin: 0000:00:02.0 timeline:a7d using HWSP offset:ee492000
<7> [520.712466] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.712608] gem_exec-2403 0..... 287637239us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:a7d pin ring:{start:ee608000, head:0160, tail:0160}
<0> [520.712709] gem_exec-2403 0..... 287637256us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:a7d unpin
<0> [520.712829] gem_exec-2403 0..... 287637384us : __intel_context_retire: 0000:00:02.0 bcs0: context:a7d retire runtime: { total:44732ns, avg:44732ns }
<0> [520.712954] gem_exec-2403 0..... 287637387us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:a7d active
<0> [520.713080] gem_exec-2403 0..... 287637389us : intel_timeline_pin: 0000:00:02.0 timeline:a7d using HWSP offset:ee492000
<0> [520.713198] gem_exec-2403 0..... 287637393us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:a7d pin ring:{start:ee608000, head:0160, tail:0160}
<0> [520.713334] gem_exec-2403 0..... 287637428us : __i915_request_commit: 0000:00:02.0 bcs0: fence a7d:6, current 4
<0> [520.713465] gem_exec-2403 0D..1. 287637433us : __i915_request_submit: 0000:00:02.0 bcs0: fence a7d:6, current 4
<0> [520.713596] gem_exec-2375 0..... 287637741us : __i915_request_commit: 0000:00:02.0 vcs1: fence 58a:10, current 8
<0> [520.713726] gem_exec-2375 0D..1. 287637744us : __i915_request_submit: 0000:00:02.0 vcs1: fence 58a:10, current 8
<0> [520.713856] gem_exec-2390 0..... 287637822us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54804, current 54804
<0> [520.713988] gem_exec-2390 0..... 287637836us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54878, current 54804
<0> [520.714119] gem_exec-2385 0..... 287637907us : __i915_request_commit: 0000:00:02.0 bcs0: fence 2ca:8, current 6
<0> [520.714257] gem_exec-2385 0D..1. 287638141us : __i915_request_submit: 0000:00:02.0 bcs0: fence 2ca:8, current 6
<0> [520.714391] gem_exec-2385 0..... 287638284us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54888, current 54820
<0> [520.714521] gem_exec-2390 0..... 287643948us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54940, current 54882
<0> [520.714651] gem_exec-2390 0D..1. 287643954us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54940, current 54882
<0> [520.714781] gem_exec-2390 0..... 287643974us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54884, current 54884
<0> [520.714911] gem_exec-2374 20..... 287644152us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54944, current 54893
<7> [520.714893] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.715042] gem_exec-2374 20D..1. 287644215us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54944, current 54898
<0> [520.715153] gem_exec-2374 20..... 287644600us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54886, current 54906
<0> [520.715284] gem_exec-2374 20..... 287644625us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54888, current 54906
<0> [520.715415] gem_exec-2374 20..... 287644646us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54890, current 54908
<0> [520.715545] gem_exec-2374 20..... 287644666us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54892, current 54909
<0> [520.715676] gem_exec-2374 20..... 287644890us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54894, current 54921
<0> [520.715806] gem_exec-2374 20..... 287644915us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54896, current 54923
<0> [520.715937] gem_exec-2374 20..... 287644933us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54898, current 54924
<0> [520.716068] gem_exec-2374 20..... 287644953us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54900, current 54924
<0> [520.716198] gem_exec-2374 20..... 287644972us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54902, current 54926
<0> [520.716328] gem_exec-2374 20..... 287644993us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54904, current 54927
<0> [520.716458] gem_exec-2374 20..... 287645013us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54906, current 54928
<0> [520.716588] gem_exec-2374 20..... 287645037us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54908, current 54930
<0> [520.716719] gem_exec-2374 20..... 287645055us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54910, current 54931
<0> [520.716849] gem_exec-2374 20..... 287645076us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54912, current 54932
<0> [520.716981] gem_exec-2374 20..... 287645094us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54914, current 54933
<0> [520.717111] gem_exec-2374 20..... 287645112us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54916, current 54934
<0> [520.717240] gem_exec-2374 20..... 287645129us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54918, current 54935
<7> [520.717180] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.717371] gem_exec-2374 20..... 287645147us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54920, current 54936
<0> [520.717500] gem_exec-2374 20..... 287645165us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54922, current 54937
<0> [520.717630] gem_exec-2374 20..... 287645185us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54924, current 54938
<0> [520.717760] gem_exec-2374 20..... 287645204us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54926, current 54940
<0> [520.717890] gem_exec-2374 20..... 287645222us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54928, current 54941
<0> [520.718022] gem_exec-2374 20..... 287645239us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54930, current 54942
<0> [520.718152] gem_exec-2374 20..... 287645257us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54932, current 54943
<0> [520.718282] gem_exec-2374 20..... 287645278us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54934, current 54944
<0> [520.718413] gem_exec-2374 20..... 287645297us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54936, current 54944
<0> [520.718543] gem_exec-2374 20..... 287645314us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54938, current 54944
<0> [520.718672] gem_exec-2374 20..... 287645331us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54940, current 54944
<0> [520.718804] gem_exec-2385 0..... 287646342us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:54964, current 54944
<0> [520.718935] gem_exec-2385 0D..1. 287646347us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:54964, current 54944
<0> [520.719067] gem_exec-2374 20..... 287646995us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55008, current 54944
<0> [520.719197] gem_exec-2390 0..... 287647255us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55022, current 54950
<0> [520.719327] gem_exec-2395 21..... 287647321us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55024, current 54954
<0> [520.719457] gem_exec-2395 21.N... 287647345us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54952, current 54954
<7> [520.719456] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.719587] gem_exec-2395 21.N... 287647355us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:54954, current 54955
<0> [520.719699] gem_exec-2377 20..... 287647983us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55046, current 54982
<0> [520.719829] gem_exec-2385 0..... 287648085us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55054, current 54982
<0> [520.719952] gem_exec-2385 0..... 287648158us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:c24 active
<0> [520.720079] gem_exec-2385 0..... 287648161us : intel_timeline_pin: 0000:00:02.0 timeline:c24 using HWSP offset:ead4d000
<0> [520.720200] gem_exec-2385 0..... 287648168us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:c24 pin ring:{start:eaee8000, head:00b0, tail:00b0}
<0> [520.720323] gem_exec-2385 0..... 287648185us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:c24 unpin
<0> [520.720442] gem_exec-2385 0.N... 287648257us : __intel_context_retire: 0000:00:02.0 bcs0: context:c24 retire runtime: { total:19928ns, avg:19928ns }
<0> [520.720578] gem_exec-2379 20..... 287652140us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55104, current 55077
<0> [520.720710] gem_exec-2379 20D..1. 287652174us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55104, current 55079
<0> [520.720842] gem_exec-2379 20..... 287652198us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55076, current 55080
<0> [520.720976] gem_exec-2379 20..... 287652218us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55078, current 55082
<0> [520.721107] gem_exec-2379 20..... 287652232us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55080, current 55082
<0> [520.721251] gem_exec-2379 20..... 287652243us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55082, current 55083
<0> [520.721397] gem_exec-2379 20..... 287652254us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55084, current 55084
<0> [520.721543] gem_exec-2366 0..... 287652315us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55106, current 55087
<0> [520.721684] gem_exec-2366 0D..1. 287652330us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55106, current 55088
<7> [520.721662] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.721819] gem_exec-2366 0..... 287652653us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55086, current 55098
<0> [520.721932] gem_exec-2366 0..... 287652660us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55088, current 55098
<0> [520.722066] gem_exec-2366 0..... 287652661us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55090, current 55098
<0> [520.722197] gem_exec-2366 0..... 287652665us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55092, current 55098
<0> [520.722337] gem_exec-2366 0..... 287652667us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55094, current 55098
<0> [520.722476] gem_exec-2366 0..... 287652670us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55096, current 55098
<0> [520.722616] gem_exec-2366 0..... 287652672us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55098, current 55098
<0> [520.722755] gem_exec-2395 21..... 287653318us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55114, current 55114
<0> [520.722892] gem_exec-2395 21..... 287653420us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55118, current 55116
<0> [520.723023] gem_exec-2395 21D..1. 287653449us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55118, current 55116
<0> [520.723145] gem_exec-2366 0..... 287653539us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:a55 active
<0> [520.723272] gem_exec-2366 0..... 287653541us : intel_timeline_pin: 0000:00:02.0 timeline:a55 using HWSP offset:eea89000
<0> [520.723394] gem_exec-2366 0..... 287653547us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:a55 pin ring:{start:eea8a000, head:09d8, tail:09d8}
<0> [520.723517] gem_exec-2366 0..... 287653564us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:a55 unpin
<0> [520.723636] gem_exec-2366 0..... 287653635us : __intel_context_retire: 0000:00:02.0 rcs0: context:a55 retire runtime: { total:1026133ns, avg:275176ns }
<0> [520.723757] gem_exec-2366 0..... 287653638us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:a55 active
<7> [520.723797] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.723883] gem_exec-2366 0..... 287653640us : intel_timeline_pin: 0000:00:02.0 timeline:a55 using HWSP offset:eea89000
<0> [520.723985] gem_exec-2366 0..... 287653644us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:a55 pin ring:{start:eea8a000, head:09d8, tail:09d8}
<0> [520.724122] gem_exec-2366 0..... 287653681us : __i915_request_commit: 0000:00:02.0 rcs0: fence a55:16, current 14
<0> [520.724253] gem_exec-2366 0D..1. 287653877us : __i915_request_submit: 0000:00:02.0 rcs0: fence a55:16, current 14
<0> [520.724373] gem_exec-2395 21..... 287654235us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:be6 active
<0> [520.724500] gem_exec-2395 21..... 287654249us : intel_timeline_pin: 0000:00:02.0 timeline:be6 using HWSP offset:eb575000
<0> [520.724620] gem_exec-2395 21..... 287654276us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:be6 pin ring:{start:eb638000, head:0168, tail:0168}
<0> [520.724758] gem_exec-2382 0..... 287654434us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55156, current 55126
<0> [520.724891] gem_exec-2395 21..... 287654462us : __i915_request_commit: 0000:00:02.0 ccs0: fence be6:4, current 2
<0> [520.725023] gem_exec-2395 21D..1. 287654505us : __i915_request_submit: 0000:00:02.0 ccs0: fence be6:4, current 2
<0> [520.725153] gem_exec-2374 20..... 287654535us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55160, current 55126
<0> [520.725275] gem_exec-2382 0..... 287654714us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:99d active
<0> [520.725401] gem_exec-2382 0..... 287654716us : intel_timeline_pin: 0000:00:02.0 timeline:99d using HWSP offset:f0220000
<0> [520.725521] gem_exec-2382 0..... 287654724us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:99d pin ring:{start:f02e3000, head:0370, tail:0370}
<0> [520.725658] gem_exec-2382 0.N... 287654780us : __i915_request_commit: 0000:00:02.0 vecs0: fence 99d:12, current 10
<0> [520.725790] gem_exec-2382 0DN.1. 287654785us : __i915_request_submit: 0000:00:02.0 vecs0: fence 99d:12, current 10
<0> [520.725921] gem_exec-2382 0..... 287655026us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55174, current 55126
<0> [520.726054] gem_exec-2367 0..... 287655215us : __i915_request_commit: 0000:00:02.0 bcs0: fence 725:10, current 8
<0> [520.726185] gem_exec-2367 0DN.1. 287655222us : __i915_request_submit: 0000:00:02.0 bcs0: fence 725:10, current 8
<7> [520.726236] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.726324] gem_exec-2395 21..... 287655861us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55128, current 55138
<0> [520.726439] gem_exec-2395 21..... 287655991us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55216, current 55140
<0> [520.726569] gem_exec-2395 21..... 287656008us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55130, current 55140
<0> [520.726700] gem_exec-2395 21..... 287656016us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55132, current 55140
<0> [520.726830] gem_exec-2395 21..... 287656023us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55134, current 55140
<0> [520.726962] gem_exec-2395 21..... 287656030us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55136, current 55140
<0> [520.727093] gem_exec-2395 21..... 287656039us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55138, current 55140
<0> [520.727226] gem_exec-2395 21..... 287656049us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55140, current 55140
<0> [520.727365] gem_exec-2395 21..... 287663081us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55262, current 55254
<0> [520.727496] gem_exec-2395 21D..1. 287663102us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55262, current 55254
<0> [520.727626] gem_exec-2395 21..... 287663466us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55256, current 55256
<0> [520.727757] gem_exec-2382 0..... 287663567us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55260, current 55260
<0> [520.727887] gem_exec-2382 0..... 287663586us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55268, current 55260
<0> [520.728017] gem_exec-2382 0D..1. 287663592us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55268, current 55260
<0> [520.728145] gem_exec-2382 0..... 287663772us : intel_rps_boost: 0000:00:02.0 boost fence:298:d7e4
<0> [520.728273] gem_exec-2371 0..... 287664464us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55294, current 55274
<0> [520.728403] gem_exec-2371 0D..1. 287664470us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55294, current 55274
<0> [520.728534] gem_exec-2395 21..... 287664924us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55316, current 55276
<0> [520.728664] gem_exec-2374 20..... 287665287us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55336, current 55279
<0> [520.728793] gem_exec-2374 20..... 287665303us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55280, current 55280
<0> [520.728924] gem_exec-2382 0..... 287665349us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55338, current 55281
<0> [520.729056] gem_exec-2382 0..... 287665354us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55282, current 55282
<7> [520.729027] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.729191] gem_exec-2395 21..... 287665478us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:7d2 active
<0> [520.729335] gem_exec-2395 21..... 287665490us : intel_timeline_pin: 0000:00:02.0 timeline:7d2 using HWSP offset:f3dde000
<0> [520.729468] gem_exec-2395 21..... 287665516us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:7d2 pin ring:{start:f3ece000, head:0168, tail:0168}
<0> [520.729601] gem_exec-2382 0..... 287665537us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bef active
<0> [520.729733] gem_exec-2382 0..... 287665540us : intel_timeline_pin: 0000:00:02.0 timeline:bef using HWSP offset:eb452000
<0> [520.729852] gem_exec-2382 0..... 287665555us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bef pin ring:{start:eb553000, head:00b0, tail:00b0}
<0> [520.729980] gem_exec-2382 0..... 287665586us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:bef unpin
<0> [520.730113] gem_exec-2395 21..... 287665678us : __i915_request_commit: 0000:00:02.0 ccs0: fence 7d2:4, current 2
<0> [520.730232] gem_exec-2382 0..... 287665714us : __intel_context_retire: 0000:00:02.0 vecs0: context:bef retire runtime: { total:42135ns, avg:42135ns }
<0> [520.730355] gem_exec-2382 0..... 287665716us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bef active
<0> [520.730480] gem_exec-2382 0..... 287665717us : intel_timeline_pin: 0000:00:02.0 timeline:bef using HWSP offset:eb452000
<0> [520.730600] gem_exec-2382 0..... 287665721us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:bef pin ring:{start:eb553000, head:00b0, tail:00b0}
<0> [520.730737] gem_exec-2395 21D..1. 287665757us : __i915_request_submit: 0000:00:02.0 ccs0: fence 7d2:4, current 2
<0> [520.730869] gem_exec-2382 0..... 287665770us : __i915_request_commit: 0000:00:02.0 vecs0: fence bef:4, current 2
<0> [520.731001] gem_exec-2382 0DN.1. 287665785us : __i915_request_submit: 0000:00:02.0 vecs0: fence bef:4, current 2
<0> [520.731122] kworker/-545 0..... 287666500us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:319 unpin
<0> [520.731248] kworker/-545 0..... 287666629us : __intel_context_retire: 0000:00:02.0 rcs0: context:319 retire runtime: { total:462637ns, avg:68741ns }
<0> [520.731374] kworker/-545 0..... 287667136us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:9d1 unpin
<0> [520.731491] kworker/-545 0..... 287667269us : __intel_context_retire: 0000:00:02.0 vcs1: context:9d1 retire runtime: { total:150573ns, avg:56922ns }
<7> [520.731466] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:508
<0> [520.731612] kworker/-545 0..... 287667273us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:390 unpin
<0> [520.731729] kworker/-545 0..... 287667398us : __intel_context_retire: 0000:00:02.0 vcs1: context:390 retire runtime: { total:109180ns, avg:26500ns }
<0> [520.731849] kworker/-545 0..... 287667406us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:4df unpin
<0> [520.731970] kworker/-545 0..... 287667530us : __intel_context_retire: 0000:00:02.0 vecs0: context:4df retire runtime: { total:79235ns, avg:35404ns }
<0> [520.732090] kworker/-545 0..... 287669283us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs1: context:d05 unpin
<0> [520.732206] kworker/-545 0..... 287669422us : __intel_context_retire: 0000:00:02.0 vcs1: context:d05 retire runtime: { total:58141ns, avg:58141ns }
<0> [520.732343] gem_exec-2395 21..... 287672336us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55404, current 55346
<0> [520.732475] gem_exec-2395 21D..1. 287672349us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55404, current 55346
<0> [520.732613] gem_exec-2395 21..... 287672753us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55348, current 55371
<0> [520.732759] gem_exec-2395 21..... 287672757us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55350, current 55371
<0> [520.732900] gem_exec-2395 21..... 287672759us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55352, current 55371
<0> [520.733041] gem_exec-2395 21..... 287672766us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55354, current 55372
<0> [520.733179] gem_exec-2395 21..... 287672797us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55356, current 55373
<0> [520.733311] gem_exec-2395 21..... 287672803us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55358, current 55374
<0> [520.733442] gem_exec-2395 21..... 287672809us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55360, current 55374
<0> [520.733576] gem_exec-2395 21..... 287672814us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55362, current 55374
<0> [520.733715] gem_exec-2395 21..... 287672820us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55364, current 55375
<0> [520.733854] gem_exec-2395 21..... 287672826us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55366, current 55375
<0> [520.733994] gem_exec-2395 21..... 287672831us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55368, current 55376
<0> [520.734133] gem_exec-2395 21..... 287672836us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55370, current 55376
<0> [520.734267] gem_exec-2395 21..... 287672843us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55372, current 55376
<0> [520.734397] gem_exec-2395 21..... 287672850us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55374, current 55377
<0> [520.734526] gem_exec-2395 21..... 287672856us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55376, current 55378
<0> [520.734656] gem_exec-2395 21..... 287672859us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55378, current 55378
<0> [520.734787] gem_exec-2373 0..... 287674195us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55432, current 55420
<0> [520.734916] gem_exec-2373 0D..1. 287674200us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55432, current 55420
<0> [520.735047] gem_exec-2373 0..... 287674581us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55422, current 55422
<0> [520.735178] gem_exec-2395 21..... 287674752us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55440, current 55427
<0> [520.735308] gem_exec-2395 21D..1. 287674771us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55440, current 55428
<0> [520.735437] gem_exec-2395 21..... 287674800us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55426, current 55430
<0> [520.735567] gem_exec-2395 21..... 287674809us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55428, current 55430
<0> [520.735697] gem_exec-2395 21..... 287674813us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55430, current 55430
<0> [520.735828] gem_exec-2398 20..... 287675222us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55440, current 55442
<0> [520.735960] gem_exec-2398 20..... 287675327us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55444, current 55442
<0> [520.736090] gem_exec-2398 20D..1. 287675343us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55444, current 55442
<0> [520.736220] gem_exec-2385 21..... 287675973us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55458, current 55442
<0> [520.736351] gem_exec-2385 21D..1. 287675993us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55458, current 55442
<0> [520.736473] gem_exec-2398 20..... 287676619us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:578 active
<0> [520.736610] gem_exec-2373 0..... 287676622us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55474, current 55442
<0> [520.736747] gem_exec-2398 20..... 287676628us : intel_timeline_pin: 0000:00:02.0 timeline:578 using HWSP offset:f8e17000
<0> [520.736876] gem_exec-2398 20..... 287676648us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:578 pin ring:{start:f8eef000, head:0210, tail:0210}
<0> [520.737022] gem_exec-2398 20..... 287676837us : __i915_request_commit: 0000:00:02.0 vcs0: fence 578:8, current 6
<0> [520.737156] gem_exec-2398 20D..1. 287676851us : __i915_request_submit: 0000:00:02.0 vcs0: fence 578:8, current 6
<0> [520.737287] gem_exec-2391 0..... 287677490us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55506, current 55468
<0> [520.737417] gem_exec-2398 20..... 287678743us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55550, current 55470
<0> [520.737548] gem_exec-2395 21..... 287678903us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55552, current 55472
<0> [520.737678] gem_exec-2395 21..... 287678921us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55472, current 55472
<0> [520.737809] gem_exec-2395 21..... 287679217us : __i915_request_commit: 0000:00:02.0 ccs0: fence d13:6, current 4
<0> [520.737939] gem_exec-2395 21D..1. 287679228us : __i915_request_submit: 0000:00:02.0 ccs0: fence d13:6, current 4
<0> [520.738069] gem_exec-2373 0..... 287682180us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55604, current 55540
<0> [520.738216] gem_exec-2373 0DN.1. 287682186us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55604, current 55541
<0> [520.738363] gem_exec-2406 0..... 287682263us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55610, current 55544
<0> [520.738506] gem_exec-2406 0D..1. 287682267us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55610, current 55544
<0> [520.738650] gem_exec-2406 0..... 287682272us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55544, current 55544
<0> [520.738784] gem_exec-2402 0..... 287684384us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55680, current 55622
<0> [520.738906] gem_exec-2402 0..... 287684477us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3b4 active
<0> [520.739034] gem_exec-2402 0..... 287684479us : intel_timeline_pin: 0000:00:02.0 timeline:3b4 using HWSP offset:fc86d000
<0> [520.739163] gem_exec-2402 0..... 287684486us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3b4 pin ring:{start:fc9bb000, head:0438, tail:0438}
<0> [520.739294] gem_exec-2402 0..... 287684500us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3b4 unpin
<0> [520.739421] gem_exec-2402 0..... 287684570us : __intel_context_retire: 0000:00:02.0 rcs0: context:3b4 retire runtime: { total:300722ns, avg:77857ns }
<0> [520.739543] gem_exec-2402 0..... 287684574us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3b4 active
<0> [520.739669] gem_exec-2402 0..... 287684575us : intel_timeline_pin: 0000:00:02.0 timeline:3b4 using HWSP offset:fc86d000
<0> [520.739788] gem_exec-2402 0..... 287684579us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:3b4 pin ring:{start:fc9bb000, head:0438, tail:0438}
<0> [520.739918] gem_exec-2402 0..... 287684592us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:3b4 unpin
<0> [520.740045] gem_exec-2402 0.N... 287684664us : __intel_context_retire: 0000:00:02.0 rcs0: context:3b4 retire runtime: { total:300722ns, avg:77857ns }
<0> [520.740174] gem_exec-2406 0..... 287684887us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:399 active
<0> [520.740300] gem_exec-2406 0..... 287684890us : intel_timeline_pin: 0000:00:02.0 timeline:399 using HWSP offset:fcc67000
<0> [520.740418] gem_exec-2406 0..... 287684896us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:399 pin ring:{start:fcd5f000, head:0210, tail:0210}
<0> [520.740540] gem_exec-2406 0..... 287684908us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:399 unpin
<0> [520.740657] gem_exec-2406 0..... 287684978us : __intel_context_retire: 0000:00:02.0 vecs0: context:399 retire runtime: { total:65296ns, avg:20829ns }
<0> [520.740778] gem_exec-2406 0..... 287684981us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:399 active
<0> [520.740902] gem_exec-2406 0..... 287684982us : intel_timeline_pin: 0000:00:02.0 timeline:399 using HWSP offset:fcc67000
<0> [520.741022] gem_exec-2406 0..... 287684985us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:399 pin ring:{start:fcd5f000, head:0210, tail:0210}
<0> [520.741153] gem_exec-2406 0..... 287684996us : __intel_context_do_unpin.part.0: 0000:00:02.0 vecs0: context:399 unpin
<0> [520.741274] gem_exec-2406 0..... 287685065us : __intel_context_retire: 0000:00:02.0 vecs0: context:399 retire runtime: { total:65296ns, avg:20829ns }
<0> [520.741394] gem_exec-2406 0..... 287685068us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:399 active
<0> [520.741520] gem_exec-2406 0..... 287685069us : intel_timeline_pin: 0000:00:02.0 timeline:399 using HWSP offset:fcc67000
<0> [520.741638] gem_exec-2406 0..... 287685072us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:399 pin ring:{start:fcd5f000, head:0210, tail:0210}
<0> [520.741778] gem_exec-2406 0..... 287685104us : __i915_request_commit: 0000:00:02.0 vecs0: fence 399:8, current 6
<0> [520.741911] gem_exec-2406 0D..1. 287685109us : __i915_request_submit: 0000:00:02.0 vecs0: fence 399:8, current 6
<0> [520.742044] gem_exec-2395 21..... 287687329us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55626, current 55638
<0> [520.742184] gem_exec-2395 21..... 287687438us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55714, current 55645
<0> [520.742328] gem_exec-2395 21..... 287687457us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55628, current 55646
<0> [520.742468] gem_exec-2395 21..... 287687468us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55630, current 55647
<0> [520.742608] gem_exec-2395 21..... 287687472us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55632, current 55648
<0> [520.742744] gem_exec-2395 21..... 287687495us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55634, current 55649
<0> [520.742875] gem_exec-2395 21..... 287687503us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55636, current 55650
<0> [520.743006] gem_exec-2395 21..... 287687510us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55638, current 55650
<0> [520.743137] gem_exec-2395 21..... 287687518us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55640, current 55650
<0> [520.743268] gem_exec-2395 21..... 287687522us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55642, current 55650
<0> [520.743399] gem_exec-2395 21..... 287687529us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55644, current 55651
<0> [520.743537] gem_exec-2395 21..... 287687536us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55646, current 55652
<0> [520.743671] gem_exec-2395 21..... 287687540us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55648, current 55652
<0> [520.743804] gem_exec-2395 21..... 287687544us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55650, current 55652
<0> [520.743944] gem_exec-2395 21..... 287687548us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55652, current 55652
<0> [520.744079] gem_exec-2375 0..... 287688090us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55746, current 55662
<0> [520.744209] gem_exec-2406 0..... 287692303us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55734, current 55734
<0> [520.744341] gem_exec-2406 0..... 287692324us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55788, current 55735
<0> [520.744481] gem_exec-2406 0..... 287692674us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55814, current 55748
<0> [520.744619] gem_exec-2407 0..... 287698167us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55876, current 55852
<0> [520.744758] gem_exec-2407 0D..1. 287698173us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:55876, current 55852
<0> [520.744897] gem_exec-2407 0..... 287699620us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:55916, current 55896
<0> [520.745028] gem_exec-2406 0..... 287700823us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:617 active
<0> [520.745163] gem_exec-2406 0..... 287700826us : intel_timeline_pin: 0000:00:02.0 timeline:617 using HWSP offset:f7913000
<0> [520.745286] gem_exec-2406 0..... 287700833us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:617 pin ring:{start:f79e4000, head:0160, tail:0160}
<0> [520.745423] gem_exec-2406 0..... 287700867us : __i915_request_commit: 0000:00:02.0 vecs0: fence 617:6, current 4
<0> [520.745554] gem_exec-2406 0D..1. 287700872us : __i915_request_submit: 0000:00:02.0 vecs0: fence 617:6, current 4
<0> [520.745685] gem_exec-2406 0..... 287707330us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56006, current 55987
<0> [520.745820] gem_exec-2406 0D..1. 287707335us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56006, current 55987
<0> [520.745961] gem_exec-2406 0..... 287707340us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:55988, current 55988
<0> [520.746102] gem_exec-2406 0..... 287708719us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56042, current 56020
<0> [520.746246] gem_exec-2406 0D..1. 287708724us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56042, current 56020
<0> [520.746395] gem_exec-2395 0..... 287709176us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56070, current 56038
<0> [520.746550] gem_exec-2395 0..... 287709180us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56038, current 56038
<0> [520.746681] gem_exec-2395 0..... 287709320us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:aa3 active
<0> [520.746813] gem_exec-2395 0..... 287709323us : intel_timeline_pin: 0000:00:02.0 timeline:aa3 using HWSP offset:edf4f000
<0> [520.746935] gem_exec-2395 0..... 287709328us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:aa3 pin ring:{start:ee062000, head:02d0, tail:02d0}
<0> [520.747080] gem_exec-2395 0..... 287709365us : __i915_request_commit: 0000:00:02.0 ccs0: fence aa3:6, current 4
<0> [520.747220] gem_exec-2395 0D..1. 287709369us : __i915_request_submit: 0000:00:02.0 ccs0: fence aa3:6, current 4
<0> [520.747359] gem_exec-2395 0..... 287709614us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56098, current 56040
<0> [520.747500] gem_exec-2393 0..... 287709893us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56120, current 56044
<0> [520.747639] gem_exec-2393 0..... 287709897us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56044, current 56044
<0> [520.747780] gem_exec-2393 0..... 287709975us : __i915_request_commit: 0000:00:02.0 vcs1: fence 4b4:8, current 6
<0> [520.747918] gem_exec-2393 0D..1. 287709978us : __i915_request_submit: 0000:00:02.0 vcs1: fence 4b4:8, current 6
<0> [520.748058] gem_exec-2370 0..... 287717275us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56164, current 56151
<0> [520.748198] gem_exec-2370 0..... 287717870us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56196, current 56162
<0> [520.748337] gem_exec-2369 0..... 287718428us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56228, current 56162
<0> [520.748471] gem_exec-2369 0..... 287724081us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56292, current 56260
<0> [520.748600] gem_exec-2369 0D..1. 287724088us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56292, current 56260
<0> [520.748722] gem_exec-2369 0..... 287724180us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:6ef active
<0> [520.748849] gem_exec-2369 0..... 287724183us : intel_timeline_pin: 0000:00:02.0 timeline:6ef using HWSP offset:f5bf8000
<0> [520.748977] gem_exec-2369 0..... 287724192us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:6ef pin ring:{start:f5d37000, head:02c0, tail:02c0}
<0> [520.749125] gem_exec-2369 0..... 287724234us : __i915_request_commit: 0000:00:02.0 vcs1: fence 6ef:10, current 8
<0> [520.749275] gem_exec-2369 0D..1. 287724239us : __i915_request_submit: 0000:00:02.0 vcs1: fence 6ef:10, current 8
<0> [520.749425] gem_exec-2399 0..... 287727960us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56374, current 56308
<0> [520.749572] gem_exec-2390 0..... 287729748us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56418, current 56348
<0> [520.749711] gem_exec-2397 0..... 287731714us : __i915_request_commit: 0000:00:02.0 bcs0: fence 730:10, current 8
<0> [520.749848] gem_exec-2397 0D..1. 287732311us : __i915_request_submit: 0000:00:02.0 bcs0: fence 730:10, current 8
<0> [520.749988] gem_exec-2390 0..... 287733745us : i915_request_retire_upto: 0000:00:02.0 rcs0: fence 298:56396, current 56398
<0> [520.750126] gem_exec-2390 0..... 287733747us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56396, current 56398
<0> [520.750266] gem_exec-2390 0..... 287733792us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56484, current 56400
<0> [520.750404] gem_exec-2390 0D..1. 287733796us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56484, current 56400
<0> [520.750541] gem_exec-2390 0..... 287734408us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56398, current 56429
<0> [520.750672] gem_exec-2390 0..... 287734410us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56400, current 56429
<0> [520.750803] gem_exec-2390 0..... 287734411us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56402, current 56429
<0> [520.750935] gem_exec-2390 0..... 287734412us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56404, current 56429
<0> [520.751065] gem_exec-2390 0..... 287734413us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56406, current 56429
<0> [520.751196] gem_exec-2390 0..... 287734415us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56408, current 56429
<0> [520.751325] gem_exec-2390 0..... 287734416us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56410, current 56429
<0> [520.751455] gem_exec-2390 0..... 287734417us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56412, current 56429
<0> [520.751584] gem_exec-2390 0..... 287734418us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56414, current 56429
<0> [520.751714] gem_exec-2390 0..... 287734419us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56416, current 56429
<0> [520.751846] gem_exec-2390 0..... 287734420us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56418, current 56430
<0> [520.751987] gem_exec-2390 0..... 287734422us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56420, current 56430
<0> [520.752129] gem_exec-2390 0..... 287734423us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56422, current 56430
<0> [520.752277] gem_exec-2390 0..... 287734423us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56424, current 56430
<0> [520.752425] gem_exec-2390 0..... 287734427us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56426, current 56430
<0> [520.752571] gem_exec-2390 0..... 287734428us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56428, current 56430
<0> [520.752709] gem_exec-2390 0..... 287734429us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56430, current 56430
<0> [520.752848] gem_exec-2368 0..... 287735340us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56508, current 56479
<0> [520.752984] gem_exec-2368 0D..1. 287735346us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56508, current 56480
<0> [520.753114] gem_exec-2368 0..... 287735352us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56480, current 56480
<0> [520.753255] gem_exec-2368 0..... 287736068us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:c31 active
<0> [520.753400] gem_exec-2368 0..... 287736071us : intel_timeline_pin: 0000:00:02.0 timeline:c31 using HWSP offset:eac84000
<0> [520.753534] gem_exec-2368 0..... 287736078us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:c31 pin ring:{start:eacdf000, head:0420, tail:0420}
<0> [520.753664] gem_exec-2368 0..... 287736100us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:c31 unpin
<0> [520.753782] gem_exec-2368 0..... 287736186us : __intel_context_retire: 0000:00:02.0 vcs0: context:c31 retire runtime: { total:136581ns, avg:48548ns }
<0> [520.753903] gem_exec-2368 0..... 287736189us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:c31 active
<0> [520.754030] gem_exec-2368 0..... 287736190us : intel_timeline_pin: 0000:00:02.0 timeline:c31 using HWSP offset:eac84000
<0> [520.754148] gem_exec-2368 0..... 287736194us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:c31 pin ring:{start:eacdf000, head:0420, tail:0420}
<0> [520.754285] gem_exec-2368 0..... 287736233us : __i915_request_commit: 0000:00:02.0 vcs0: fence c31:14, current 12
<0> [520.754417] gem_exec-2368 0D..1. 287736237us : __i915_request_submit: 0000:00:02.0 vcs0: fence c31:14, current 12
<0> [520.754549] gem_exec-2397 0..... 287737022us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56572, current 56516
<0> [520.754681] gem_exec-2397 0..... 287740008us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56618, current 56554
<0> [520.754812] gem_exec-2398 0..... 287743718us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56668, current 56581
<0> [520.754944] gem_exec-2398 0D..1. 287743725us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56668, current 56582
<0> [520.755075] gem_exec-2398 0..... 287743731us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56582, current 56582
<0> [520.755207] gem_exec-2399 0..... 287743930us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56588, current 56589
<0> [520.755337] gem_exec-2399 0..... 287743946us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56676, current 56590
<0> [520.755468] gem_exec-2399 0D..1. 287743951us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56676, current 56591
<0> [520.755597] gem_exec-2399 0..... 287743956us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56590, current 56591
<0> [520.755728] gem_exec-2397 0..... 287744662us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56692, current 56636
<0> [520.755857] gem_exec-2397 0..... 287744667us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56636, current 56636
<0> [520.755988] gem_exec-2402 0..... 287745869us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56730, current 56656
<0> [520.756121] gem_exec-2397 0..... 287746532us : __i915_request_commit: 0000:00:02.0 bcs0: fence b6a:6, current 4
<0> [520.756259] gem_exec-2397 0D..1. 287746535us : __i915_request_submit: 0000:00:02.0 bcs0: fence b6a:6, current 4
<0> [520.756390] gem_exec-2371 0..... 287747650us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56788, current 56728
<0> [520.756519] gem_exec-2371 0..... 287747655us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56728, current 56728
<0> [520.756649] gem_exec-2371 0..... 287748005us : __i915_request_commit: 0000:00:02.0 ccs0: fence 2dc:16, current 14
<0> [520.756776] gem_exec-2371 0D..1. 287748011us : __i915_request_submit: 0000:00:02.0 ccs0: fence 2dc:16, current 14
<0> [520.756905] gem_exec-2398 0..... 287753622us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56878, current 56856
<0> [520.757035] gem_exec-2398 0D..1. 287753628us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56878, current 56857
<0> [520.757158] gem_exec-2398 0..... 287754391us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:cf2 active
<0> [520.757285] gem_exec-2398 0..... 287754394us : intel_timeline_pin: 0000:00:02.0 timeline:cf2 using HWSP offset:e927c000
<0> [520.757407] gem_exec-2398 0..... 287754402us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:cf2 pin ring:{start:e927d000, head:0210, tail:0210}
<0> [520.757530] gem_exec-2398 0..... 287754419us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:cf2 unpin
<0> [520.757649] gem_exec-2398 0..... 287754490us : __intel_context_retire: 0000:00:02.0 vcs0: context:cf2 retire runtime: { total:87662ns, avg:87662ns }
<0> [520.757770] gem_exec-2398 0..... 287754494us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:cf2 active
<0> [520.757894] gem_exec-2398 0..... 287754496us : intel_timeline_pin: 0000:00:02.0 timeline:cf2 using HWSP offset:e927c000
<0> [520.758014] gem_exec-2398 0..... 287754501us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:cf2 pin ring:{start:e927d000, head:0210, tail:0210}
<0> [520.758135] gem_exec-2398 0..... 287754518us : __intel_context_do_unpin.part.0: 0000:00:02.0 vcs0: context:cf2 unpin
<0> [520.758252] gem_exec-2398 0..... 287754592us : __intel_context_retire: 0000:00:02.0 vcs0: context:cf2 retire runtime: { total:87662ns, avg:87662ns }
<0> [520.758372] gem_exec-2398 0..... 287754596us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:cf2 active
<0> [520.758497] gem_exec-2398 0..... 287754597us : intel_timeline_pin: 0000:00:02.0 timeline:cf2 using HWSP offset:e927c000
<0> [520.758615] gem_exec-2398 0..... 287754602us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:cf2 pin ring:{start:e927d000, head:0210, tail:0210}
<0> [520.758752] gem_exec-2398 0..... 287754669us : __i915_request_commit: 0000:00:02.0 vcs0: fence cf2:8, current 6
<0> [520.758884] gem_exec-2398 0D..1. 287754674us : __i915_request_submit: 0000:00:02.0 vcs0: fence cf2:8, current 6
<0> [520.759018] gem_exec-2399 20..... 287754871us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56916, current 56892
<0> [520.759149] gem_exec-2370 20..... 287755431us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56950, current 56892
<0> [520.759280] gem_exec-2398 0..... 287759677us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:56988, current 56906
<0> [520.759411] gem_exec-2398 0D..1. 287759695us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:56988, current 56907
<0> [520.759543] gem_exec-2398 0..... 287759707us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56904, current 56908
<0> [520.759674] gem_exec-2398 0..... 287759713us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56906, current 56908
<0> [520.759806] gem_exec-2398 0..... 287759721us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56908, current 56908
<0> [520.759938] gem_exec-2398 0..... 287760419us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57008, current 56936
<0> [520.760069] gem_exec-2398 0..... 287760426us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56936, current 56936
<0> [520.760199] gem_exec-2399 20..... 287760528us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56942, current 56942
<0> [520.760330] gem_exec-2399 20..... 287760733us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57016, current 56952
<0> [520.760460] gem_exec-2399 20D..1. 287760868us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57016, current 56955
<0> [520.760591] gem_exec-2399 20..... 287760938us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56944, current 56956
<0> [520.760721] gem_exec-2399 20..... 287760952us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56946, current 56956
<0> [520.760850] gem_exec-2399 20..... 287760963us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56948, current 56956
<0> [520.760982] gem_exec-2399 20..... 287760977us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56950, current 56956
<0> [520.761112] gem_exec-2399 20..... 287760982us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56952, current 56957
<0> [520.761243] gem_exec-2399 20..... 287760994us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56954, current 56958
<0> [520.761373] gem_exec-2399 20..... 287760998us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56956, current 56958
<0> [520.761503] gem_exec-2399 20..... 287761003us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56958, current 56958
<0> [520.761634] gem_exec-2368 0..... 287761815us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57040, current 56990
<0> [520.761755] gem_exec-2368 0..... 287761956us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:373 active
<0> [520.761882] gem_exec-2368 0..... 287761960us : intel_timeline_pin: 0000:00:02.0 timeline:373 using HWSP offset:fd148000
<0> [520.762004] gem_exec-2368 0..... 287761970us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:373 pin ring:{start:fd242000, head:02c0, tail:02c0}
<0> [520.762141] gem_exec-2370 21..... 287761986us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57048, current 56993
<0> [520.762273] gem_exec-2370 21..... 287762008us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56992, current 56994
<0> [520.762405] gem_exec-2368 0..... 287762017us : __i915_request_commit: 0000:00:02.0 vcs0: fence 373:10, current 8
<0> [520.762534] gem_exec-2370 21..... 287762021us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:56994, current 56994
<0> [520.762665] gem_exec-2368 0D..1. 287762022us : __i915_request_submit: 0000:00:02.0 vcs0: fence 373:10, current 8
<0> [520.762794] gem_exec-2371 0..... 287762295us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57066, current 57004
<0> [520.762925] gem_exec-2385 20..... 287767136us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57112, current 57072
<0> [520.763055] gem_exec-2385 20D..1. 287767164us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57112, current 57072
<0> [520.763184] gem_exec-2368 0..... 287768208us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57156, current 57074
<0> [520.763313] gem_exec-2373 21..... 287790414us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57098, current 57098
<0> [520.763444] gem_exec-2373 21..... 287790545us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57176, current 57104
<0> [520.763574] gem_exec-2373 21..... 287790566us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57100, current 57106
<0> [520.763704] gem_exec-2373 21..... 287790573us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57102, current 57106
<0> [520.763835] gem_exec-2373 21..... 287790576us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57104, current 57106
<0> [520.763966] gem_exec-2373 21..... 287790581us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57106, current 57106
<0> [520.764096] gem_exec-2370 0..... 287790730us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57114, current 57114
<0> [520.764225] gem_exec-2370 0..... 287790750us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57186, current 57114
<0> [520.764355] gem_exec-2388 0..... 287791327us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57204, current 57132
<0> [520.764484] gem_exec-2372 0..... 287791652us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57220, current 57132
<0> [520.764614] gem_exec-2383 20..... 287793849us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57226, current 57152
<0> [520.764744] gem_exec-2383 20..... 287793867us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57148, current 57153
<0> [520.764874] gem_exec-2383 20..... 287793909us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57150, current 57156
<0> [520.765004] gem_exec-2383 20..... 287793934us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57152, current 57157
<0> [520.765134] gem_exec-2383 20..... 287793984us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57154, current 57159
<0> [520.765264] gem_exec-2383 20..... 287793993us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57156, current 57160
<0> [520.765393] gem_exec-2383 20..... 287794016us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57158, current 57160
<0> [520.765522] gem_exec-2383 20..... 287794038us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57160, current 57162
<0> [520.765652] gem_exec-2383 20..... 287794047us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57162, current 57162
<0> [520.765782] gem_exec-2391 21..... 287794110us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57228, current 57162
<0> [520.765914] gem_exec-2382 0..... 287794179us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57230, current 57162
<0> [520.766043] gem_exec-2383 20..... 287794700us : __i915_request_commit: 0000:00:02.0 ccs0: fence 716:10, current 8
<0> [520.766172] gem_exec-2388 0..... 287794764us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57258, current 57182
<0> [520.766301] gem_exec-2388 0..... 287794769us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57182, current 57182
<0> [520.766430] gem_exec-2388 0.N... 287794810us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57184, current 57184
<0> [520.766561] gem_exec-2395 20..... 287795633us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57262, current 57188
<0> [520.766691] gem_exec-2380 21..... 287795708us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57264, current 57188
<0> [520.766821] gem_exec-2382 0..... 287803140us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57280, current 57226
<0> [520.766954] gem_exec-2382 0..... 287803147us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57226, current 57227
<0> [520.767084] gem_exec-2390 21..... 287803250us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57284, current 57234
<0> [520.767213] gem_exec-2390 21..... 287803265us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57232, current 57235
<0> [520.767343] gem_exec-2390 21..... 287803279us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57234, current 57236
<0> [520.767473] gem_exec-2390 21..... 287803288us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57236, current 57236
<0> [520.767603] gem_exec-2383 20..... 287803423us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57288, current 57246
<0> [520.767733] gem_exec-2383 20..... 287803439us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57242, current 57247
<0> [520.767863] gem_exec-2383 20..... 287803447us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57244, current 57247
<0> [520.767994] gem_exec-2383 20..... 287803456us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57246, current 57248
<0> [520.768123] gem_exec-2383 20..... 287803465us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57248, current 57248
<0> [520.768246] gem_exec-2390 21..... 287803523us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:361 active
<0> [520.768372] gem_exec-2390 21..... 287803532us : intel_timeline_pin: 0000:00:02.0 timeline:361 using HWSP offset:fd528000
<0> [520.768493] gem_exec-2390 21..... 287803557us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:361 pin ring:{start:fd529000, head:0870, tail:0870}
<0> [520.768616] gem_exec-2390 21..... 287803607us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:361 unpin
<0> [520.768736] gem_exec-2390 21..... 287803697us : __intel_context_retire: 0000:00:02.0 rcs0: context:361 retire runtime: { total:1400207ns, avg:168434ns }
<0> [520.768857] gem_exec-2390 21..... 287803708us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:361 active
<0> [520.768984] gem_exec-2390 21..... 287803712us : intel_timeline_pin: 0000:00:02.0 timeline:361 using HWSP offset:fd528000
<0> [520.769104] gem_exec-2390 21..... 287803725us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:361 pin ring:{start:fd529000, head:0870, tail:0870}
<0> [520.769240] gem_exec-2390 21..... 287803877us : __i915_request_commit: 0000:00:02.0 rcs0: fence 361:14, current 12
<0> [520.769372] gem_exec-2390 21D..1. 287803889us : __i915_request_submit: 0000:00:02.0 rcs0: fence 361:14, current 12
<0> [520.769505] gem_exec-2395 20..... 287804024us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57314, current 57260
<0> [520.769637] gem_exec-2396 0..... 287804192us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57324, current 57260
<0> [520.769769] gem_exec-2406 0..... 287804426us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57336, current 57260
<0> [520.769900] gem_exec-2396 0..... 287891150us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57350, current 57352
<0> [520.770033] gem_exec-2396 0..... 287891198us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57354, current 57352
<0> [520.770163] gem_exec-2396 0D..1. 287891212us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57354, current 57352
<0> [520.770294] gem_exec-2383 21..... 287893049us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57376, current 57370
<0> [520.770424] gem_exec-2383 21D..1. 287893069us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57376, current 57370
<0> [520.770553] gem_exec-2383 21..... 287893098us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57372, current 57372
<0> [520.770684] gem_exec-2389 0..... 287895272us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57392, current 57386
<0> [520.770814] gem_exec-2389 0D..1. 287895281us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57392, current 57386
<0> [520.770937] gem_exec-2389 0..... 287895674us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:2d4 active
<0> [520.771062] gem_exec-2389 0..... 287895681us : intel_timeline_pin: 0000:00:02.0 timeline:2d4 using HWSP offset:fe68b000
<0> [520.771183] gem_exec-2389 0..... 287895694us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:2d4 pin ring:{start:fe6c7000, head:0438, tail:0438}
<0> [520.771307] gem_exec-2389 0..... 287895717us : __intel_context_do_unpin.part.0: 0000:00:02.0 ccs0: context:2d4 unpin
<0> [520.771426] gem_exec-2389 0..... 287895865us : __intel_context_retire: 0000:00:02.0 ccs0: context:2d4 retire runtime: { total:612627ns, avg:165784ns }
<0> [520.771548] gem_exec-2389 0..... 287895872us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:2d4 active
<0> [520.771673] gem_exec-2389 0..... 287895875us : intel_timeline_pin: 0000:00:02.0 timeline:2d4 using HWSP offset:fe68b000
<0> [520.771792] gem_exec-2389 0..... 287895881us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:2d4 pin ring:{start:fe6c7000, head:0438, tail:0438}
<0> [520.771930] gem_exec-2389 0..... 287895952us : __i915_request_commit: 0000:00:02.0 ccs0: fence 2d4:8, current 6
<0> [520.772063] gem_exec-2389 0D..1. 287895978us : __i915_request_submit: 0000:00:02.0 ccs0: fence 2d4:8, current 6
<0> [520.772194] gem_exec-2389 0..... 287896162us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57402, current 57402
<0> [520.772326] gem_exec-2389 0..... 287896203us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57406, current 57402
<0> [520.772457] gem_exec-2382 0..... 287898576us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57428, current 57422
<0> [520.772578] gem_exec-2382 0..... 287898712us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:331 active
<0> [520.772705] gem_exec-2382 0..... 287898716us : intel_timeline_pin: 0000:00:02.0 timeline:331 using HWSP offset:fda19000
<0> [520.772825] gem_exec-2382 0..... 287898727us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:331 pin ring:{start:fdb20000, head:0420, tail:0420}
<0> [520.772964] gem_exec-2382 0..... 287898803us : __i915_request_commit: 0000:00:02.0 vecs0: fence 331:14, current 12
<0> [520.773096] gem_exec-2382 0D..1. 287898811us : __i915_request_submit: 0000:00:02.0 vecs0: fence 331:14, current 12
<0> [520.773215] gem_exec-2385 0..... 287903703us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:513 active
<0> [520.773341] gem_exec-2385 0..... 287903707us : intel_timeline_pin: 0000:00:02.0 timeline:513 using HWSP offset:f9b66000
<0> [520.773460] gem_exec-2385 0..... 287903715us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:513 pin ring:{start:f9bb8000, head:0420, tail:0420}
<0> [520.773583] gem_exec-2385 0..... 287903733us : __intel_context_do_unpin.part.0: 0000:00:02.0 bcs0: context:513 unpin
<0> [520.773701] gem_exec-2385 0..... 287903871us : __intel_context_retire: 0000:00:02.0 bcs0: context:513 retire runtime: { total:111512ns, avg:19504ns }
<0> [520.773822] gem_exec-2385 0..... 287903875us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:513 active
<0> [520.773948] gem_exec-2385 0..... 287903876us : intel_timeline_pin: 0000:00:02.0 timeline:513 using HWSP offset:f9b66000
<0> [520.774066] gem_exec-2385 0..... 287903879us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:513 pin ring:{start:f9bb8000, head:0420, tail:0420}
<0> [520.774202] gem_exec-2385 0..... 287903918us : __i915_request_commit: 0000:00:02.0 bcs0: fence 513:14, current 12
<0> [520.774335] gem_exec-2385 0D..1. 287903922us : __i915_request_submit: 0000:00:02.0 bcs0: fence 513:14, current 12
<0> [520.774467] gem_exec-2371 21..... 287904703us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57568, current 57490
<0> [520.774599] gem_exec-2380 0..... 287911092us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57598, current 57552
<0> [520.774731] gem_exec-2380 0D..1. 287911097us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57598, current 57552
<0> [520.774863] gem_exec-2403 20..... 287911857us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57616, current 57562
<0> [520.774995] gem_exec-2403 20D..1. 287912166us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57616, current 57578
<0> [520.775126] gem_exec-2403 20..... 287912188us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57560, current 57578
<0> [520.775257] gem_exec-2403 20..... 287912207us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57562, current 57578
<0> [520.775387] gem_exec-2403 20..... 287912218us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57564, current 57578
<0> [520.775518] gem_exec-2403 20..... 287912231us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57566, current 57578
<0> [520.775649] gem_exec-2403 20..... 287912243us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57568, current 57578
<0> [520.775779] gem_exec-2403 20..... 287912249us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57570, current 57578
<0> [520.775910] gem_exec-2403 20..... 287912253us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57572, current 57578
<0> [520.776041] gem_exec-2403 20..... 287912264us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57574, current 57578
<0> [520.776171] gem_exec-2403 20..... 287912274us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57576, current 57578
<0> [520.776302] gem_exec-2403 20..... 287912278us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57578, current 57578
<0> [520.776424] gem_exec-2379 21..... 287912654us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:c22 active
<0> [520.776551] gem_exec-2379 21..... 287912666us : intel_timeline_pin: 0000:00:02.0 timeline:c22 using HWSP offset:ead54000
<0> [520.776671] gem_exec-2379 21..... 287912692us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:c22 pin ring:{start:eaef0000, head:00b0, tail:00b0}
<0> [520.776807] gem_exec-2379 21..... 287912865us : __i915_request_commit: 0000:00:02.0 bcs0: fence c22:4, current 2
<0> [520.776939] gem_exec-2379 21D..1. 287912881us : __i915_request_submit: 0000:00:02.0 bcs0: fence c22:4, current 2
<0> [520.777070] gem_exec-2385 0..... 287912920us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57626, current 57592
<0> [520.777201] gem_exec-2385 0D..1. 287912925us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57626, current 57592
<0> [520.777333] gem_exec-2385 0..... 287912932us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57592, current 57592
<0> [520.777455] gem_exec-2403 20..... 287913683us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:337 active
<0> [520.777581] gem_exec-2403 20..... 287913695us : intel_timeline_pin: 0000:00:02.0 timeline:337 using HWSP offset:fda75000
<0> [520.777701] gem_exec-2403 20..... 287913720us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:337 pin ring:{start:fda76000, head:02c0, tail:02c0}
<0> [520.777836] gem_exec-2403 20..... 287913869us : __i915_request_commit: 0000:00:02.0 bcs0: fence 337:10, current 8
<0> [520.777968] gem_exec-2403 20D..1. 287914002us : __i915_request_submit: 0000:00:02.0 bcs0: fence 337:10, current 8
<0> [520.778099] gem_exec-2379 21..... 287914183us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57652, current 57601
<0> [520.778230] gem_exec-2385 0..... 287914316us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57662, current 57602
<0> [520.778352] gem_exec-2404 20..... 287914623us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:3a7 active
<0> [520.778479] gem_exec-2404 20..... 287914631us : intel_timeline_pin: 0000:00:02.0 timeline:3a7 using HWSP offset:fca7b000
<0> [520.778611] gem_exec-2377 0..... 287920011us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57754, current 57711
<0> [520.778744] gem_exec-2377 0D..1. 287920017us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57754, current 57711
<0> [520.778875] gem_exec-2377 0..... 287920024us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57712, current 57712
<0> [520.779004] gem_exec-2377 0..... 287921290us : intel_rps_boost: 0000:00:02.0 boost fence:298:e19a
<0> [520.779133] gem_exec-2390 0..... 287921891us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57788, current 57752
<0> [520.779265] gem_exec-2390 0D..1. 287921899us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57788, current 57752
<0> [520.779396] gem_exec-2390 0..... 287922492us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57826, current 57754
<0> [520.779527] gem_exec-2377 0..... 287926850us : __i915_request_commit: 0000:00:02.0 rcs0: fence 298:57876, current 57811
<0> [520.779658] gem_exec-2377 0D..1. 287926855us : __i915_request_submit: 0000:00:02.0 rcs0: fence 298:57876, current 57811
<0> [520.779791] gem_exec-2377 0..... 287926861us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 298:57812, current 57812
<0> [520.779916] gem_exec-2377 0..... 287927519us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:be3 active
<0> [520.780042] gem_exec-2377 0..... 287927522us : intel_timeline_pin: 0000:00:02.0 timeline:be3 using HWSP offset:eb6f8000
<0> [520.780164] gem_exec-2377 0..... 287927529us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:be3 pin ring:{start:eb6f9000, head:02d0, tail:02d0}
<6> [520.780248] [IGT] gem_exec_schedule: finished subtest vecs0, SUCCESS
<0> [520.780300] gem_exec-2377 0..... 287927574us : __i915_request_commit: 0000:00:02.0 ccs0: fence be3:6, current 4
<0> [520.780432] gem_exec-2377 0D..1. 287927577us : __i915_request_submit: 0000:00:02.0 ccs0: fence be3:6, current 4
|