add[14/16]: (61, 0), (63, 4)
add[15/16]: (64, 0), (66, 4)
add[16/16]: (67, 0), (69, 4)
add[17/32]: (70, 0), (72, 4)
add[18/32]: (73, 0), (75, 4)
add[19/32]: (76, 0), (78, 4)
add[20/32]: (79, 0), (81, 4)
add[21/32]: (82, 0), (84, 4)
sfbBitmapToRegion: region extents=(22, 0), (84, 4) x 21
sna_validate_gc changes=80000
sna_validate_gc: recomputing clip
sna_validate_gc: composite clip=21x[(22, 3), (84, 7)]
sna_copy_area: src=(0, 256)x(64, 4)+(0, 0) -> dst=(21, 3)+(0, 0); alu=3, pm=ffffffff
sna_do_copy: src=(0, 256), dst=(21, 3), size=(64x4)
sna_do_copy: after drawable: src=(0, 256), dst=(21, 3), size=(64x4)
sna_do_copy: dst extents (21, 3), (85, 7)
sna_do_copy: pixmap -- no source clipping
sna_do_copy: src extents (1, 256), (63, 260) x 1
sna_do_copy: copy region (22, 3), (84, 7) x 21
sna_copy_boxes (boxes=21x[(22, 3), (24, 7)...], src=+(-21, 253), alu=3, src.size=128x260, dst.size=85x26)
sna_copy_boxes: dst=(priv=0x872cf10, gpu_bo=164, cpu_bo=0), src=(priv=0x87315c0, gpu_bo=0, cpu_bo=114), replaces=0
kgem_bo_is_busy: handle=114, domain: 1 exec? 0, rq? 0
__kgem_busy: handle=15, busy=0, wedged=0
__kgem_ring_is_idle: ring=0 idle (handle=15)
__kgem_busy: handle=15, busy=0, wedged=0
__kgem_retire_rq: request 15 complete
kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
sna_drawable_use_bo pixmap=1859, box=((22, 3), (84, 7)), flags=1...
sna_pixmap_undo_cow: pixmap=1859, handle=164 [refcnt=2], cow refcnt=2, flags=2
sna_pixmap_undo_cow: copying cow
kgem_choose_tiling: 85x26 -> 1
kgem_create_2d(85x26, bpp=32, tiling=1, exact=0, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=0)
kgem_bo_remove_from_inactive: removing handle=269 from inactive
  from inactive: pitch=512, tiling=1: handle=269, id=25001
gen3_render_copy_boxes (0, 0)->(0, 0) x 1
sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(512, 512)
kgem_set_mode: flushing before new bo
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=2, aperture=106 [fenced=512]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=14, offset=0, len=40)
kgem_commit: release handle=14 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e8000
kgem_commit: release handle=173 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=f500000
kgem_commit: release handle=304 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=d100000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
  sna_blt_copy_boxes: box=(0, 0)x(85, 26)
