<6> [437.177331] ccs0((efault)): Sampling 712ea616d4e8, with alignment 200000, using PTE size 200000 (phys 400000, sg 211000), invalidate:0+1000000000000
<6> [437.239131] i915: Running intel_tlb_live_selftests/invalidate_full
<3> [437.256999] vcs0((efault)): Semaphore sanitycheck failed 3cda6a818240, with alignment 1000, using PTE size 1000 (phys 400000, sg 211000)
<7> [438.192229] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 turning VDD off
<7> [438.192737] i915 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [i915]] [ENCODER:240:DDI A/PHY A] PPS 0 PP_STATUS: 0x00000000 PP_CONTROL: 0x00000060
<7> [438.193152] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [438.194336] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [438.194884] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [438.195127] i915 0000:00:02.0: [drm:intel_get_hpd_pins [i915]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010
<7> [438.195336] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] digital hpd on [ENCODER:240:DDI A/PHY A] - long
<7> [438.195378] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [438.195524] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 10
<7> [438.196057] i915 0000:00:02.0: [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP [ENCODER:240:DDI A/PHY A]
<0> [440.748187] gem_exec-4134 20..... 86286905us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:99a active
<0> [440.748257] gem_exec-4134 20..... 86286908us : intel_timeline_pin: 0000:00:02.0 timeline:99a using HWSP offset:fc7ef000
<0> [440.748311] gem_exec-4134 20..... 86286914us : __intel_context_do_pin_ww: 0000:00:02.0 bcs0: context:99a pin ring:{start:fec40000, head:0000, tail:0000}
<0> [440.748386] gem_exec-4134 20..... 86287338us : __i915_request_commit: 0000:00:02.0 bcs0: fence 99a:2, current 0
<0> [440.748460] gem_exec-4134 20D..1. 86287346us : __i915_request_submit: 0000:00:02.0 bcs0: fence 99a:2, current 0
<0> [440.748534] gem_exec-4134 20..... 86287405us : i915_request_retire_upto: 0000:00:02.0 bcs0: fence 6:267, current 302
<0> [440.748606] gem_exec-4134 20..... 86287405us : i915_request_retire.part.0: 0000:00:02.0 bcs0: fence 6:267, current 302
<0> [440.748679] gem_exec-4134 20..... 86287414us : __i915_request_commit: 0000:00:02.0 bcs0: fence 6:303, current 302
<0> [440.748750] gem_exec-4134 20D..1. 86287420us : __i915_request_submit: 0000:00:02.0 bcs0: fence 6:303, current 302
<0> [440.748805] gem_exec-3937 17..... 86288166us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:99b pin ring:{start:fcd79000, head:0000, tail:0000}
<0> [440.748861] gem_exec-3937 17..... 86288181us : __intel_context_do_unpin: 0000:00:02.0 vcs0: context:99b unpin
<0> [440.748916] gem_exec-3937 17..... 86288242us : __intel_context_retire: 0000:00:02.0 vcs0: context:99b retire runtime: { total:0ns, avg:0ns }
<0> [440.748969] gem_exec-3937 17..... 86288251us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:99b active
<0> [440.749039] gem_exec-3937 17..... 86288253us : intel_timeline_pin: 0000:00:02.0 timeline:99b using HWSP offset:fc7ed000
<0> [440.749094] gem_exec-3937 17..... 86288260us : __intel_context_do_pin_ww: 0000:00:02.0 vcs0: context:99b pin ring:{start:fcd79000, head:0000, tail:0000}
<0> [440.749166] gem_exec-3937 17..... 86288694us : __i915_request_commit: 0000:00:02.0 vcs0: fence 99b:2, current 0
<0> [440.749237] gem_exec-3937 17D..1. 86288704us : __i915_request_submit: 0000:00:02.0 vcs0: fence 99b:2, current 0
<0> [440.749308] gem_exec-3937 17..... 86288762us : i915_request_retire_upto: 0000:00:02.0 vcs0: fence c:298, current 333
<0> [440.749379] gem_exec-3937 17..... 86288762us : i915_request_retire.part.0: 0000:00:02.0 vcs0: fence c:298, current 333
<0> [440.749450] gem_exec-3937 17..... 86288770us : __i915_request_commit: 0000:00:02.0 vcs0: fence c:334, current 333
<0> [440.749519] gem_exec-3937 17D..1. 86288776us : __i915_request_submit: 0000:00:02.0 vcs0: fence c:334, current 333
<0> [440.749574] gem_exec-4064 19..... 86289341us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:99e pin ring:{start:fdc66000, head:0000, tail:0000}
<0> [440.749628] gem_exec-4064 19..... 86289363us : __intel_context_do_unpin: 0000:00:02.0 vcs1: context:99e unpin
<0> [440.749682] gem_exec-4064 19..... 86289425us : __intel_context_retire: 0000:00:02.0 vcs1: context:99e retire runtime: { total:0ns, avg:0ns }
<0> [440.749734] gem_exec-4064 19..... 86289431us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:99e active
<0> [440.749805] gem_exec-4064 19..... 86289433us : intel_timeline_pin: 0000:00:02.0 timeline:99e using HWSP offset:fd157000
<0> [440.749858] gem_exec-4064 19..... 86289440us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:99e pin ring:{start:fdc66000, head:0000, tail:0000}
<0> [440.749910] gem_exec-4064 19..... 86289450us : __intel_context_do_unpin: 0000:00:02.0 vcs1: context:99e unpin
<0> [440.749963] gem_exec-4064 19..... 86289511us : __intel_context_retire: 0000:00:02.0 vcs1: context:99e retire runtime: { total:0ns, avg:0ns }
<0> [440.750014] gem_exec-4064 19..... 86289516us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:99e active
<0> [440.750083] gem_exec-4064 19..... 86289517us : intel_timeline_pin: 0000:00:02.0 timeline:99e using HWSP offset:fd157000
<0> [440.750135] gem_exec-4064 19..... 86289523us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:99e pin ring:{start:fdc66000, head:0000, tail:0000}
<0> [440.750208] gem_exec-4064 19..... 86289952us : __i915_request_commit: 0000:00:02.0 vcs1: fence 99e:2, current 0
<0> [440.750281] gem_exec-4064 19D..1. 86289961us : __i915_request_submit: 0000:00:02.0 vcs1: fence 99e:2, current 0
<0> [440.750354] gem_exec-4064 19..... 86290016us : i915_request_retire_upto: 0000:00:02.0 vcs1: fence d:300, current 335
<0> [440.750427] gem_exec-4064 19..... 86290017us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:300, current 335
<0> [440.750500] gem_exec-4064 19..... 86290025us : __i915_request_commit: 0000:00:02.0 vcs1: fence d:336, current 335
<0> [440.750571] gem_exec-4064 19D..1. 86290031us : __i915_request_submit: 0000:00:02.0 vcs1: fence d:336, current 335
<0> [440.750627] gem_exec-4132 21..... 86290202us : __intel_context_do_pin_ww: 0000:00:02.0 ccs0: context:99f pin ring:{start:03d8a000, head:0000, tail:0000}
<0> [440.750697] gem_exec-4132 21..... 86290782us : __i915_request_commit: 0000:00:02.0 ccs0: fence 99f:2, current 0
<0> [440.750767] gem_exec-4132 21D..1. 86290791us : __i915_request_submit: 0000:00:02.0 ccs0: fence 99f:2, current 0
<0> [440.750837] gem_exec-4132 21..... 86290851us : i915_request_retire_upto: 0000:00:02.0 ccs0: fence 7:317, current 328
<0> [440.750906] gem_exec-4132 21..... 86290851us : i915_request_retire.part.0: 0000:00:02.0 ccs0: fence 7:317, current 328
<0> [440.750976] gem_exec-4132 21..... 86290861us : __i915_request_commit: 0000:00:02.0 ccs0: fence 7:329, current 328
<0> [440.751045] gem_exec-4132 21D..1. 86290867us : __i915_request_submit: 0000:00:02.0 ccs0: fence 7:329, current 328
<0> [440.751101] gem_exec-3752 13..... 86308971us : __intel_context_do_pin_ww: 0000:00:02.0 vcs1: context:9b4 pin ring:{start:fcc17000, head:0000, tail:0000}
<0> [440.751170] gem_exec-3752 13..... 86309419us : __i915_request_commit: 0000:00:02.0 vcs1: fence 9b4:2, current 0
<0> [440.751238] gem_exec-3752 13D..1. 86309429us : __i915_request_submit: 0000:00:02.0 vcs1: fence 9b4:2, current 0
<0> [440.751307] gem_exec-3752 13..... 86309490us : i915_request_retire_upto: 0000:00:02.0 vcs1: fence d:303, current 338
<0> [440.751375] gem_exec-3752 13..... 86309490us : i915_request_retire.part.0: 0000:00:02.0 vcs1: fence d:303, current 338
<0> [440.751443] gem_exec-3752 13..... 86309500us : __i915_request_commit: 0000:00:02.0 vcs1: fence d:339, current 338
<0> [440.751510] gem_exec-3752 13D..1. 86309506us : __i915_request_submit: 0000:00:02.0 vcs1: fence d:339, current 338
<0> [440.751565] kworker/-197 11..... 87132862us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:820 unpin
<0> [440.751621] kworker/-197 11..... 87132927us : __intel_context_retire: 0000:00:02.0 ccs0: context:820 retire runtime: { total:77963ns, avg:77963ns }
<0> [440.751676] kworker/-197 11..... 87132960us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:822 unpin
<0> [440.751730] kworker/-197 11..... 87133021us : __intel_context_retire: 0000:00:02.0 bcs0: context:822 retire runtime: { total:17914ns, avg:17914ns }
<0> [440.751783] kworker/-197 11..... 87133042us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:935 unpin
<0> [440.751836] kworker/-197 11..... 87133103us : __intel_context_retire: 0000:00:02.0 ccs0: context:935 retire runtime: { total:226363ns, avg:226363ns }
<0> [440.751888] kworker/-197 11..... 87133122us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:829 unpin
<0> [440.751941] kworker/-197 11..... 87133181us : __intel_context_retire: 0000:00:02.0 rcs0: context:829 retire runtime: { total:138489ns, avg:138489ns }
<0> [440.751992] kworker/-197 11..... 87133296us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:82b unpin
<0> [440.752045] kworker/-197 11..... 87133363us : __intel_context_retire: 0000:00:02.0 bcs0: context:82b retire runtime: { total:17066ns, avg:17066ns }
<0> [440.752097] kworker/-197 11..... 87133383us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:82e unpin
<0> [440.752149] kworker/-197 11..... 87133444us : __intel_context_retire: 0000:00:02.0 rcs0: context:82e retire runtime: { total:653914ns, avg:653914ns }
<0> [440.752200] kworker/-197 11..... 87133463us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:830 unpin
<0> [440.752252] kworker/-197 11..... 87133523us : __intel_context_retire: 0000:00:02.0 bcs0: context:830 retire runtime: { total:17967ns, avg:17967ns }
<0> [440.752303] kworker/-197 11..... 87133556us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:82f unpin
<0> [440.752354] kworker/-197 11..... 87133617us : __intel_context_retire: 0000:00:02.0 bcs0: context:82f retire runtime: { total:17331ns, avg:17331ns }
<0> [440.752405] kworker/-197 11..... 87133875us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:834 unpin
<0> [440.752455] kworker/-197 11..... 87133938us : __intel_context_retire: 0000:00:02.0 bcs0: context:834 retire runtime: { total:18338ns, avg:18338ns }
<0> [440.752505] kworker/-197 11..... 87133966us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:836 unpin
<0> [440.752555] kworker/-197 11..... 87134027us : __intel_context_retire: 0000:00:02.0 bcs0: context:836 retire runtime: { total:17702ns, avg:17702ns }
<0> [440.752605] kworker/-197 11..... 87134049us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:837 unpin
<0> [440.752655] kworker/-197 11..... 87134110us : __intel_context_retire: 0000:00:02.0 rcs0: context:837 retire runtime: { total:136369ns, avg:136369ns }
<0> [440.752704] kworker/-197 11..... 87134130us : __intel_context_do_unpin: 0000:00:02.0 rcs0: context:83c unpin
<0> [440.752753] kworker/-197 11..... 87134190us : __intel_context_retire: 0000:00:02.0 rcs0: context:83c retire runtime: { total:577965ns, avg:577965ns }
<0> [440.752803] kworker/-197 11..... 87134393us : __intel_context_do_unpin: 0000:00:02.0 bcs0: context:841 unpin
<0> [440.752851] kworker/-197 11..... 87134460us : __intel_context_retire: 0000:00:02.0 bcs0: context:841 retire runtime: { total:21730ns, avg:21730ns }
<0> [440.752900] kworker/-197 11..... 87134480us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:842 unpin
<0> [440.752949] kworker/-197 11..... 87134549us : __intel_context_retire: 0000:00:02.0 ccs0: context:842 retire runtime: { total:139602ns, avg:139602ns }
<0> [440.752997] kworker/-197 11..... 87134569us : __intel_context_do_unpin: 0000:00:02.0 ccs0: context:844 unpin
<0> [440.753046] kworker/-197 11..... 87134629us : __intel_context_retire: 0000:00:02.0 ccs0: context:844 retire runtime: { total:122218ns, avg:122218ns }