[  8355.483] kgem_add_reloc: handle=29, pos=127, delta=0, domains=28000
[  8355.483] sna_copy_area: src=(0, 0)x(1920, 18)+(0, 0) -> dst=(0, 0)+(1920, 0); alu=3, pm=ffffffffffffffff, depth=24
[  8355.483] sna_do_copy: src=(0, 0), dst=(0, 0), size=(1920x18)
[  8355.483] sna_do_copy: after drawable: src=(0, 0), dst=(1920, 0), size=(1920x18)
[  8355.483] sna_do_copy: dst extents (1920, 0), (3840, 18), dst clip extents (1920, 0), (3840, 18), dst size=1920x18
[  8355.483] sna_do_copy: clipped dst extents (1920, 0), (3840, 18)
[  8355.483] sna_do_copy: unclipped src extents (0, 0), (1920, 18)
[  8355.483] sna_do_copy: pixmap -- no source clipping
[  8355.483] sna_do_copy: src extents (0, 0), (1920, 18) x 1
[  8355.483] sna_do_copy: copy region (1920, 0), (3840, 18) x 1
[  8355.483] sna_copy_boxes (boxes=1x[(1920, 0), (3840, 18)...], src=+(-1920, 0), dst=+(0, 0), alu=3, src.size=1920x18, dst.size=3840x1080)
[  8355.483] sna_copy_boxes: dst=(priv=0x24646f0, gpu_bo=25, cpu_bo=0), src=(priv=0x26d0550, gpu_bo=39, cpu_bo=0), replaces=0
[  8355.483] copy_prefer_gpu: source has gpu damage, force gpu? 1
[  8355.483] sna_drawable_use_bo pixmap=13, box=((1920, 0), (3840, 18)), flags=b...
[  8355.483] sna_drawable_use_bo: exported target, set PREFER_GPU
[  8355.483] sna_drawable_use_bo: pinned, never REPLACES
[  8355.483] sna_drawable_use_bo: flush=1, shm=0, cpu=0 => flags=b
[  8355.483] sna_drawable_use_bo: use GPU fast path (all-damaged)
[  8355.483] sna_drawable_use_bo: using whole GPU bo
[  8355.483] sna_pixmap_move_to_gpu(pixmap=145, usage=0), flags=a
[  8355.483] sna_pixmap_move_to_gpu: already all-damaged
[  8355.483] sna_pixmap_mark_active: pixmap=145, handle=39
[  8355.483] sna_copy_boxes: move whole src_pixmap to GPU and copy
[  8355.484] gen6_render_copy_boxes (-1920, 0)->(0, 0) x 1, alu=3, self-copy=0, overlaps? 0
[  8355.484] sna_blt_copy_boxes src=(-1920, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(7680, 15360)
[  8355.484]   sna_blt_copy_boxes: box=(1920, 0)x(1920, 18)
[  8355.484] kgem_add_reloc: handle=25, pos=132, delta=0, domains=28002
[  8355.484] kgem_add_reloc: handle=39, pos=135, delta=0, domains=28000
[  8355.484] sna_accel_flush: flush?=1, dirty?=0
[  8355.484] batch[3/3, flags=1c00]: 138 138 65528 65528, nreloc=34, nexec=5, nfence=0, aperture=8190 [fenced=0]
[  8355.484] __kgem_busy: handle=1, busy=262145, wedged=0
[  8355.484] __kgem_busy: handle=2, busy=262145, wedged=0
[  8355.484] kgem_create_linear(552)
[  8355.484] search_linear_cache: num_pages=1, flags=202, use_active? 0, use_large=0 [max=65536]
[  8355.484] search_linear_cache: inactive and cache bucket empty
[  8355.484] search_linear_cache: active cache bucket empty
[  8355.484] kgem_create_linear: new handle=19, num_pages=1
[  8355.484] __kgem_bo_init(handle=19, num_pages=1)
[  8355.484] gem_write__cachealigned(handle=19, offset=0, len=552)
[  8355.484] kgem_commit: release handle=19 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b3c9000
[  8355.484] kgem_commit: release handle=25 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=ca3000
[  8355.484] kgem_commit: release handle=10 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=9c0c000
[  8355.484] kgem_commit: release handle=39 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=2d74000
[  8355.484] kgem_commit: release handle=8 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=9422000
[  8355.484] kgem_commit: release handle=29 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=1c75000
[  8355.484] sna_block_handler (tv=0.652000)
[  8355.484] kgem_retire, need_retire?=1
[  8355.484] kgem_retire__flushing: 0 bo on flushing list
[  8355.484] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  8355.484] __kgem_busy: handle=1, busy=262145, wedged=0
[  8355.484] kgem_retire__requests_ring: ring=1, 5 outstanding requests, oldest=1
[  8355.484] kgem_retire -- retired=0, need_retire=1
[  8355.484] has_shadow: has pending damage? 1, outstanding flips: 1
[  8355.484] start_flush: has dirty shadow
[  8355.484] timer_enable (time=8355484), starting timer 0
[  8355.484] timer_enable (time=8355484), starting timer 1
[  8355.484] sna_accel_block_handler: evaluating timers, active=7
[  8355.484] sna_accel_block_handler: flush timer expires in 8 [8355492]
[  8355.484] sna_wakeup_handler
[  8355.484] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
[  8355.484] sna_validate_gc(0x250fec0) changes=0, previous serial=ad, drawable=bc
[  8355.484] sna_validate_gc: recomputing clip
[  8355.484] sna_validate_gc: composite clip=1x[(1920, 1061), (3840, 1080)] [0x250fad0]
[  8355.484] sna_copy_area: src=(0, 0)x(1920, 1080)+(0, 0) -> dst=(0, 0)+(1920, 1061); alu=3, pm=ffffffffffffffff, depth=24
[  8355.484] sna_do_copy: src=(0, 0), dst=(0, 0), size=(1920x1080)
[  8355.484] sna_do_copy: after drawable: src=(0, 0), dst=(1920, 1061), size=(1920x1080)
[  8355.484] sna_do_copy: dst extents (1920, 1061), (3840, 2141), dst clip extents (1920, 1061), (3840, 1080), dst size=1920x19
[  8355.484] sna_do_copy: clipped dst extents (1920, 1061), (3840, 1080)
[  8355.484] sna_do_copy: unclipped src extents (0, 0), (1920, 19)
[  8355.484] sna_do_copy: pixmap -- no source clipping
[  8355.484] sna_do_copy: src extents (0, 0), (1920, 19) x 1
[  8355.484] sna_do_copy: copy region (1920, 1061), (3840, 1080) x 1
[  8355.484] sna_copy_boxes (boxes=1x[(1920, 1061), (3840, 1080)...], src=+(-1920, -1061), dst=+(0, 0), alu=3, src.size=1920x19, dst.size=3840x1080)
[  8355.484] sna_copy_boxes: dst=(priv=0x24646f0, gpu_bo=25, cpu_bo=0), src=(priv=0x250fc50, gpu_bo=16, cpu_bo=0), replaces=0
[  8355.484] copy_prefer_gpu: source has gpu damage, force gpu? 1
[  8355.484] sna_drawable_use_bo pixmap=13, box=((1920, 1061), (3840, 1080)), flags=b...
[  8355.484] sna_drawable_use_bo: exported target, set PREFER_GPU
[  8355.484] sna_drawable_use_bo: pinned, never REPLACES
[  8355.484] sna_drawable_use_bo: flush=1, shm=0, cpu=0 => flags=b
[  8355.484] sna_drawable_use_bo: use GPU fast path (all-damaged)
[  8355.484] sna_drawable_use_bo: using whole GPU bo
[  8355.484] sna_pixmap_move_to_gpu(pixmap=56, usage=0), flags=a
[  8355.484] sna_pixmap_move_to_gpu: already all-damaged
[  8355.484] sna_pixmap_mark_active: pixmap=56, handle=16
[  8355.484] sna_copy_boxes: move whole src_pixmap to GPU and copy
[  8355.484] gen6_render_copy_boxes (-1920, -1061)->(0, 0) x 1, alu=3, self-copy=0, overlaps? 0
[  8355.484] kgem_bo_is_render: handle=16, rq? 0 [0]
[  8355.484] kgem_bo_is_render: handle=25, rq? 1 [3]
[  8355.484] kgem_bo_is_render: handle=25, rq? 1 [3]
[  8355.484] kgem_bo_is_render: handle=16, rq? 0 [0]
[  8355.484] kgem_bo_is_render: handle=25, rq? 1 [3]
[  8355.484] sna_blt_copy_boxes src=(-1920, -1061) -> (0, 0) x 1, tiling=(1, 1), pitch=(7680, 15360)
[  8355.484]   sna_blt_copy_boxes: box=(1920, 1061)x(1920, 19)
[  8355.484] kgem_add_reloc: handle=25, pos=4, delta=0, domains=28002
[  8355.484] kgem_add_handle: handle=25, index=0
[  8355.484] __kgem_bo_mark_dirty: handle=25 (proxy? 0)
[  8355.484] kgem_add_reloc: handle=16, pos=7, delta=0, domains=28000
[  8355.484] kgem_add_handle: handle=16, index=1
[  8355.484] sna_accel_flush: flush?=1, dirty?=0
[  8355.484] batch[3/3, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=4095 [fenced=0]
[  8355.484] __kgem_busy: handle=1, busy=262145, wedged=0
[  8355.484] __kgem_busy: handle=2, busy=262145, wedged=0
[  8355.484] kgem_create_linear(40)
[  8355.484] search_linear_cache: num_pages=1, flags=202, use_active? 0, use_large=0 [max=65536]
[  8355.484] search_linear_cache: inactive and cache bucket empty
[  8355.484] search_linear_cache: active cache bucket empty
[  8355.484] kgem_create_linear: new handle=20, num_pages=1
[  8355.484] __kgem_bo_init(handle=20, num_pages=1)
[  8355.484] gem_write__cachealigned(handle=20, offset=0, len=40)
[  8355.484] kgem_commit: release handle=20 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b3ca000
[  8355.484] kgem_commit: release handle=25 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=ca3000
[  8355.484] kgem_commit: release handle=16 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=c3d000
[  8355.484] sna_copy_area: src=(0, 0)x(1920, 1080)+(0, 0) -> dst=(0, 0)+(0, 1061); alu=3, pm=ffffffffffffffff, depth=24
[  8355.484] sna_do_copy: src=(0, 0), dst=(0, 0), size=(1920x1080)
[  8355.484] sna_do_copy: after drawable: src=(0, 0), dst=(0, 1061), size=(1920x1080)
[  8355.484] sna_do_copy: dst extents (0, 1061), (1920, 2141), dst clip extents (0, 1061), (1920, 1080), dst size=1920x19
[  8355.484] sna_do_copy: clipped dst extents (0, 1061), (1920, 1080)
[  8355.484] sna_do_copy: unclipped src extents (0, 0), (1920, 19)
[  8355.484] sna_do_copy: pixmap -- no source clipping
[  8355.484] sna_do_copy: src extents (0, 0), (1920, 19) x 1
[  8355.484] sna_do_copy: copy region (0, 1061), (1920, 1080) x 1
[  8355.484] sna_copy_boxes (boxes=1x[(0, 1061), (1920, 1080)...], src=+(0, -1061), dst=+(0, 0), alu=3, src.size=1920x19, dst.size=3840x1080)
[  8355.484] sna_copy_boxes: dst=(priv=0x24646f0, gpu_bo=25, cpu_bo=0), src=(priv=0x2510500, gpu_bo=17, cpu_bo=0), replaces=0
[  8355.484] copy_prefer_gpu: source has gpu damage, force gpu? 1
[  8355.484] sna_drawable_use_bo pixmap=13, box=((0, 1061), (1920, 1080)), flags=b...
[  8355.484] sna_drawable_use_bo: exported target, set PREFER_GPU
[  8355.484] sna_drawable_use_bo: pinned, never REPLACES
[  8355.484] sna_drawable_use_bo: flush=1, shm=0, cpu=0 => flags=b
[  8355.484] sna_drawable_use_bo: use GPU fast path (all-damaged)
[  8355.484] sna_drawable_use_bo: using whole GPU bo
[  8355.484] sna_pixmap_move_to_gpu(pixmap=60, usage=0), flags=a
[  8355.484] sna_pixmap_move_to_gpu: already all-damaged
[  8355.484] sna_pixmap_mark_active: pixmap=60, handle=17
[  8355.484] sna_copy_boxes: move whole src_pixmap to GPU and copy
[  8355.484] gen6_render_copy_boxes (0, -1061)->(0, 0) x 1, alu=3, self-copy=0, overlaps? 0
[  8355.484] sna_blt_copy_boxes src=(0, -1061) -> (0, 0) x 1, tiling=(1, 1), pitch=(7680, 15360)
[  8355.484]   sna_blt_copy_boxes: box=(0, 1061)x(1920, 19)
[  8355.484] kgem_add_reloc: handle=25, pos=4, delta=0, domains=28002
[  8355.484] kgem_add_handle: handle=25, index=0
[  8355.484] __kgem_bo_mark_dirty: handle=25 (proxy? 0)
[  8355.484] kgem_add_reloc: handle=17, pos=7, delta=0, domains=28000
[  8355.484] kgem_add_handle: handle=17, index=1
[  8355.484] sna_accel_flush: flush?=1, dirty?=0
[  8355.484] batch[3/3, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=4095 [fenced=0]
[  8355.484] __kgem_busy: handle=1, busy=262145, wedged=0
[  8355.484] __kgem_busy: handle=2, busy=262145, wedged=0
[  8355.484] kgem_create_linear(40)
[  8355.484] search_linear_cache: num_pages=1, flags=202, use_active? 0, use_large=0 [max=65536]
[  8355.484] search_linear_cache: inactive and cache bucket empty
[  8355.484] search_linear_cache: active cache bucket empty
[  8355.484] kgem_create_linear: new handle=21, num_pages=1
[  8355.484] __kgem_bo_init(handle=21, num_pages=1)
[  8355.484] gem_write__cachealigned(handle=21, offset=0, len=40)
[  8355.484] kgem_commit: release handle=21 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b3cb000
[  8355.484] kgem_commit: release handle=25 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=ca3000
[  8355.484] kgem_commit: release handle=17 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=c6a000
[  8355.484] sna_accel_flush: flush?=0, dirty?=0
[  8355.484] sna_block_handler (tv=0.652000)
[  8355.484] kgem_retire, need_retire?=1
[  8355.484] kgem_retire__flushing: 0 bo on flushing list
[  8355.484] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  8355.484] __kgem_busy: handle=1, busy=262145, wedged=0
[  8355.484] kgem_retire__requests_ring: ring=1, 7 outstanding requests, oldest=1
[  8355.484] kgem_retire -- retired=0, need_retire=1
[  8355.484] sna_scanout_do_flush: flush timer active: delta=8
[  8355.484] sna_accel_block_handler: evaluating timers, active=7
[  8355.484] sna_accel_block_handler: flush timer expires in 8 [8355492]
[  8355.490] sna_wakeup_handler
[  8355.490] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
[  8355.490] sna_mode_wakeup: len=64
[  8355.490] sna_dri2_vblank_handler(type=3, sequence=294312)
[  8355.490] sna_crtc_record_swap: recording last swap on pipe=1, frame 294312, time 8355.492106
[  8355.490] sna_dri2_vblank_handler: 3 complete, frame=294312 tv=8355.492106
[  8355.490] sna_dri2_blit_complete: blit finished
[  8355.490] sna_dri2_vblank_handler: triple buffer swap complete, unblocking client (frame=294312, tv=8355.492106)
[  8355.490] frame_swap_complete: draw=39122672, pipe=1, frame=294312 [msc=403112], tv=8355.492106
[  8355.490] sna_accel_flush: flush?=0, dirty?=0
[  8355.490] sna_dri2_remove_event: remove[0x26d0fa0] from window 18874370)
[  8355.490] chain_swap: chaining draw=18874370, type=3
[  8355.490] chain_swap: emitting chained vsync'ed blit
[  8355.490] can_xchg: window size: 1916x1041, clip=(1922, 18), (3838, 1059) x 1, pixmap size=3840x1080
[  8355.490] is_clipped: region[1]x(1922, 18),(3838, 1059) against drawable 3840x1080
[  8355.490] can_xchg: no, 1916x1041 window is clipped: clip region=(1922, 18), (3838, 1059)
[  8355.490] can_xchg_crtc: no, window [(1922, 18), (3838, 1059)] does not cover CRTC [(1920, 0), (3840, 1080)]
[  8355.490] is_clipped: region[1]x(1922, 18),(3838, 1059) against drawable 1916x1041
[  8355.490] sna_pixmap_move_area_to_gpu: pixmap=13 box=(1922, 18), (3838, 1059), flags=41
[  8355.490] _sna_damage_subtract_box(None - (1922, 18), (3838, 1059))...
[  8355.490]   = None
[  8355.490] sna_pixmap_mark_active: pixmap=13, handle=25
[  8355.490] __sna_dri2_copy_region: updated FrontLeft dst_bo from handle=40 to handle=25
[  8355.490] sna_dri2_select_mode: src busy?=0
[  8355.490] sna_dri2_select_mode: dst busy?=0
[  8355.490] sna_dri2_select_mode: src/dst is idle, using defaults
[  8355.490] gen6_render_copy_boxes (-1922, -18)->(0, 0) x 1, alu=3, self-copy=0, overlaps? 0
[  8355.490] kgem_bo_is_render: handle=45, rq? 1 [0]
[  8355.490] kgem_bo_is_render: handle=25, rq? 1 [3]
[  8355.490] kgem_bo_is_render: handle=25, rq? 1 [3]
[  8355.490] kgem_bo_is_render: handle=45, rq? 1 [0]
[  8355.490] kgem_bo_is_render: handle=25, rq? 1 [3]
[  8355.490] sna_blt_copy_boxes src=(-1922, -18) -> (0, 0) x 1, tiling=(1, 1), pitch=(7680, 15360)
[  8355.490]   sna_blt_copy_boxes: box=(1922, 18)x(1916, 1041)
[  8355.490] kgem_add_reloc: handle=25, pos=4, delta=0, domains=28002
[  8355.490] kgem_add_handle: handle=25, index=0
[  8355.490] __kgem_bo_mark_dirty: handle=25 (proxy? 0)
[  8355.490] kgem_add_reloc: handle=45, pos=7, delta=0, domains=28000
[  8355.490] kgem_add_handle: handle=45, index=1
[  8355.490] __sna_dri2_copy_region: flushing? 0
[  8355.490] sna_wait_vblank(pipe=1, waiting until seq=1 [relative])
[  8355.490] swap_limit: draw=18874370 setting swap limit to 2
[  8355.490] sna_dri2_swap_limit_validate: swap limit set to 2
[  8355.490] sna_dri2_event_free
[  8355.490] _sna_dri2_destroy_buffer: 0x26d1330 [handle=45] -- refcnt=3, pixmap=13
[  8355.490] _sna_dri2_destroy_buffer: 0x26d1600 [handle=40] -- refcnt=3, pixmap=13
[  8355.490] sna_dri2_event_free: releasing cached handle=11
[  8355.490] sna_dri2_vblank_handler complete
[  8355.490] sna_mode_wakeup: removing handle=11 from scanout, installing handle=40
[  8355.490] _kgem_bo_destroy: handle=11, proxy? 0
[  8355.490] __kgem_bo_destroy: handle=11, size=16588800
[  8355.490] kgem_bo_move_to_scanout: moving 11 [fb 58] to scanout cache, active? 1
[  8355.490] sna_mode_wakeup: flip complete, pending? 1
[  8355.490] sna_mode_redisplay: posting shadow damage? 1 (flips pending? 0)
[  8355.490] sna_mode_redisplay: damage: 4x(0, 0), (3840, 1080)
[  8355.490] sna_pixmap_move_to_gpu(pixmap=13, usage=16), flags=10a
[  8355.490] sna_pixmap_move_to_gpu: already all-damaged
[  8355.490] sna_pixmap_mark_active: pixmap=13, handle=25
[  8355.490] sna_mode_redisplay: crtc[0] transformed? 0
[  8355.490] sna_mode_redisplay: crtc[1] transformed? 0
[  8355.490] sna_mode_redisplay: flipping tear-free outputs, current scanout handle=46 [active?=1], new handle=25 [active=0]
[  8355.490] get_fb: reusing fb=57 for handle=25
[  8355.490] batch[3/3, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=6075 [fenced=0]
[  8355.490] __kgem_busy: handle=1, busy=0, wedged=0
[  8355.490] __kgem_retire_rq: request 1 complete
[  8355.490] __kgem_busy: handle=40, busy=0, wedged=0
[  8355.490] gem_write__cachealigned(handle=1, offset=0, len=40)
[  8355.490] kgem_commit: release handle=1 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=9c0b000
[  8355.490] kgem_commit: release handle=25 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=ca3000
[  8355.490] kgem_commit: release handle=45 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=58b6000
[  8355.490] sna_mode_redisplay: crtc 0 [5, pipe=0] active? 46, transformed? 0
[  8355.490] sna_mode_redisplay: marking flip bo as busy [40001 -> mode=3]
[  8355.490] sna_mode_redisplay: crtc 1 [8, pipe=1] active? 40, transformed? 0
[  8355.491] sna_mode_redisplay: changing pitch (15360 == 7680) or offset (780 == 0)
[  8355.491] sna_crtc_flip CRTC:8 [pipe=1], handle=40
[  8355.491] sna_crtc_flip: attaching output 'HDMI2' 3 [19] to crtc:8 (pipe 1) (possible crtc:3, possible clones:1)
[  8355.491] sna_crtc_flip: applying crtc [8, pipe=1] mode=1920x1080+1920+0@148500, fb=61 update to 1 outputs [19...]
[  8355.491] (EE) intel(0): sna_mode_redisplay: page flipping failed, disabling CRTC:8 (pipe=1)
[  8355.491] sna_crtc_disable: disabling crtc [8, pipe=1]
[  8355.491] sna_crtc_force_outputs_off(pipe=1), currently? 0
[  8355.491] sna_output_dpms(HDMI2:19): dpms=3 (current: 0), active? 1
[  8355.541] sna_crtc_disable_shadow: disa