kgem_add_reloc: handle=269, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=269, index=0
__kgem_bo_mark_dirty: handle=269 (proxy? 0)
kgem_add_reloc: handle=164, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=164, index=1
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=1
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
move_to_gpu: can use CPU bo? cpu_damage=-1, gpu_damage=0, cpu hint=1
region_overlaps_damage?
__kgem_bo_is_busy: handle=269, domain: 0 exec? 1, rq? 1
use_shm_bo: yes, dst is busy
sna_copy_boxes: region overlaps CPU damage, copy from CPU bo (shm? 0)
sna_drawable_move_region_to_cpu(pixmap=1813 (128x260), [(1, 256), (63, 260)], flags=10)
sna_drawable_move_region_to_cpu: pixmap=1813 all damaged on CPU
gen3_render_copy_boxes (-21, 253)->(0, 0) x 21
sna_blt_copy_boxes src=(-21, 253) -> (0, 0) x 21, tiling=(0, 1), pitch=(512, 512)
  sna_blt_copy_boxes: box=(22, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=12, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=15, delta=0, domains=00028000
kgem_add_handle: handle=114, index=2
  sna_blt_copy_boxes: box=(25, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=20, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=23, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(28, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=28, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=31, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(31, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=36, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=39, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(34, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=44, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=47, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(37, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=52, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=55, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(40, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=60, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=63, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(43, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=68, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=71, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(46, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=76, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=79, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(49, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=84, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=87, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(52, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=92, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=95, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(55, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=100, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=103, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(58, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=108, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=111, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(61, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=116, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=119, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(64, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=124, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=127, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(67, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=132, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=135, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(70, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=140, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=143, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(73, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=148, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=151, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(76, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=156, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=159, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(79, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=164, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=167, delta=0, domains=00028000
  sna_blt_copy_boxes: box=(82, 3)x(2, 4)
kgem_add_reloc: handle=269, pos=172, delta=0, domains=00028002
kgem_add_reloc: handle=114, pos=175, delta=0, domains=00028000
sfbBitmapToRegion bitmap=85x4
add[1/16]: (2, 0), (4, 4)
sfbBitmapToRegion: region extents=(2, 0), (4, 4) x 1
sna_validate_gc changes=c0000
sna_validate_gc: recomputing clip
sna_validate_gc: composite clip=1x[(2, 4), (4, 8)]
sna_copy_area: src=(62, 4)x(66, 4)+(0, 0) -> dst=(0, 4)+(0, 0); alu=3, pm=ffffffff
sna_do_copy: src=(62, 4), dst=(0, 4), size=(66x4)
sna_do_copy: after drawable: src=(62, 4), dst=(0, 4), size=(66x4)
sna_do_copy: dst extents (0, 4), (66, 8)
sna_do_copy: pixmap -- no source clipping
sna_do_copy: src extents (64, 4), (66, 8) x 1
sna_do_copy: copy region (2, 4), (4, 8) x 1
sna_copy_boxes (boxes=1x[(2, 4), (4, 8)...], src=+(62, 0), alu=3, src.size=128x260, dst.size=85x26)
sna_copy_boxes: dst=(priv=0x872cf10, gpu_bo=269, cpu_bo=0), src=(priv=0x872d930, gpu_bo=0, cpu_bo=115), replaces=0
kgem_bo_is_busy: handle=115, domain: 0 exec? 0, rq? 0
__kgem_busy: handle=14, busy=0, wedged=0
__kgem_ring_is_idle: ring=0 idle (handle=14)
__kgem_busy: handle=14, busy=0, wedged=0
__kgem_retire_rq: request 14 complete
__kgem_busy: handle=173, busy=0, wedged=0
__kgem_busy: handle=304, busy=0, wedged=0
kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
sna_drawable_use_bo pixmap=1859, box=((2, 4), (4, 8)), flags=9...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=9
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
move_to_gpu: can use CPU bo? cpu_damage=-1, gpu_damage=0, cpu hint=0
region_overlaps_damage?
use_shm_bo: yes, ordinary CPU bo
sna_copy_boxes: region overlaps CPU damage, copy from CPU bo (shm? 0)
sna_drawable_move_region_to_cpu(pixmap=1830 (128x260), [(64, 4), (66, 8)], flags=10)
sna_drawable_move_region_to_cpu: pixmap=1830 all damaged on CPU
gen3_render_copy_boxes (62, 0)->(0, 0) x 1
sna_blt_copy_boxes src=(62, 0) -> (0, 0) x 1, tiling=(0, 1), pitch=(512, 512)
  sna_blt_copy_boxes: box=(2, 4)x(2, 4)
kgem_add_reloc: handle=269, pos=180, delta=0, domains=00028002
kgem_add_reloc: handle=115, pos=183, delta=0, domains=00028000
kgem_add_handle: handle=115, index=3
batch[3/0, flags=1c00]: 186 186 65528 65528, nreloc=46, nexec=4, nfence=2, aperture=74 [fenced=512]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=13, offset=0, len=744)
kgem_commit: release handle=13 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e7000
kgem_commit: release handle=269 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=4400000
kgem_commit: release handle=164 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=4300000
kgem_commit: release handle=114 (proxy? 0), dirty? 0 flush? 0, snoop? 1 -> offset=4501000
kgem_commit: release handle=115 (proxy? 0), dirty? 0 flush? 0, snoop? 1 -> offset=4522000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
sna_validate_gc changes=e0000
sna_validate_gc: recomputing clip
sna_validate_gc: composite clip=1x[(1588, 725), (1673, 751)]
sna_copy_area: src=(0, 0)x(85, 26)+(0, 0) -> dst=(0, 0)+(1588, 725); alu=3, pm=ffffffff
sna_do_copy: src=(0, 0), dst=(0, 0), size=(85x26)
sna_do_copy: after drawable: src=(0, 0), dst=(1588, 725), size=(85x26)
sna_do_copy: dst extents (1588, 725), (1673, 751)
sna_do_copy: pixmap -- no source clipping
sna_do_copy: src extents (0, 0), (85, 26) x 1
sna_do_copy: copy region (1588, 725), (1673, 751) x 1
sna_copy_boxes (boxes=1x[(1588, 725), (1673, 751)...], src=+(-1588, -725), alu=3, src.size=85x26, dst.size=87x796)
sna_copy_boxes: dst=(priv=0x8763880, gpu_bo=173, cpu_bo=0), src=(priv=0x872cf10, gpu_bo=269, cpu_bo=0), replaces=0
copy_prefer_gpu: source has gpu damage, force gpu? 1
sna_drawable_use_bo pixmap=2383, box=((1, 711), (86, 737)), flags=b...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=b
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
sna_pixmap_move_to_gpu(pixmap=1859, usage=0), flags=a
sna_pixmap_move_to_gpu: already all-damaged
sna_pixmap_mark_active: pixmap=1859, handle=269
sna_copy_boxes: move whole src_pixmap to GPU and copy
gen3_render_copy_boxes (-1, -711)->(0, 0) x 1
sna_blt_copy_boxes src=(-1, -711) -> (0, 0) x 1, tiling=(1, 1), pitch=(512, 512)
  sna_blt_copy_boxes: box=(1, 711)x(85, 26)
kgem_add_reloc: handle=173, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=173, index=0
__kgem_bo_mark_dirty: handle=173 (proxy? 0)
kgem_add_reloc: handle=269, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=269, index=1
sna_block_handler (tv=-1.000000)
kgem_retire
kgem_retire__flushing: 0 bo on flushing list
__kgem_busy: handle=13, busy=0, wedged=0
__kgem_retire_rq: request 13 complete
kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
kgem_retire -- retired=0, need_retire=0
kgem_bo_is_busy: handle=210, domain: 0 exec? 0, rq? 0
gen3_render_retire: resetting idle vbo
sna_accel_block_handler: GPU idle, flushing
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=2, aperture=104 [fenced=512]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=12, offset=0, len=40)
kgem_commit: release handle=12 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e6000
kgem_commit: release handle=173 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=f500000
kgem_commit: release handle=269 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=4400000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_accel_do_throttle (time=222228115), triggered
sna_accel_throttle (time=222228115)
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(1587, 14)+(0, 0), size=(87, 796)
sna_compute_composite_region: dst=(1587, 14)x(87, 796)
sna_compute_composite_region: initial clip against dst->pDrawable: (1587, 14), (1674, 810)
clip_to_dst: region: 1x[(1587, 14), (1674, 810)], clip: 2x[(1588, 643), (1673, 751)]
sna_compute_composite_region: clip against dst->pCompositeClip: (1588, 643), (1673, 751)
sna_compute_composite_region: clip against src (87x796 clip=0): (1588, 643), (1673, 751)
sna_composite: composite region extents:+(1, 629) -> (1588, 643), (1673, 751) + (0, 0)
gen3_render_composite()
sna_blt_composite (1, 629), (1588, 643), 85x108
sna_blt_composite: blt dst offset (0, 0), source offset (-1587, -14), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=1706, box=((1588, 643), (1673, 751)), flags=3...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=3
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
prepare_blt_copy
blt_composite_copy_boxes: nbox=2
blt_composite_copy_boxes: box (1588, 643), (1673, 667)
sna_blt_copy_one: (1, 629) -> (1588, 643) x (85, 24)
kgem_add_reloc: handle=105, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=105, index=0
__kgem_bo_mark_dirty: handle=105 (proxy? 0)
kgem_add_reloc: handle=173, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=173, index=1
blt_composite_copy_boxes: box (1588, 725), (1673, 751)
sna_blt_copy_one: (1, 711) -> (1588, 725) x (85, 26)
kgem_add_reloc: handle=105, pos=12, delta=0, domains=00028002
kgem_add_reloc: handle=173, pos=15, delta=0, domains=00028000
apply_damage: damage=(nil), region=2 [(1588, 643), (1673, 751) + (0, 0)]
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(269, 8)+(0, 0), size=(1306, 1038)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(1638, 1018)+(0, 0), size=(42, 26)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(0, 0)+(0, 0), size=(1680, 1050)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(0, 0)+(0, 0), size=(1680, 1050)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(0, 0)+(0, 0), size=(1680, 1050)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(1638, 1018)+(0, 0), size=(42, 26)
sna_composite: empty clip, skipping
sna_composite(3 src=(0, 0), mask=(0, 0), dst=(251, -7)+(0, 0), size=(1342, 1071)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(269, 8)+(0, 0), size=(1306, 1038)
sna_composite: empty clip, skipping
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(1587, 14)+(0, 0), size=(87, 796)
sna_composite: empty clip, skipping
sna_block_handler (tv=-1.000000)
kgem_retire
kgem_retire__flushing: 0 bo on flushing list
__kgem_busy: handle=12, busy=0, wedged=0
__kgem_retire_rq: request 12 complete
__kgem_busy: handle=269, busy=0, wedged=0
kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
kgem_retire -- retired=0, need_retire=0
kgem_bo_is_busy: handle=210, domain: 0 exec? 0, rq? 0
gen3_render_retire: resetting idle vbo
sna_accel_block_handler: GPU idle, flushing
batch[3/0, flags=1c00]: 18 18 65528 65528, nreloc=4, nexec=2, nfence=2, aperture=2212 [fenced=4352]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=11, offset=0, len=72)
kgem_commit: release handle=11 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e5000
kgem_commit: release handle=105 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=8000000
kgem_commit: release handle=173 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=f500000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
sna_put_image((0, 3198)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3198, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3198), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3198)]: [(0, 0), (5030, 3198)] ]  + [(0, 3198), (5030, 3211)])
damage_union: extending damage (0, 0), (5030, 3198) by (0, 3198), (5030, 3211)
  = [[(0, 0), (5030, 3211)]: [(0, 0), (5030, 3211)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3198), (5030, 3211)])...
  = None
sna_block_handler (tv=-1.000000)
kgem_retire
kgem_retire__flushing: 0 bo on flushing list
__kgem_busy: handle=11, busy=0, wedged=0
__kgem_retire_rq: request 11 complete
__kgem_busy: handle=105, busy=0, wedged=0
__kgem_busy: handle=173, busy=0, wedged=0
kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
kgem_retire -- retired=0, need_retire=0
kgem_bo_is_busy: handle=210, domain: 0 exec? 0, rq? 0
gen3_render_retire: resetting idle vbo
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3211)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3211, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3211), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3211)]: [(0, 0), (5030, 3211)] ]  + [(0, 3211), (5030, 3224)])
damage_union: extending damage (0, 0), (5030, 3211) by (0, 3211), (5030, 3224)
  = [[(0, 0), (5030, 3224)]: [(0, 0), (5030, 3224)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3211), (5030, 3224)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3224)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3224, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3224), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3224)]: [(0, 0), (5030, 3224)] ]  + [(0, 3224), (5030, 3237)])
damage_union: extending damage (0, 0), (5030, 3224) by (0, 3224), (5030, 3237)
  = [[(0, 0), (5030, 3237)]: [(0, 0), (5030, 3237)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3224), (5030, 3237)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3237)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3237, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3237), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3237)]: [(0, 0), (5030, 3237)] ]  + [(0, 3237), (5030, 3250)])
