<6> [737.662151] Submitted 1 contexts to vecs0, filling 1 dwords
<3> [740.726875] igt_live_test_end+0x14/0xf0 [i915] timed out, cancelling all further testing.
<0> [740.736420] Dumping ftrace buffer:
<0> [740.739899] ---------------------------------
<0> [740.745136] <...>-10 12..... 62463273us : __gt_unpark: 0000:00:02.0
<0> [740.752923] kworker/-176 12..... 62466358us : __gt_park: 0000:00:02.0
<0> [740.760518] <...>-1126 12..... 78059304us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:24 active
<0> [740.771620] <...>-1126 12..... 78059909us : intel_timeline_pin: 0000:00:02.0 timeline:24 using HWSP offset:fecbb000
<0> [740.783245] <...>-1126 12..... 78060365us : __engine_unpark: 0000:00:02.0 vecs0:
<0> [740.791891] <...>-1126 12..... 78060383us : __engine_unpark: 0000:00:02.0 vecs0: context:e reset { seqno:1, *hwsp:1, ring:68 }
<0> [740.804425] <...>-1126 12..... 78060406us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:24 pin ring:{start:fecc0000, head:0000, tail:0000}
<0> [740.818907] <...>-1126 12..... 78060446us : __intel_context_do_unpin: 0000:00:02.0 vecs0: context:24 unpin
<0> [740.829689] kworker/-176 12..... 78061577us : __engine_park: 0000:00:02.0 vecs0: parked
<0> [740.838662] <...>-1126 12..... 78062670us : __intel_context_retire: 0000:00:02.0 vecs0: context:24 retire runtime: { total:0ns, avg:0ns }
<0> [740.852136] <...>-10 13d..2. 78065322us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000003
<0> [740.863258] <...>-10 13d..2. 78066004us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000003
<0> [740.874361] <...>-10 12d..2. 78067570us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000003
<0> [740.885486] <...>-1126 12..... 78068838us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:24 active
<0> [740.896553] <...>-1126 12..... 78068858us : intel_timeline_pin: 0000:00:02.0 timeline:24 using HWSP offset:fecbb000
<0> [740.908106] <...>-1126 12..... 78069140us : __engine_unpark: 0000:00:02.0 vecs0:
<0> [740.916705] <...>-1126 12..... 78069157us : __engine_unpark: 0000:00:02.0 vecs0: context:e reset { seqno:1, *hwsp:1, ring:68 }
<0> [740.929197] <...>-1126 12..... 78069172us : __intel_context_do_pin_ww: 0000:00:02.0 vecs0: context:24 pin ring:{start:fecc0000, head:0000, tail:0000}
<0> [740.943699] <...>-10 12d..2. 78071118us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000003
<0> [740.954819] <...>-10 12d..2. 78072127us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00400003
<0> [740.965922] <...>-10 12d..2. 78075248us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00400003
<0> [740.977045] <...>-10 13..... 78432982us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:1a unpin
<0> [740.987725] <...>-142 13..... 78433288us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [740.996597] <...>-10 13..... 78433504us : __intel_context_retire: 0000:00:02.0 rcs0: context:1a retire runtime: { total:395751ns, avg:395751ns }
<0> [741.010800] <...>-10 13d..2. 78442638us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000000
<0> [741.021903] <...>-10 13d..2. 78443051us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000000
<0> [741.032986] <...>-10 13d..2. 78453582us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000000
<0> [741.044091] <...>-10 13..... 79403280us : __intel_context_do_unpin: 0000:00:02.0 vcs1: context:22 unpin
<0> [741.054758] <...>-142 13..... 79403585us : __engine_park: 0000:00:02.0 vcs1: parked
<0> [741.063615] <...>-10 13..... 79403639us : __intel_context_retire: 0000:00:02.0 vcs1: context:22 retire runtime: { total:21730ns, avg:21730ns }
<0> [741.077628] <...>-10 13..... 79403776us : __intel_context_do_unpin: 0000:00:02.0 vcs0: context:23 unpin
<0> [741.088312] <...>-10 7d..2. 82279340us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00000001
<0> [741.099418] kworker/-1108 14..... 82279480us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:2b unpin
<0> [741.110097] <...>-154 14..... 82279761us : __engine_park: 0000:00:02.0 rcs0: parked
<0> [741.119013] <...>-154 14..... 82279806us : __gt_park: 0000:00:02.0
<0> [741.126511] kworker/-1108 14..... 82280013us : __intel_context_retire: 0000:00:02.0 rcs0: context:2b retire runtime: { total:36411ns, avg:36411ns }
<0> [741.140510] <...>-169 13..... 85430818us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:32 unpin
<0> [741.151161] <...>-169 13..... 85431066us : __intel_context_retire: 0000:00:02.0 rcs0: context:32 retire runtime: { total:36411ns, avg:36411ns }
<0> [741.165163] <...>-169 13..... 85431207us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:33 unpin
<0> [741.175812] <...>-169 13..... 85431405us : __intel_context_retire: 0000:00:02.0 bcs0: context:33 retire runtime: { total:13515ns, avg:13515ns }
<0> [741.189814] <...>-169 13..... 85431508us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:37 unpin
<0> [741.200456] <...>-169 13..... 85431703us : __intel_context_retire: 0000:00:02.0 ccs0: context:37 retire runtime: { total:18550ns, avg:18550ns }
<0> [741.214450] <...>-169 13..... 85431798us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:38 unpin
<0> [741.225088] <...>-169 13..... 85432021us : __intel_context_retire: 0000:00:02.0 rcs0: context:38 retire runtime: { total:35139ns, avg:35139ns }
<0> [741.239080] <...>-169 13..... 85432113us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:39 unpin
<0> [741.249730] <...>-169 13..... 85432309us : __intel_context_retire: 0000:00:02.0 bcs0: context:39 retire runtime: { total:14310ns, avg:14310ns }
<0> [741.263729] <...>-169 13..... 85432402us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:3d unpin
<0> [741.274373] <...>-169 13..... 85432597us : __intel_context_retire: 0000:00:02.0 ccs0: context:3d retire runtime: { total:19928ns, avg:19928ns }
<0> [741.288367] <...>-169 13..... 85432688us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:3e unpin
<0> [741.299004] <...>-169 13..... 85432903us : __intel_context_retire: 0000:00:02.0 rcs0: context:3e retire runtime: { total:37153ns, avg:37153ns }
<0> [741.313000] <...>-169 13..... 85432993us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:3f unpin
<0> [741.323633] <...>-169 13..... 85433187us : __intel_context_retire: 0000:00:02.0 bcs0: context:3f retire runtime: { total:13621ns, avg:13621ns }
<0> [741.337620] <...>-169 13..... 85433279us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:43 unpin
<0> [741.348253] <...>-169 13..... 85433476us : __intel_context_retire: 0000:00:02.0 ccs0: context:43 retire runtime: { total:18709ns, avg:18709ns }
<0> [741.362235] <...>-169 13..... 85433567us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:44 unpin
<0> [741.372872] <...>-169 13..... 85433765us : __intel_context_retire: 0000:00:02.0 rcs0: context:44 retire runtime: { total:35934ns, avg:35934ns }
<0> [741.386857] <...>-169 13..... 85433855us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:45 unpin
<0> [741.397487] <...>-169 13..... 85434070us : __intel_context_retire: 0000:00:02.0 bcs0: context:45 retire runtime: { total:13886ns, avg:13886ns }
<0> [741.411463] <...>-169 13..... 85434162us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:49 unpin
<0> [741.422096] <...>-169 13..... 85434357us : __intel_context_retire: 0000:00:02.0 ccs0: context:49 retire runtime: { total:19080ns, avg:19080ns }
<0> [741.436078] <...>-169 13..... 85434454us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:4a unpin
<0> [741.446715] <...>-169 13..... 85434649us : __intel_context_retire: 0000:00:02.0 rcs0: context:4a retire runtime: { total:35828ns, avg:35828ns }
<0> [741.460693] <...>-169 13..... 85434734us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:4b unpin
<0> [741.471321] <...>-169 13..... 85434947us : __intel_context_retire: 0000:00:02.0 bcs0: context:4b retire runtime: { total:13939ns, avg:13939ns }
<0> [741.485302] <...>-169 13..... 85435035us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:4f unpin
<0> [741.495941] <...>-169 13..... 85435231us : __intel_context_retire: 0000:00:02.0 ccs0: context:4f retire runtime: { total:18444ns, avg:18444ns }
<0> [741.509924] <...>-169 13..... 85435324us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:50 unpin
<0> [741.520553] <...>-169 13..... 85435518us : __intel_context_retire: 0000:00:02.0 rcs0: context:50 retire runtime: { total:36146ns, avg:36146ns }
<0> [741.534533] <...>-169 13..... 85435606us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:51 unpin
<0> [741.545168] <...>-169 13..... 85435813us : __intel_context_retire: 0000:00:02.0 bcs0: context:51 retire runtime: { total:14363ns, avg:14363ns }
<0> [741.559147] <...>-169 13..... 85435946us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:55 unpin
<0> [741.569776] <...>-169 13..... 85436161us : __intel_context_retire: 0000:00:02.0 ccs0: context:55 retire runtime: { total:19451ns, avg:19451ns }
<0> [741.583760] <...>-169 13..... 85436273us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:56 unpin
<0> [741.594386] <...>-169 13..... 85436485us : __intel_context_retire: 0000:00:02.0 rcs0: context:56 retire runtime: { total:35563ns, avg:35563ns }
<0> [741.608356] <...>-169 13..... 85436569us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:57 unpin
<0> [741.618989] <...>-169 13..... 85436763us : __intel_context_retire: 0000:00:02.0 bcs0: context:57 retire runtime: { total:13674ns, avg:13674ns }
<0> [741.632963] <...>-169 13..... 85436854us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:5b unpin
<0> [741.643593] <...>-169 13..... 85437068us : __intel_context_retire: 0000:00:02.0 ccs0: context:5b retire runtime: { total:18550ns, avg:18550ns }
<0> [741.657567] <...>-169 13..... 85437164us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:5c unpin
<0> [741.668188] <...>-169 13..... 85437360us : __intel_context_retire: 0000:00:02.0 rcs0: context:5c retire runtime: { total:35987ns, avg:35987ns }
<0> [741.682163] <...>-169 13..... 85437449us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:5d unpin
<0> [741.692797] <...>-169 13..... 85437645us : __intel_context_retire: 0000:00:02.0 bcs0: context:5d retire runtime: { total:14045ns, avg:14045ns }
<0> [741.706782] <...>-169 13..... 85437731us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:61 unpin
<0> [741.717420] <...>-169 13..... 85437942us : __intel_context_retire: 0000:00:02.0 ccs0: context:61 retire runtime: { total:18868ns, avg:18868ns }
<0> [741.731397] <...>-169 13..... 85438041us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:62 unpin
<0> [741.742023] <...>-169 13..... 85438236us : __intel_context_retire: 0000:00:02.0 rcs0: context:62 retire runtime: { total:36093ns, avg:36093ns }
<0> [741.756004] <...>-169 13..... 85438326us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:63 unpin
<0> [741.766708] kworker/-1161 12d..2. 85473567us : intel_gt_invalidate_tlb: 0000:00:02.0 invalidated engines 00400001