damage_union: extending damage (0, 0), (5030, 3237) by (0, 3237), (5030, 3250)
  = [[(0, 0), (5030, 3250)]: [(0, 0), (5030, 3250)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3237), (5030, 3250)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3250)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3250, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3250), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3250)]: [(0, 0), (5030, 3250)] ]  + [(0, 3250), (5030, 3263)])
damage_union: extending damage (0, 0), (5030, 3250) by (0, 3250), (5030, 3263)
  = [[(0, 0), (5030, 3263)]: [(0, 0), (5030, 3263)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3250), (5030, 3263)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3263)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3263, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3263), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3263)]: [(0, 0), (5030, 3263)] ]  + [(0, 3263), (5030, 3276)])
damage_union: extending damage (0, 0), (5030, 3263) by (0, 3263), (5030, 3276)
  = [[(0, 0), (5030, 3276)]: [(0, 0), (5030, 3276)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3263), (5030, 3276)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3276)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3276, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3276), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3276)]: [(0, 0), (5030, 3276)] ]  + [(0, 3276), (5030, 3289)])
damage_union: extending damage (0, 0), (5030, 3276) by (0, 3276), (5030, 3289)
  = [[(0, 0), (5030, 3289)]: [(0, 0), (5030, 3289)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3276), (5030, 3289)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3289)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3289, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3289), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3289)]: [(0, 0), (5030, 3289)] ]  + [(0, 3289), (5030, 3302)])
damage_union: extending damage (0, 0), (5030, 3289) by (0, 3289), (5030, 3302)
  = [[(0, 0), (5030, 3302)]: [(0, 0), (5030, 3302)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3289), (5030, 3302)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3302)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3302, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3302), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3302)]: [(0, 0), (5030, 3302)] ]  + [(0, 3302), (5030, 3315)])
damage_union: extending damage (0, 0), (5030, 3302) by (0, 3302), (5030, 3315)
  = [[(0, 0), (5030, 3315)]: [(0, 0), (5030, 3315)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3302), (5030, 3315)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3315)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3315, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3315), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3315)]: [(0, 0), (5030, 3315)] ]  + [(0, 3315), (5030, 3328)])
damage_union: extending damage (0, 0), (5030, 3315) by (0, 3315), (5030, 3328)
  = [[(0, 0), (5030, 3328)]: [(0, 0), (5030, 3328)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3315), (5030, 3328)])...
  = None
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_put_image((0, 3328)x(5030, 13), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3328, 5030, 13) x 1
memcpy_blt: src=(0, 0), dst=(0, 3328), size=5030x13, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3328)]: [(0, 0), (5030, 3328)] ]  + [(0, 3328), (5030, 3341)])
damage_union: extending damage (0, 0), (5030, 3328) by (0, 3328), (5030, 3341)
  = [[(0, 0), (5030, 3341)]: [(0, 0), (5030, 3341)] ] 
sna_damage_reduce_all(width=5030, height=3353)
_sna_damage_subtract(None - [(0, 3328), (5030, 3341)])...
  = None
sna_put_image((0, 3341)x(5030, 12), depth=32, format=2)
try_upload_tiled_x: bo? 1, can map? 1
try_upload_tiled_x: tiling=0
kgem_bo_map__cpu(handle=284, size=67616768, map=(nil):0x9892f000)
kgem_bo_sync__cpu: handle=284
try_upload_tiled_x: upload(0, 3341, 5030, 12) x 1
memcpy_blt: src=(0, 0), dst=(0, 3341), size=5030x12, pitch=20120/20160
_sna_damage_add([[(0, 0), (5030, 3341)]: [(0, 0), (5030, 3341)] ]  + [(0, 3341), (5030, 3353)])
damage_union: extending damage (0, 0), (5030, 3341) by (0, 3341), (5030, 3353)
  = [[(0, 0), (5030, 3353)]: [(0, 0), (5030, 3353)] ] 
sna_damage_reduce_all(width=5030, height=3353)
__sna_damage_all(5030, 3353)
sna_block_handler (tv=-1.000000)
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(0, 0)+(0, 0), size=(1680, 1050)
sna_compute_composite_region: dst=(0, 0)x(1680, 1050)
sna_compute_composite_region: initial clip against dst->pDrawable: (0, 0), (1680, 1050)
clip_to_dst: region: 1x[(0, 0), (1680, 1050)], clip: 2x[(1588, 643), (1673, 751)]
sna_compute_composite_region: clip against dst->pCompositeClip: (1588, 643), (1673, 751)
sna_compute_composite_region: clip against src (1680x1050 clip=2): (1588, 643), (1673, 751)
sna_composite: composite region extents:+(1588, 643) -> (1588, 643), (1673, 751) + (0, 0)
gen3_render_composite()
sna_blt_composite (1588, 643), (1588, 643), 85x108
sna_blt_composite: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=3, box=((1588, 643), (1673, 751)), flags=3...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=3
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
prepare_blt_copy
blt_composite_copy_boxes: nbox=2
blt_composite_copy_boxes: box (1588, 643), (1673, 667)
sna_blt_copy_one: (1588, 643) -> (1588, 643) x (85, 24)
kgem_add_reloc: handle=27, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=27, index=0
__kgem_bo_mark_dirty: handle=27 (proxy? 0)
kgem_add_reloc: handle=105, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=105, index=1
blt_composite_copy_boxes: box (1588, 725), (1673, 751)
sna_blt_copy_one: (1588, 725) -> (1588, 725) x (85, 26)
kgem_add_reloc: handle=27, pos=12, delta=0, domains=00028002
kgem_add_reloc: handle=105, pos=15, delta=0, domains=00028000
apply_damage: damage=(nil), region=2 [(1588, 643), (1673, 751) + (0, 0)]
batch[3/0, flags=1c00]: 18 18 65528 65528, nreloc=4, nexec=2, nfence=2, aperture=4224 [fenced=8192]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=10, offset=0, len=72)
kgem_commit: release handle=10 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e4000
kgem_commit: release handle=27 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=1000000
kgem_commit: release handle=105 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=8000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
sna_block_handler (tv=-1.000000)
kgem_retire
kgem_retire__flushing: 0 bo on flushing list
__kgem_busy: handle=10, busy=0, wedged=0
__kgem_retire_rq: request 10 complete
__kgem_busy: handle=27, busy=0, wedged=0
kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
kgem_retire -- retired=0, need_retire=0
kgem_bo_is_busy: handle=210, domain: 0 exec? 0, rq? 0
gen3_render_retire: resetting idle vbo
start_flush: scanout=27 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
sna_accel_do_flush -- no pending write to scanout
sna_wakeup_handler
sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
sna_composite(1 src=(0, 0), mask=(0, 0), dst=(0, 0)+(0, 0), size=(5030, 3353)
sna_compute_composite_region: dst=(0, 0)x(5030, 3353)
sna_compute_composite_region: initial clip against dst->pDrawable: (0, 0), (5030, 3353)
clip_to_dst: region: 1x[(0, 0), (5030, 3353)], clip: 1x[(0, 0), (5030, 3353)]
sna_compute_composite_region: clip against dst->pCompositeClip: (0, 0), (5030, 3353)
sna_compute_composite_region: clip against src (5030x3353 clip=0): (0, 0), (5030, 3353)
sna_composite: composite region extents:+(0, 0) -> (0, 0), (5030, 3353) + (0, 0)
gen3_render_composite()
sna_blt_composite (0, 0), (0, 0), 5030x3353
sna_blt_composite: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=26724, box=((0, 0), (5030, 3353)), flags=1b...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=1b
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
prepare_blt_copy: fallback -- no room in aperture
kgem_bo_is_busy: handle=284, domain: 1 exec? 0, rq? 0
kgem_bo_is_busy: handle=261, domain: 0 exec? 0, rq? 0
gen3_composite_fallback: dst is already on the GPU, try to use GPU
sna_tiling_composite size=(5030, 3353), tile=2048
apply_damage: damage=(nil), region=1 [(0, 0), (5030, 3353) + (0, 0)]
sna_tiling_composite_done -- 5030x3353, count=1, step size=2048
gen3_render_composite()
sna_blt_composite (0, 0), (0, 0), 2048x2048
sna_blt_composite: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=26724, box=((0, 0), (2048, 2048)), flags=b...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=b
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
prepare_blt_copy: fallback -- no room in aperture
kgem_bo_is_busy: handle=284, domain: 1 exec? 0, rq? 0
kgem_bo_is_busy: handle=261, domain: 0 exec? 0, rq? 0
gen3_composite_fallback: dst is already on the GPU, try to use GPU
sna_drawable_use_bo pixmap=26724, box=((0, 0), (2048, 2048)), flags=7...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
gen3_composite_set_target: pixmap=0x884e858, format=20020888, size=5030x3353, pitch=20160, delta=(0,0),damage=0xffffffff
sna_render_composite_redirect: target too large (5030x3353), copying to temporary 2048x2048, max 2048 / 8192
kgem_choose_tiling: TLB miss between lines 2048x2048 (pitch=8192), forcing tiling 1
kgem_create_2d(2048x2048, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
  new pitch=8192, tiling=1, handle=77, id=25002, num_pages=4096 [4096], bucket=12
sna_render_composite_redirect: original box (0, 0), (2048, 2048)
gen3_render_composite: preparing source
gen3_composite_picture: (0, 0)x(2048, 2048), dst=(0, 0)
gen3_composite_picture: integer translation (0, 0), removing
gen3_composite_picture: pixmap too large (5030x3353), extracting (0, 0)x(2048,2048)
sna_render_picture_extract (0, 0)x(2048, 2048) [dst=(0, 0)]
sna_render_picture_partial (0, 0)x(2048, 2048) [dst=(0, 0)]
sna_render_picture_partial sample=(0, 0), (2048, 2048): (2048, 2048)/(5030, 3353), repeat=0
use_cpu_bo: no cpu bo
sna_pixmap_force_to_gpu(pixmap=0x87d6530, flags=12)
sna_pixmap_move_to_gpu(pixmap=26726, usage=0), flags=52
sna_pixmap_move_to_gpu: already all-damaged
sna_pixmap_mark_active: pixmap=26726, handle=284
sna_render_picture_partial: pitch too great 20160 > 8192
sna_render_picture_extract sample=(0, 0), (2048, 2048): (2048, 2048)/(5030, 3353), repeat=0
sna_render_picture_extract box=(0, 0), (2048, 2048): (2048, 2048)/(5030, 3353)
use_cpu_bo: no cpu bo
kgem_choose_tiling: TLB miss between lines 2048x2048 (pitch=8192), forcing tiling 1
kgem_create_2d(2048x2048, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
  new pitch=8192, tiling=1, handle=101, id=25003, num_pages=4096 [4096], bucket=12
gen3_render_copy_boxes (0, 0)->(0, 0) x 1
sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(0, 1), pitch=(20160, 8192)
  sna_blt_copy_boxes: box=(0, 0)x(2048, 2048)
kgem_add_reloc: handle=101, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=101, index=0
__kgem_bo_mark_dirty: handle=101 (proxy? 0)
kgem_add_reloc: handle=284, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=284, index=1
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=9, offset=0, len=40)
kgem_commit: release handle=9 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e3000
kgem_commit: release handle=101 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=5000000
kgem_commit: release handle=284 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=9000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
sna_blt_composite__convert src=101, dst=77 (redirect? 261)
sna_blt_composite__convert: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
sna_tiling_composite_done: rect[0] = (0, 0)x(5030,3353), tile=(0,0)x(2048, 2048), blt=(0,0),(2048,2048), delta=(0,0)
blt_composite_copy: src=(0, 0), dst=(0, 0), size=(2048, 2048)
blt_composite_copy: box=(0, 0), (2048, 2048)
sna_blt_copy_one: (0, 0) -> (0, 0) x (2048, 2048)
kgem_add_reloc: handle=77, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=77, index=0
__kgem_bo_mark_dirty: handle=77 (proxy? 0)
kgem_add_reloc: handle=101, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=101, index=1
_kgem_bo_destroy: handle=101, proxy? 0
__kgem_bo_destroy: handle=101
__kgem_bo_destroy: handle=101 -> active
sna_render_composite_redirect_done: copying temporary to dst
sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 0), pitch=(8192, 20160)
__kgem_busy: handle=9, busy=65537, wedged=0
__kgem_ring_is_idle: last requests handle=9 still busy
kgem_check_many_bo_fenced: final aperture usage (24700) is greater than high water mark (24576)
aperture_check: aperture required 134725632 bytes, available 244338688 bytes
aperture_check: num_pages=32892, estimated max usable=18433
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=2, aperture=8192 [fenced=8192]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=8, offset=0, len=40)
kgem_commit: release handle=8 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e2000
kgem_commit: release handle=77 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=6000000
kgem_commit: release handle=101 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=5000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
  sna_blt_copy_boxes: box=(0, 0)x(2048, 2048)
kgem_add_reloc: handle=261, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=261, index=0
__kgem_bo_mark_dirty: handle=261 (proxy? 0)
kgem_add_reloc: handle=77, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=77, index=1
_kgem_bo_destroy: handle=77, proxy? 0
__kgem_bo_destroy: handle=77
__kgem_bo_destroy: handle=77 -> active
gen3_render_composite()
sna_blt_composite (2048, 0), (2048, 0), 2048x2048
sna_blt_composite: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=26724, box=((2048, 0), (4096, 2048)), flags=b...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=b
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
kgem_check_many_bo_fenced: final aperture usage (37112) is greater than high water mark (24576)
aperture_check: aperture required 168787968 bytes, available 244338688 bytes
aperture_check: num_pages=41208, estimated max usable=22529
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=7, offset=0, len=40)
kgem_commit: release handle=7 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e1000
kgem_commit: release handle=261 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=7000000
kgem_commit: release handle=77 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=6000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
prepare_blt_copy: fallback -- no room in aperture
kgem_bo_is_busy: handle=284, domain: 3 exec? 0, rq? 1
kgem_bo_is_busy: handle=261, domain: 3 exec? 0, rq? 1
gen3_composite_fallback: dst is already on the GPU, try to use GPU
sna_drawable_use_bo pixmap=26724, box=((2048, 0), (4096, 2048)), flags=7...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
gen3_composite_set_target: pixmap=0x884e858, format=20020888, size=5030x3353, pitch=20160, delta=(0,0),damage=0xffffffff
sna_render_composite_redirect: target too large (5030x3353), copying to temporary 2048x2048, max 2048 / 8192
kgem_choose_tiling: TLB miss between lines 2048x2048 (pitch=8192), forcing tiling 1
kgem_create_2d(2048x2048, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
kgem_bo_remove_from_active: removing handle=77 from active
  1:from active: pitch=8192, tiling=1, handle=77, id=25004
sna_render_composite_redirect: original box (2048, 0), (4096, 2048)
gen3_render_composite: preparing source
gen3_composite_picture: (2048, 0)x(2048, 2048), dst=(2048, 0)
gen3_composite_picture: integer translation (0, 0), removing
gen3_composite_picture: pixmap too large (5030x3353), extracting (2048, 0)x(2048,2048)
sna_render_picture_extract (2048, 0)x(2048, 2048) [dst=(2048, 0)]
sna_render_picture_partial (2048, 0)x(2048, 2048) [dst=(2048, 0)]
sna_render_picture_partial sample=(2048, 0), (4096, 2048): (2048, 2048)/(5030, 3353), repeat=0
use_cpu_bo: no cpu bo
sna_pixmap_force_to_gpu(pixmap=0x87d6530, flags=12)
sna_pixmap_move_to_gpu(pixmap=26726, usage=0), flags=52
sna_pixmap_move_to_gpu: already all-damaged
sna_pixmap_mark_active: pixmap=26726, handle=284
sna_render_picture_partial: pitch too great 20160 > 8192
sna_render_picture_extract sample=(2048, 0), (4096, 2048): (2048, 2048)/(5030, 3353), repeat=0
sna_render_picture_extract box=(2048, 0), (4096, 2048): (2048, 2048)/(5030, 3353)
use_cpu_bo: no cpu bo
kgem_choose_tiling: TLB miss between lines 2048x2048 (pitch=8192), forcing tiling 1
kgem_create_2d(2048x2048, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
kgem_bo_remove_from_active: removing handle=101 from active
  1:from active: pitch=8192, tiling=1, handle=101, id=25005
gen3_render_copy_boxes (0, 0)->(-2048, 0) x 1
sna_blt_copy_boxes src=(0, 0) -> (-2048, 0) x 1, tiling=(0, 1), pitch=(20160, 8192)
  sna_blt_copy_boxes: box=(2048, 0)x(2048, 2048)
kgem_add_reloc: handle=101, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=101, index=0
__kgem_bo_mark_dirty: handle=101 (proxy? 0)
kgem_add_reloc: handle=284, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=284, index=1
sna_blt_composite__convert src=101, dst=77 (redirect? 261)
kgem_check_many_bo_fenced: final aperture usage (24700) is greater than high water mark (24576)
aperture_check: aperture required 117948416 bytes, available 244338688 bytes
aperture_check: num_pages=28796, estimated max usable=22529
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=6, offset=0, len=40)
kgem_commit: release handle=6 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6e0000
kgem_commit: release handle=101 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=5000000
kgem_commit: release handle=284 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b07c000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
sna_blt_composite__convert: blt dst offset (-2048, 0), source offset (0, 0), with alpha fixup? 0
sna_tiling_composite_done: rect[0] = (0, 0)x(5030,3353), tile=(2048,0)x(2048, 2048), blt=(2048,0),(4096,2048), delta=(2048,0)
blt_composite_copy: src=(2048, 0), dst=(2048, 0), size=(2048, 2048)
blt_composite_copy: box=(0, 0), (2048, 2048)
sna_blt_copy_one: (2048, 0) -> (0, 0) x (2048, 2048)
kgem_add_reloc: handle=77, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=77, index=0
__kgem_bo_mark_dirty: handle=77 (proxy? 0)
kgem_add_reloc: handle=101, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=101, index=1
_kgem_bo_destroy: handle=101, proxy? 0
__kgem_bo_destroy: handle=101
__kgem_bo_destroy: handle=101 -> active
sna_render_composite_redirect_done: copying temporary to dst
sna_blt_copy_boxes src=(-2048, 0) -> (0, 0) x 1, tiling=(1, 0), pitch=(8192, 20160)
__kgem_busy: handle=6, busy=65537, wedged=0
__kgem_ring_is_idle: last requests handle=6 still busy
kgem_check_many_bo_fenced: final aperture usage (24700) is greater than high water mark (24576)
aperture_check: aperture required 134725632 bytes, available 244338688 bytes
aperture_check: num_pages=32892, estimated max usable=18433
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=2, aperture=8192 [fenced=8192]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=5, offset=0, len=40)
kgem_commit: release handle=5 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6df000
kgem_commit: release handle=77 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=6000000
kgem_commit: release handle=101 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=5000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
  sna_blt_copy_boxes: box=(2048, 0)x(2048, 2048)
kgem_add_reloc: handle=261, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=261, index=0
__kgem_bo_mark_dirty: handle=261 (proxy? 0)
kgem_add_reloc: handle=77, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=77, index=1
_kgem_bo_destroy: handle=77, proxy? 0
__kgem_bo_destroy: handle=77
__kgem_bo_destroy: handle=77 -> active
gen3_render_composite()
sna_blt_composite (4096, 0), (4096, 0), 934x2048
sna_blt_composite: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=26724, box=((4096, 0), (5030, 2048)), flags=b...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=b
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
kgem_check_many_bo_fenced: final aperture usage (37112) is greater than high water mark (24576)
aperture_check: aperture required 168787968 bytes, available 244338688 bytes
aperture_check: num_pages=41208, estimated max usable=22529
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=4, offset=0, len=40)
kgem_commit: release handle=4 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6de000
kgem_commit: release handle=261 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=7000000
kgem_commit: release handle=77 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=6000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
prepare_blt_copy: fallback -- no room in aperture
kgem_bo_is_busy: handle=284, domain: 3 exec? 0, rq? 1
kgem_bo_is_busy: handle=261, domain: 3 exec? 0, rq? 1
gen3_composite_fallback: dst is already on the GPU, try to use GPU
sna_drawable_use_bo pixmap=26724, box=((4096, 0), (5030, 2048)), flags=7...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
gen3_composite_set_target: pixmap=0x884e858, format=20020888, size=5030x3353, pitch=20160, delta=(0,0),damage=0xffffffff
sna_render_composite_redirect: target too large (5030x3353), copying to temporary 934x2048, max 2048 / 8192
kgem_choose_tiling: TLB near-miss between lines 934x2048 (pitch=3736), forcing tiling 1
kgem_create_2d(934x2048, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
kgem_bo_remove_from_active: removing handle=77 from active
  1:from active: pitch=8192, tiling=1, handle=77, id=25006
sna_render_composite_redirect: original box (4096, 0), (5030, 2048)
gen3_render_composite: preparing source
gen3_composite_picture: (4096, 0)x(934, 2048), dst=(4096, 0)
gen3_composite_picture: integer translation (0, 0), removing
gen3_composite_picture: pixmap too large (5030x3353), extracting (4096, 0)x(934,2048)
sna_render_picture_extract (4096, 0)x(934, 2048) [dst=(4096, 0)]
sna_render_picture_partial (4096, 0)x(934, 2048) [dst=(4096, 0)]
sna_render_picture_partial sample=(4096, 0), (5030, 2048): (934, 2048)/(5030, 3353), repeat=0
use_cpu_bo: no cpu bo
sna_pixmap_force_to_gpu(pixmap=0x87d6530, flags=12)
sna_pixmap_move_to_gpu(pixmap=26726, usage=0), flags=52
sna_pixmap_move_to_gpu: already all-damaged
sna_pixmap_mark_active: pixmap=26726, handle=284
sna_render_picture_partial: pitch too great 20160 > 8192
sna_render_picture_extract sample=(4096, 0), (5030, 2048): (934, 2048)/(5030, 3353), repeat=0
sna_render_picture_extract box=(4096, 0), (5030, 2048): (934, 2048)/(5030, 3353)
use_cpu_bo: no cpu bo
kgem_choose_tiling: TLB near-miss between lines 934x2048 (pitch=3736), forcing tiling 1
kgem_create_2d(934x2048, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
kgem_bo_remove_from_active: removing handle=101 from active
  1:from active: pitch=8192, tiling=1, handle=101, id=25007
gen3_render_copy_boxes (0, 0)->(-4096, 0) x 1
sna_blt_copy_boxes src=(0, 0) -> (-4096, 0) x 1, tiling=(0, 1), pitch=(20160, 8192)
  sna_blt_copy_boxes: box=(4096, 0)x(934, 2048)
kgem_add_reloc: handle=101, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=101, index=0
__kgem_bo_mark_dirty: handle=101 (proxy? 0)
kgem_add_reloc: handle=284, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=284, index=1
sna_blt_composite__convert src=101, dst=77 (redirect? 261)
kgem_check_many_bo_fenced: final aperture usage (24700) is greater than high water mark (24576)
aperture_check: aperture required 117948416 bytes, available 244338688 bytes
aperture_check: num_pages=28796, estimated max usable=22529
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=3, offset=0, len=40)
kgem_commit: release handle=3 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6dd000
kgem_commit: release handle=101 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=5000000
kgem_commit: release handle=284 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b07c000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
sna_blt_composite__convert: blt dst offset (-4096, 0), source offset (0, 0), with alpha fixup? 0
sna_tiling_composite_done: rect[0] = (0, 0)x(5030,3353), tile=(4096,0)x(934, 2048), blt=(4096,0),(5030,2048), delta=(4096,0)
blt_composite_copy: src=(4096, 0), dst=(4096, 0), size=(934, 2048)
blt_composite_copy: box=(0, 0), (934, 2048)
sna_blt_copy_one: (4096, 0) -> (0, 0) x (934, 2048)
kgem_add_reloc: handle=77, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=77, index=0
__kgem_bo_mark_dirty: handle=77 (proxy? 0)
kgem_add_reloc: handle=101, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=101, index=1
_kgem_bo_destroy: handle=101, proxy? 0
__kgem_bo_destroy: handle=101
__kgem_bo_destroy: handle=101 -> active
sna_render_composite_redirect_done: copying temporary to dst
sna_blt_copy_boxes src=(-4096, 0) -> (0, 0) x 1, tiling=(1, 0), pitch=(8192, 20160)
__kgem_busy: handle=3, busy=65537, wedged=0
__kgem_ring_is_idle: last requests handle=3 still busy
kgem_check_many_bo_fenced: final aperture usage (24700) is greater than high water mark (24576)
aperture_check: aperture required 134725632 bytes, available 244338688 bytes
aperture_check: num_pages=32892, estimated max usable=18433
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=2, aperture=8192 [fenced=8192]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=2, offset=0, len=40)
kgem_commit: release handle=2 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6dc000
kgem_commit: release handle=77 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=6000000
kgem_commit: release handle=101 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=5000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
  sna_blt_copy_boxes: box=(4096, 0)x(934, 2048)
kgem_add_reloc: handle=261, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=261, index=0
__kgem_bo_mark_dirty: handle=261 (proxy? 0)
kgem_add_reloc: handle=77, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=77, index=1
_kgem_bo_destroy: handle=77, proxy? 0
__kgem_bo_destroy: handle=77
__kgem_bo_destroy: handle=77 -> active
gen3_render_composite()
sna_blt_composite (0, 2048), (0, 2048), 2048x1305
sna_blt_composite: blt dst offset (0, 0), source offset (0, 0), with alpha fixup? 0
use_cpu_bo: no cpu bo
sna_drawable_use_bo pixmap=26724, box=((0, 2048), (2048, 3353)), flags=b...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=b
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
kgem_check_many_bo_fenced: final aperture usage (37112) is greater than high water mark (24576)
aperture_check: aperture required 168787968 bytes, available 244338688 bytes
aperture_check: num_pages=41208, estimated max usable=22529
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=1, offset=0, len=40)
kgem_commit: release handle=1 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6db000
kgem_commit: release handle=261 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=7000000
kgem_commit: release handle=77 (proxy? 0), dirty? 0 flush? 1, snoop? 0 -> offset=6000000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
kgem_check_many_bo_fenced: final aperture usage (33016) is greater than high water mark (24576)
prepare_blt_copy: fallback -- no room in aperture
kgem_bo_is_busy: handle=284, domain: 3 exec? 0, rq? 1
kgem_bo_is_busy: handle=261, domain: 3 exec? 0, rq? 1
gen3_composite_fallback: dst is already on the GPU, try to use GPU
sna_drawable_use_bo pixmap=26724, box=((0, 2048), (2048, 3353)), flags=7...
sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
sna_drawable_use_bo: use GPU fast path (all-damaged)
sna_drawable_use_bo: using whole GPU bo
gen3_composite_set_target: pixmap=0x884e858, format=20020888, size=5030x3353, pitch=20160, delta=(0,0),damage=0xffffffff
sna_render_composite_redirect: target too large (5030x3353), copying to temporary 2048x1305, max 2048 / 8192
kgem_choose_tiling: TLB miss between lines 2048x1305 (pitch=8192), forcing tiling 1
kgem_create_2d(2048x1305, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
kgem_bo_remove_from_active: removing handle=77 from active
  1:from active: pitch=8192, tiling=1, handle=77, id=25008
sna_render_composite_redirect: original box (0, 2048), (2048, 3353)
gen3_render_composite: preparing source
gen3_composite_picture: (0, 2048)x(2048, 1305), dst=(0, 2048)
gen3_composite_picture: integer translation (0, 0), removing
gen3_composite_picture: pixmap too large (5030x3353), extracting (0, 2048)x(2048,1305)
sna_render_picture_extract (0, 2048)x(2048, 1305) [dst=(0, 2048)]
sna_render_picture_partial (0, 2048)x(2048, 1305) [dst=(0, 2048)]
sna_render_picture_partial sample=(0, 2048), (2048, 3353): (2048, 1305)/(5030, 3353), repeat=0
use_cpu_bo: no cpu bo
sna_pixmap_force_to_gpu(pixmap=0x87d6530, flags=12)
sna_pixmap_move_to_gpu(pixmap=26726, usage=0), flags=52
sna_pixmap_move_to_gpu: already all-damaged
sna_pixmap_mark_active: pixmap=26726, handle=284
sna_render_picture_partial: pitch too great 20160 > 8192
sna_render_picture_extract sample=(0, 2048), (2048, 3353): (2048, 1305)/(5030, 3353), repeat=0
sna_render_picture_extract box=(0, 2048), (2048, 3353): (2048, 1305)/(5030, 3353)
use_cpu_bo: no cpu bo
kgem_choose_tiling: TLB miss between lines 2048x1305 (pitch=8192), forcing tiling 1
kgem_create_2d(2048x1305, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
kgem_bo_remove_from_active: removing handle=101 from active
  1:from active: pitch=8192, tiling=1, handle=101, id=25009
gen3_render_copy_boxes (0, 0)->(0, -2048) x 1
sna_blt_copy_boxes src=(0, 0) -> (0, -2048) x 1, tiling=(0, 1), pitch=(20160, 8192)
  sna_blt_copy_boxes: box=(0, 2048)x(2048, 1305)
kgem_add_reloc: handle=101, pos=4, delta=0, domains=00028002
kgem_add_handle: handle=101, index=0
__kgem_bo_mark_dirty: handle=101 (proxy? 0)
kgem_add_reloc: handle=284, pos=7, delta=0, domains=00028000
kgem_add_handle: handle=284, index=1
sna_blt_composite__convert src=101, dst=77 (redirect? 261)
kgem_check_many_bo_fenced: final aperture usage (24700) is greater than high water mark (24576)
aperture_check: aperture required 117948416 bytes, available 244338688 bytes
aperture_check: num_pages=28796, estimated max usable=22529
batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=1, aperture=20604 [fenced=4096]
kgem_finish_buffers: buffer handle=241, used=688128, exec?=0, write=1, mmapped=cpu
kgem_finish_buffers: skipping unattached handle=241, used=688128
gem_write__cachealigned(handle=16, offset=0, len=40)
kgem_commit: release handle=16 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=6ea000
kgem_commit: release handle=101 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=5000000
kgem_commit: release handle=284 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b07c000
kgem_bo_can_map: map=0xa2950000:(nil), tiling=0, domain=0, offset=221249536
sna_blt_composite__convert: blt dst offset (0, -2048), source offset (0, 0), with alpha fixup? 0
sna_tiling_composite_done: rect[0] = (0, 0)x(5030,3353), tile=(0,2048)x(2048, 1305), blt=(0,2048),(2048,3353), delta=(0,2048)
blt_composite_copy: src=(0, 2048), dst=(0, 2048), size=(2048, 1305)
blt_composite_copy: box=(0, 0), (2048, 1305)
sna_blt_copy_one: (0, 2048) -> (0, 0) x (2048, 1305)
X: /mnt/storage/tmp/portage/x11-drivers/xf86-video-intel-9999/work/xf86-video-intel-9999/src/sna/sna_blt.c:500: sna_blt_copy_one: Assertion `(src_y + height) * blt->bo[0]->pitch <= kgem_bo_size(blt->bo[0])' failed.