[  4840.846] kgem_add_reloc: handle=32, pos=7, delta=0, domains=00028000
[  4840.846] kgem_add_handle: handle=32, index=1
[  4840.846] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2736 [fenced=0]
[  4840.846] gem_write__cachealigned(handle=8, offset=0, len=40)
[  4840.847] kgem_commit: release handle=8 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=540000
[  4840.847] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=559000
[  4840.847] kgem_commit: release handle=32 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=214a000
[  4840.847] kgem_bo_can_map: map=0x7f31f3e79000:0x7f31f3e85000, tiling=0, domain=0, offset=53665792
[  4840.847] __sna_dri_copy_region: flushing? 0
[  4840.847] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.847] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.847] sna_block_handler (tv=584.366000)
[  4840.847] kgem_retire, need_retire?=1
[  4840.847] kgem_retire__flushing: 0 bo on flushing list
[  4840.847] __kgem_busy: handle=8, busy=0, wedged=0
[  4840.847] __kgem_retire_rq: request 8 complete
[  4840.847] __kgem_busy: handle=28, busy=0, wedged=0
[  4840.847] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  4840.847] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
[  4840.847] kgem_retire -- retired=0, need_retire=0
[  4840.847] kgem_bo_is_busy: handle=45, domain: 0 exec? 0, rq? 0
[  4840.847] gen4_render_retire: resetting idle vbo
[  4840.847] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.847] sna_accel_do_flush -- no pending write to scanout
[  4840.847] sna_accel_do_throttle -- no pending activity
[  4840.847] sna_wakeup_handler
[  4840.847] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.847] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.847] sna_block_handler (tv=584.366000)
[  4840.847] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.847] sna_accel_do_flush -- no pending write to scanout
[  4840.847] sna_accel_do_throttle -- no pending activity
[  4840.851] sna_wakeup_handler
[  4840.851] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.851] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.851] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.851] sna_covering_crtc: box exactly matches crtc [0]
[  4840.851] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.851] sna_dri_get_msc(pipe=0)
[  4840.851] sna_wait_vblank
[  4840.851] sna_dri_get_msc: msc=288151, ust=4840846945
[  4840.851] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.851] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.851] sna_block_handler (tv=584.362000)
[  4840.851] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.851] sna_accel_do_flush -- no pending write to scanout
[  4840.851] sna_accel_do_throttle -- no pending activity
[  4840.851] sna_wakeup_handler
[  4840.851] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.851] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.851] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.851] sna_covering_crtc: box exactly matches crtc [0]
[  4840.851] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.851] sna_dri_schedule_wait_msc(pipe=0, target_msc=0, divisor=2, rem=0)
[  4840.851] sna_wait_vblank
[  4840.851] sna_dri_add_frame_event: add[0x20a4230] to window 14680091)
[  4840.851] sna_dri_window_set_chain: head now 0x20a4230
[  4840.851] sna_wait_vblank
[  4840.851] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.851] sna_block_handler (tv=584.362000)
[  4840.851] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.851] sna_accel_do_flush -- no pending write to scanout
[  4840.851] sna_accel_do_throttle -- no pending activity
[  4840.863] sna_wakeup_handler
[  4840.863] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.863] sna_mode_wakeup: len=32
[  4840.863] sna_dri_vblank_handler(type=0)
[  4840.863] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.863] sna_dri_remove_frame_event: remove[0x20a4230] from window 14680091)
[  4840.863] sna_dri_window_set_chain: head now (nil)
[  4840.863] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.863] sna_block_handler (tv=584.350000)
[  4840.863] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.863] sna_accel_do_flush -- no pending write to scanout
[  4840.863] sna_accel_do_throttle -- no pending activity
[  4840.863] sna_wakeup_handler
[  4840.863] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.863] sna_dri_copy_region: pixmap=3, src=32 (refs=1/1, flush=1, attach=1) , dst=28 (refs=2/3, flush=1, attach=0)
[  4840.863] sna_dri_copy_region: region (90, 0), (179, 49) x 1
[  4840.863] is_clipped: region[1]x(0, 0),(1440, 900) against drawable 1440x900
[  4840.863] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
[  4840.863] sna_pixmap_move_to_gpu: already all-damaged
[  4840.863] sna_pixmap_mark_active: pixmap=3, handle=28
[  4840.863] gen5_render_copy_boxes alu=3, src=3:handle=32, dst=3:handle=28 boxes=1 x [((90, 0), (179, 49))...], flags=1
[  4840.863] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(6144, 6144)
[  4840.863]   sna_blt_copy_boxes: box=(90, 0)x(89, 49)
[  4840.863] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
[  4840.863] kgem_add_handle: handle=28, index=0
[  4840.863] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
[  4840.863] kgem_add_reloc: handle=32, pos=7, delta=0, domains=00028000
[  4840.863] kgem_add_handle: handle=32, index=1
[  4840.863] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2736 [fenced=0]
[  4840.863] gem_write__cachealigned(handle=7, offset=0, len=40)
[  4840.863] kgem_commit: release handle=7 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=53f000
[  4840.863] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=559000
[  4840.863] kgem_commit: release handle=32 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=214a000
[  4840.863] kgem_bo_can_map: map=0x7f31f3e79000:0x7f31f3e85000, tiling=0, domain=0, offset=53665792
[  4840.863] __sna_dri_copy_region: flushing? 0
[  4840.863] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.863] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.863] sna_block_handler (tv=584.350000)
[  4840.863] kgem_retire, need_retire?=1
[  4840.863] kgem_retire__flushing: 0 bo on flushing list
[  4840.863] __kgem_busy: handle=7, busy=0, wedged=0
[  4840.863] __kgem_retire_rq: request 7 complete
[  4840.863] __kgem_busy: handle=28, busy=0, wedged=0
[  4840.863] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  4840.863] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
[  4840.863] kgem_retire -- retired=0, need_retire=0
[  4840.863] kgem_bo_is_busy: handle=45, domain: 0 exec? 0, rq? 0
[  4840.863] gen4_render_retire: resetting idle vbo
[  4840.863] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.863] sna_accel_do_flush -- no pending write to scanout
[  4840.863] sna_accel_do_throttle -- no pending activity
[  4840.863] sna_wakeup_handler
[  4840.863] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.863] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.863] sna_block_handler (tv=584.350000)
[  4840.863] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.863] sna_accel_do_flush -- no pending write to scanout
[  4840.863] sna_accel_do_throttle -- no pending activity
[  4840.868] sna_wakeup_handler
[  4840.868] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.869] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.869] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.869] sna_covering_crtc: box exactly matches crtc [0]
[  4840.869] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.869] sna_dri_get_msc(pipe=0)
[  4840.869] sna_wait_vblank
[  4840.869] sna_dri_get_msc: msc=288152, ust=4840863598
[  4840.869] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.869] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.869] sna_block_handler (tv=584.344000)
[  4840.869] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.869] sna_accel_do_flush -- no pending write to scanout
[  4840.869] sna_accel_do_throttle -- no pending activity
[  4840.869] sna_wakeup_handler
[  4840.869] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.869] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.869] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.869] sna_covering_crtc: box exactly matches crtc [0]
[  4840.869] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.869] sna_dri_schedule_wait_msc(pipe=0, target_msc=0, divisor=2, rem=1)
[  4840.869] sna_wait_vblank
[  4840.869] sna_dri_add_frame_event: add[0x20b40a0] to window 14680091)
[  4840.869] sna_dri_window_set_chain: head now 0x20b40a0
[  4840.869] sna_wait_vblank
[  4840.869] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.869] sna_block_handler (tv=584.344000)
[  4840.869] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.869] sna_accel_do_flush -- no pending write to scanout
[  4840.869] sna_accel_do_throttle -- no pending activity
[  4840.879] sna_wakeup_handler
[  4840.879] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.879] sna_mode_wakeup: len=32
[  4840.879] sna_dri_vblank_handler(type=0)
[  4840.879] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.879] sna_dri_remove_frame_event: remove[0x20b40a0] from window 14680091)
[  4840.879] sna_dri_window_set_chain: head now (nil)
[  4840.880] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.880] sna_block_handler (tv=584.333000)
[  4840.880] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.880] sna_accel_do_flush -- no pending write to scanout
[  4840.880] sna_accel_do_throttle -- no pending activity
[  4840.880] sna_wakeup_handler
[  4840.880] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.880] sna_dri_copy_region: pixmap=3, src=32 (refs=1/1, flush=1, attach=1) , dst=28 (refs=2/3, flush=1, attach=0)
[  4840.880] sna_dri_copy_region: region (90, 0), (179, 49) x 1
[  4840.880] is_clipped: region[1]x(0, 0),(1440, 900) against drawable 1440x900
[  4840.880] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
[  4840.880] sna_pixmap_move_to_gpu: already all-damaged
[  4840.880] sna_pixmap_mark_active: pixmap=3, handle=28
[  4840.880] gen5_render_copy_boxes alu=3, src=3:handle=32, dst=3:handle=28 boxes=1 x [((90, 0), (179, 49))...], flags=1
[  4840.880] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(6144, 6144)
[  4840.880]   sna_blt_copy_boxes: box=(90, 0)x(89, 49)
[  4840.880] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
[  4840.880] kgem_add_handle: handle=28, index=0
[  4840.880] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
[  4840.880] kgem_add_reloc: handle=32, pos=7, delta=0, domains=00028000
[  4840.880] kgem_add_handle: handle=32, index=1
[  4840.880] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2736 [fenced=0]
[  4840.880] gem_write__cachealigned(handle=6, offset=0, len=40)
[  4840.880] kgem_commit: release handle=6 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=53e000
[  4840.880] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=559000
[  4840.880] kgem_commit: release handle=32 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=214a000
[  4840.880] kgem_bo_can_map: map=0x7f31f3e79000:0x7f31f3e85000, tiling=0, domain=0, offset=53665792
[  4840.880] __sna_dri_copy_region: flushing? 0
[  4840.880] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.880] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.880] sna_block_handler (tv=584.333000)
[  4840.880] kgem_retire, need_retire?=1
[  4840.880] kgem_retire__flushing: 0 bo on flushing list
[  4840.880] __kgem_busy: handle=6, busy=0, wedged=0
[  4840.880] __kgem_retire_rq: request 6 complete
[  4840.880] __kgem_busy: handle=28, busy=0, wedged=0
[  4840.880] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  4840.880] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
[  4840.880] kgem_retire -- retired=0, need_retire=0
[  4840.880] kgem_bo_is_busy: handle=45, domain: 0 exec? 0, rq? 0
[  4840.880] gen4_render_retire: resetting idle vbo
[  4840.880] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.880] sna_accel_do_flush -- no pending write to scanout
[  4840.880] sna_accel_do_throttle -- no pending activity
[  4840.880] sna_wakeup_handler
[  4840.881] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.881] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.881] sna_block_handler (tv=584.332000)
[  4840.881] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.881] sna_accel_do_flush -- no pending write to scanout
[  4840.881] sna_accel_do_throttle -- no pending activity
[  4840.884] sna_wakeup_handler
[  4840.884] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.884] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.884] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.884] sna_covering_crtc: box exactly matches crtc [0]
[  4840.884] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.884] sna_dri_get_msc(pipe=0)
[  4840.884] sna_wait_vblank
[  4840.884] sna_dri_get_msc: msc=288153, ust=4840880263
[  4840.884] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.884] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.884] sna_block_handler (tv=584.329000)
[  4840.884] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.884] sna_accel_do_flush -- no pending write to scanout
[  4840.884] sna_accel_do_throttle -- no pending activity
[  4840.884] sna_wakeup_handler
[  4840.884] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.884] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.884] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.884] sna_covering_crtc: box exactly matches crtc [0]
[  4840.884] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.884] sna_dri_schedule_wait_msc(pipe=0, target_msc=0, divisor=2, rem=0)
[  4840.884] sna_wait_vblank
[  4840.884] sna_dri_add_frame_event: add[0x20b4470] to window 14680091)
[  4840.885] sna_dri_window_set_chain: head now 0x20b4470
[  4840.885] sna_wait_vblank
[  4840.885] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.885] sna_block_handler (tv=584.328000)
[  4840.885] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.885] sna_accel_do_flush -- no pending write to scanout
[  4840.885] sna_accel_do_throttle -- no pending activity
[  4840.896] sna_wakeup_handler
[  4840.896] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.896] sna_mode_wakeup: len=32
[  4840.896] sna_dri_vblank_handler(type=0)
[  4840.896] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.896] sna_dri_remove_frame_event: remove[0x20b4470] from window 14680091)
[  4840.896] sna_dri_window_set_chain: head now (nil)
[  4840.896] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.896] sna_block_handler (tv=584.317000)
[  4840.896] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.896] sna_accel_do_flush -- no pending write to scanout
[  4840.896] sna_accel_do_throttle -- no pending activity
[  4840.896] sna_wakeup_handler
[  4840.896] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.896] sna_dri_copy_region: pixmap=3, src=32 (refs=1/1, flush=1, attach=1) , dst=28 (refs=2/3, flush=1, attach=0)
[  4840.896] sna_dri_copy_region: region (90, 0), (179, 49) x 1
[  4840.896] is_clipped: region[1]x(0, 0),(1440, 900) against drawable 1440x900
[  4840.896] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
[  4840.896] sna_pixmap_move_to_gpu: already all-damaged
[  4840.896] sna_pixmap_mark_active: pixmap=3, handle=28
[  4840.896] gen5_render_copy_boxes alu=3, src=3:handle=32, dst=3:handle=28 boxes=1 x [((90, 0), (179, 49))...], flags=1
[  4840.896] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(6144, 6144)
[  4840.896]   sna_blt_copy_boxes: box=(90, 0)x(89, 49)
[  4840.896] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
[  4840.896] kgem_add_handle: handle=28, index=0
[  4840.896] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
[  4840.896] kgem_add_reloc: handle=32, pos=7, delta=0, domains=00028000
[  4840.896] kgem_add_handle: handle=32, index=1
[  4840.896] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2736 [fenced=0]
[  4840.897] gem_write__cachealigned(handle=5, offset=0, len=40)
[  4840.897] kgem_commit: release handle=5 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=53d000
[  4840.897] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=559000
[  4840.897] kgem_commit: release handle=32 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=214a000
[  4840.897] kgem_bo_can_map: map=0x7f31f3e79000:0x7f31f3e85000, tiling=0, domain=0, offset=53665792
[  4840.897] __sna_dri_copy_region: flushing? 0
[  4840.897] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.897] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.897] sna_block_handler (tv=584.316000)
[  4840.897] kgem_retire, need_retire?=1
[  4840.897] kgem_retire__flushing: 0 bo on flushing list
[  4840.897] __kgem_busy: handle=5, busy=0, wedged=0
[  4840.897] __kgem_retire_rq: request 5 complete
[  4840.897] __kgem_busy: handle=28, busy=0, wedged=0
[  4840.897] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  4840.897] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
[  4840.897] kgem_retire -- retired=0, need_retire=0
[  4840.897] kgem_bo_is_busy: handle=45, domain: 0 exec? 0, rq? 0
[  4840.897] gen4_render_retire: resetting idle vbo
[  4840.897] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.897] sna_accel_do_flush -- no pending write to scanout
[  4840.897] sna_accel_do_throttle -- no pending activity
[  4840.897] sna_wakeup_handler
[  4840.897] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.897] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.897] sna_block_handler (tv=584.316000)
[  4840.897] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.897] sna_accel_do_flush -- no pending write to scanout
[  4840.897] sna_accel_do_throttle -- no pending activity
[  4840.901] sna_wakeup_handler
[  4840.901] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.901] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.901] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.901] sna_covering_crtc: box exactly matches crtc [0]
[  4840.901] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.901] sna_dri_get_msc(pipe=0)
[  4840.901] sna_wait_vblank
[  4840.901] sna_dri_get_msc: msc=288154, ust=4840896931
[  4840.901] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.901] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.901] sna_block_handler (tv=584.312000)
[  4840.901] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.901] sna_accel_do_flush -- no pending write to scanout
[  4840.901] sna_accel_do_throttle -- no pending activity
[  4840.901] sna_wakeup_handler
[  4840.901] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.901] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.901] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.901] sna_covering_crtc: box exactly matches crtc [0]
[  4840.901] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.901] sna_dri_schedule_wait_msc(pipe=0, target_msc=0, divisor=2, rem=1)
[  4840.901] sna_wait_vblank
[  4840.901] sna_dri_add_frame_event: add[0x209c400] to window 14680091)
[  4840.901] sna_dri_window_set_chain: head now 0x209c400
[  4840.901] sna_wait_vblank
[  4840.901] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.901] sna_block_handler (tv=584.312000)
[  4840.901] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.901] sna_accel_do_flush -- no pending write to scanout
[  4840.901] sna_accel_do_throttle -- no pending activity
[  4840.913] sna_wakeup_handler
[  4840.913] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.913] sna_mode_wakeup: len=32
[  4840.913] sna_dri_vblank_handler(type=0)
[  4840.913] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.913] sna_dri_remove_frame_event: remove[0x209c400] from window 14680091)
[  4840.913] sna_dri_window_set_chain: head now (nil)
[  4840.913] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.913] sna_block_handler (tv=584.300000)
[  4840.913] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.913] sna_accel_do_flush -- no pending write to scanout
[  4840.913] sna_accel_do_throttle -- no pending activity
[  4840.913] sna_wakeup_handler
[  4840.913] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.913] sna_dri_copy_region: pixmap=3, src=32 (refs=1/1, flush=1, attach=1) , dst=28 (refs=2/3, flush=1, attach=0)
[  4840.913] sna_dri_copy_region: region (90, 0), (179, 49) x 1
[  4840.913] is_clipped: region[1]x(0, 0),(1440, 900) against drawable 1440x900
[  4840.913] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
[  4840.913] sna_pixmap_move_to_gpu: already all-damaged
[  4840.913] sna_pixmap_mark_active: pixmap=3, handle=28
[  4840.913] gen5_render_copy_boxes alu=3, src=3:handle=32, dst=3:handle=28 boxes=1 x [((90, 0), (179, 49))...], flags=1
[  4840.913] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(6144, 6144)
[  4840.913]   sna_blt_copy_boxes: box=(90, 0)x(89, 49)
[  4840.913] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
[  4840.913] kgem_add_handle: handle=28, index=0
[  4840.913] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
[  4840.913] kgem_add_reloc: handle=32, pos=7, delta=0, domains=00028000
[  4840.913] kgem_add_handle: handle=32, index=1
[  4840.913] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2736 [fenced=0]
[  4840.913] gem_write__cachealigned(handle=4, offset=0, len=40)
[  4840.913] kgem_commit: release handle=4 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=53c000
[  4840.913] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=559000
[  4840.913] kgem_commit: release handle=32 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=214a000
[  4840.913] kgem_bo_can_map: map=0x7f31f3e79000:0x7f31f3e85000, tiling=0, domain=0, offset=53665792
[  4840.913] __sna_dri_copy_region: flushing? 0
[  4840.913] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.913] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.913] sna_block_handler (tv=584.300000)
[  4840.913] kgem_retire, need_retire?=1
[  4840.913] kgem_retire__flushing: 0 bo on flushing list
[  4840.913] __kgem_busy: handle=4, busy=0, wedged=0
[  4840.913] __kgem_retire_rq: request 4 complete
[  4840.913] __kgem_busy: handle=28, busy=0, wedged=0
[  4840.913] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  4840.913] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
[  4840.913] kgem_retire -- retired=0, need_retire=0
[  4840.913] kgem_bo_is_busy: handle=45, domain: 0 exec? 0, rq? 0
[  4840.913] gen4_render_retire: resetting idle vbo
[  4840.913] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.913] sna_accel_do_flush -- no pending write to scanout
[  4840.913] sna_accel_do_throttle -- no pending activity
[  4840.913] sna_wakeup_handler
[  4840.913] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.913] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.913] sna_block_handler (tv=584.300000)
[  4840.914] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.914] sna_accel_do_flush -- no pending write to scanout
[  4840.914] sna_accel_do_throttle -- no pending activity
[  4840.917] sna_wakeup_handler
[  4840.917] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.917] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.917] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.917] sna_covering_crtc: box exactly matches crtc [0]
[  4840.917] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.917] sna_dri_get_msc(pipe=0)
[  4840.917] sna_wait_vblank
[  4840.917] sna_dri_get_msc: msc=288155, ust=4840913599
[  4840.917] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.917] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.917] sna_block_handler (tv=584.296000)
[  4840.917] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.917] sna_accel_do_flush -- no pending write to scanout
[  4840.917] sna_accel_do_throttle -- no pending activity
[  4840.917] sna_wakeup_handler
[  4840.917] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.917] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.917] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.917] sna_covering_crtc: box exactly matches crtc [0]
[  4840.917] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.917] sna_dri_schedule_wait_msc(pipe=0, target_msc=0, divisor=2, rem=0)
[  4840.917] sna_wait_vblank
[  4840.917] sna_dri_add_frame_event: add[0x2100550] to window 14680091)
[  4840.917] sna_dri_window_set_chain: head now 0x2100550
[  4840.917] sna_wait_vblank
[  4840.917] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.917] sna_block_handler (tv=584.296000)
[  4840.917] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.917] sna_accel_do_flush -- no pending write to scanout
[  4840.917] sna_accel_do_throttle -- no pending activity
[  4840.929] sna_wakeup_handler
[  4840.929] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.929] sna_mode_wakeup: len=32
[  4840.929] sna_dri_vblank_handler(type=0)
[  4840.929] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.929] sna_dri_remove_frame_event: remove[0x2100550] from window 14680091)
[  4840.929] sna_dri_window_set_chain: head now (nil)
[  4840.929] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.929] sna_block_handler (tv=584.284000)
[  4840.930] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.930] sna_accel_do_flush -- no pending write to scanout
[  4840.930] sna_accel_do_throttle -- no pending activity
[  4840.930] sna_wakeup_handler
[  4840.930] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.930] sna_dri_copy_region: pixmap=3, src=32 (refs=1/1, flush=1, attach=1) , dst=28 (refs=2/3, flush=1, attach=0)
[  4840.930] sna_dri_copy_region: region (90, 0), (179, 49) x 1
[  4840.930] is_clipped: region[1]x(0, 0),(1440, 900) against drawable 1440x900
[  4840.930] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
[  4840.930] sna_pixmap_move_to_gpu: already all-damaged
[  4840.930] sna_pixmap_mark_active: pixmap=3, handle=28
[  4840.930] gen5_render_copy_boxes alu=3, src=3:handle=32, dst=3:handle=28 boxes=1 x [((90, 0), (179, 49))...], flags=1
[  4840.930] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(1, 1), pitch=(6144, 6144)
[  4840.930]   sna_blt_copy_boxes: box=(90, 0)x(89, 49)
[  4840.930] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
[  4840.930] kgem_add_handle: handle=28, index=0
[  4840.930] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
[  4840.930] kgem_add_reloc: handle=32, pos=7, delta=0, domains=00028000
[  4840.930] kgem_add_handle: handle=32, index=1
[  4840.930] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2736 [fenced=0]
[  4840.930] gem_write__cachealigned(handle=3, offset=0, len=40)
[  4840.930] kgem_commit: release handle=3 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=53b000
[  4840.930] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=559000
[  4840.930] kgem_commit: release handle=32 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=214a000
[  4840.930] kgem_bo_can_map: map=0x7f31f3e79000:0x7f31f3e85000, tiling=0, domain=0, offset=53665792
[  4840.930] __sna_dri_copy_region: flushing? 0
[  4840.930] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.930] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.930] sna_block_handler (tv=584.283000)
[  4840.930] kgem_retire, need_retire?=1
[  4840.930] kgem_retire__flushing: 0 bo on flushing list
[  4840.930] __kgem_busy: handle=3, busy=0, wedged=0
[  4840.930] __kgem_retire_rq: request 3 complete
[  4840.930] __kgem_busy: handle=28, busy=0, wedged=0
[  4840.930] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
[  4840.930] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
[  4840.930] kgem_retire -- retired=0, need_retire=0
[  4840.930] kgem_bo_is_busy: handle=45, domain: 0 exec? 0, rq? 0
[  4840.930] gen4_render_retire: resetting idle vbo
[  4840.930] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.930] sna_accel_do_flush -- no pending write to scanout
[  4840.930] sna_accel_do_throttle -- no pending activity
[  4840.930] sna_wakeup_handler
[  4840.930] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.930] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.930] sna_block_handler (tv=584.283000)
[  4840.930] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.930] sna_accel_do_flush -- no pending write to scanout
[  4840.930] sna_accel_do_throttle -- no pending activity
[  4840.935] sna_wakeup_handler
[  4840.935] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.935] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.935] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.935] sna_covering_crtc: box exactly matches crtc [0]
[  4840.935] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.935] sna_dri_get_msc(pipe=0)
[  4840.935] sna_wait_vblank
[  4840.935] sna_dri_get_msc: msc=288156, ust=4840930268
[  4840.936] sna_dri_schedule_swap: pixmap=3, back=32 (refs=1/1, flush=1) , fron=28 (refs=2/3, flush=1)
[  4840.936] sna_dri_schedule_swap(target_msc=288143, divisor=0, remainder=0)
[  4840.936] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4840.936] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4840.936] sna_covering_crtc: box exactly matches crtc [0]
[  4840.936] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4840.936] can_flip: window size: 1440x900, clip=(0, 0), (1440, 900) x 1
[  4840.936] sna_wait_vblank
[  4840.936] sna_dri_schedule_flip: target_msc=288143, current_msc=288156, divisor=0
[  4840.936] sna_dri_schedule_flip: performing immediate swap on pipe 0, pending? 0, mode: 0
[  4840.936] sna_dri_add_frame_event: add[0x2091db0] to window 14680091)
[  4840.936] sna_dri_window_set_chain: head now 0x2091db0
[  4840.936] sna_dri_page_flip()
[  4840.936] sna_page_flip: handle 32 attached
[  4840.936] do_page_flip: crtc 0 active? 1
[  4840.936] get_fb: reusing fb=29 for handle=32
[  4840.936] do_page_flip: crtc 3 [ref? 1] --> fb 29
[  4840.936] do_page_flip: crtc 1 active? 0
[  4840.936] do_page_flip: crtc 2 active? 0
[  4840.936] sna_page_flip: page flipped 1 crtcs
[  4840.936] sna_dri_page_flip: mark handle=32 as scanout, swap front (handle=28, name=3) and back (handle=32, name=4)
[  4840.936] set_bo: pixmap=3, handle=32
[  4840.936] sna_dri_schedule_flip: target_msc=288157
[  4840.936] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.936] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.936] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.936] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.936] sna_block_handler (tv=584.277000)
[  4840.936] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.936] sna_accel_do_flush -- no pending write to scanout
[  4840.936] sna_accel_do_throttle -- no pending activity
[  4840.946] sna_wakeup_handler
[  4840.946] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4840.946] sna_mode_wakeup: len=32
[  4840.946] sna_dri_page_flip_handler: pending flip_count=1
[  4840.946] sna_dri_flip_event(frame=288157, tv=4840.946936, type=4)
[  4840.946] sna_dri_flip_event: retiring previous scanout handle=28, name=3, refcnt=2
[  4840.946] sna_dri_flip_event: flip complete (drawable gone? 0), msc=288157
[  4840.946] sna_accel_flush_callback: flush?=0, dirty?=0
[  4840.946] sna_dri_remove_frame_event: remove[0x2091db0] from window 14680091)
[  4840.946] sna_dri_window_set_chain: head now (nil)
[  4840.946] _sna_dri_destroy_buffer: 0x1db61c0 [handle=32] -- refcnt=3, pixmap=3
[  4840.946] _sna_dri_destroy_buffer: 0x202be10 [handle=28] -- refcnt=2, pixmap=0
[  4840.946] sna_block_handler (tv=584.267000)
[  4840.946] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4840.946] sna_accel_do_flush -- no pending write to scanout
[  4840.946] sna_accel_do_throttle -- no pending activity
[  4841.214] sna_wakeup_handler
[  4841.214] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.214] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.214] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.214] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.214] sna_block_handler (tv=583.999000)
[  4841.214] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.214] sna_accel_do_flush -- no pending write to scanout
[  4841.214] sna_accel_do_throttle -- no pending activity
[  4841.215] sna_wakeup_handler
[  4841.215] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.215] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.215] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.215] sna_block_handler (tv=583.998000)
[  4841.215] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.216] sna_accel_do_flush -- no pending write to scanout
[  4841.216] sna_accel_do_throttle -- no pending activity
[  4841.216] sna_wakeup_handler
[  4841.216] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_block_handler (tv=583.997000)
[  4841.216] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.216] sna_accel_do_flush -- no pending write to scanout
[  4841.216] sna_accel_do_throttle -- no pending activity
[  4841.216] sna_wakeup_handler
[  4841.216] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_block_handler (tv=583.997000)
[  4841.216] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.216] sna_accel_do_flush -- no pending write to scanout
[  4841.216] sna_accel_do_throttle -- no pending activity
[  4841.216] sna_wakeup_handler
[  4841.216] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.216] sna_block_handler (tv=583.997000)
[  4841.216] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.216] sna_accel_do_flush -- no pending write to scanout
[  4841.216] sna_accel_do_throttle -- no pending activity
[  4841.231] sna_wakeup_handler
[  4841.232] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.232] sna_dri_create_buffer pixmap=3, (attachment=0, format=32, drawable=1440x900)
[  4841.232] sna_dri_create_buffer: reusing front buffer attachment, pixmap=3, handle=32, name=4
[  4841.232] kgem_bo_flink: flinked handle=32 to name=4, marking non-reusable
[  4841.232] _sna_dri_destroy_buffer: 0x1db61c0 [handle=32] -- refcnt=3, pixmap=3
[  4841.232] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.232] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.232] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.232] sna_block_handler (tv=583.981000)
[  4841.232] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.232] sna_accel_do_flush -- no pending write to scanout
[  4841.232] __kgem_busy: handle=32, busy=0, wedged=0
[  4841.232] __kgem_flush: handle=32, busy?=0
[  4841.232] sna_accel_do_throttle -- no pending activity
[  4841.240] sna_wakeup_handler
[  4841.240] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.240] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.241] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.241] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.241] sna_block_handler (tv=583.972000)
[  4841.241] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.241] sna_accel_do_flush -- no pending write to scanout
[  4841.241] sna_accel_do_throttle -- no pending activity
[  4841.248] sna_wakeup_handler
[  4841.248] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.248] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.248] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.248] sna_block_handler (tv=583.965000)
[  4841.248] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.249] sna_accel_do_flush -- no pending write to scanout
[  4841.249] sna_accel_do_throttle -- no pending activity
[  4841.249] sna_wakeup_handler
[  4841.249] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_block_handler (tv=583.964000)
[  4841.249] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.249] sna_accel_do_flush -- no pending write to scanout
[  4841.249] sna_accel_do_throttle -- no pending activity
[  4841.249] sna_wakeup_handler
[  4841.249] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_block_handler (tv=583.964000)
[  4841.249] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.249] sna_accel_do_flush -- no pending write to scanout
[  4841.249] sna_accel_do_throttle -- no pending activity
[  4841.249] sna_wakeup_handler
[  4841.249] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.249] sna_block_handler (tv=583.964000)
[  4841.249] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.249] sna_accel_do_flush -- no pending write to scanout
[  4841.249] sna_accel_do_throttle -- no pending activity
[  4841.551] sna_wakeup_handler
[  4841.551] sna_block_handler (tv=0.180000)
[  4841.551] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.551] sna_accel_do_flush -- no pending write to scanout
[  4841.551] sna_accel_do_throttle -- no pending activity
[  4841.560] sna_wakeup_handler
[  4841.560] sna_block_handler (tv=0.014000)
[  4841.560] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.560] sna_accel_do_flush -- no pending write to scanout
[  4841.560] sna_accel_do_throttle -- no pending activity
[  4841.570] sna_wakeup_handler
[  4841.570] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.570] sna_block_handler (tv=0.014000)
[  4841.570] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.570] sna_accel_do_flush -- no pending write to scanout
[  4841.570] sna_accel_do_throttle -- no pending activity
[  4841.570] sna_wakeup_handler
[  4841.570] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.570] sna_block_handler (tv=0.014000)
[  4841.570] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.570] sna_accel_do_flush -- no pending write to scanout
[  4841.570] sna_accel_do_throttle -- no pending activity
[  4841.580] sna_wakeup_handler
[  4841.580] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.580] sna_block_handler (tv=0.014000)
[  4841.580] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.580] sna_accel_do_flush -- no pending write to scanout
[  4841.580] sna_accel_do_throttle -- no pending activity
[  4841.580] sna_wakeup_handler
[  4841.580] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.580] sna_block_handler (tv=0.014000)
[  4841.580] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.580] sna_accel_do_flush -- no pending write to scanout
[  4841.580] sna_accel_do_throttle -- no pending activity
[  4841.589] sna_wakeup_handler
[  4841.589] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.589] sna_block_handler (tv=0.014000)
[  4841.590] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.590] sna_accel_do_flush -- no pending write to scanout
[  4841.590] sna_accel_do_throttle -- no pending activity
[  4841.590] sna_wakeup_handler
[  4841.590] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.590] sna_block_handler (tv=0.013000)
[  4841.590] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.590] sna_accel_do_flush -- no pending write to scanout
[  4841.590] sna_accel_do_throttle -- no pending activity
[  4841.599] sna_wakeup_handler
[  4841.599] sna_block_handler (tv=0.014000)
[  4841.599] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.599] sna_accel_do_flush -- no pending write to scanout
[  4841.599] sna_accel_do_throttle -- no pending activity
[  4841.609] sna_wakeup_handler
[  4841.609] sna_block_handler (tv=0.014000)
[  4841.609] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.609] sna_accel_do_flush -- no pending write to scanout
[  4841.609] sna_accel_do_throttle -- no pending activity
[  4841.619] sna_wakeup_handler
[  4841.619] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.619] sna_block_handler (tv=0.014000)
[  4841.619] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.619] sna_accel_do_flush -- no pending write to scanout
[  4841.619] sna_accel_do_throttle -- no pending activity
[  4841.619] sna_wakeup_handler
[  4841.619] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.619] sna_block_handler (tv=0.014000)
[  4841.619] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.619] sna_accel_do_flush -- no pending write to scanout
[  4841.619] sna_accel_do_throttle -- no pending activity
[  4841.630] sna_wakeup_handler
[  4841.630] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.630] sna_block_handler (tv=0.014000)
[  4841.630] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.630] sna_accel_do_flush -- no pending write to scanout
[  4841.630] sna_accel_do_throttle -- no pending activity
[  4841.630] sna_wakeup_handler
[  4841.630] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.630] sna_block_handler (tv=0.014000)
[  4841.630] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.630] sna_accel_do_flush -- no pending write to scanout
[  4841.630] sna_accel_do_throttle -- no pending activity
[  4841.638] sna_wakeup_handler
[  4841.638] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.638] sna_block_handler (tv=0.014000)
[  4841.638] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.638] sna_accel_do_flush -- no pending write to scanout
[  4841.638] sna_accel_do_throttle -- no pending activity
[  4841.638] sna_wakeup_handler
[  4841.638] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.638] sna_block_handler (tv=0.014000)
[  4841.638] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.638] sna_accel_do_flush -- no pending write to scanout
[  4841.638] sna_accel_do_throttle -- no pending activity
[  4841.649] sna_wakeup_handler
[  4841.649] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.649] sna_block_handler (tv=0.013000)
[  4841.649] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.649] sna_accel_do_flush -- no pending write to scanout
[  4841.649] sna_accel_do_throttle -- no pending activity
[  4841.649] sna_wakeup_handler
[  4841.649] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.649] sna_block_handler (tv=0.013000)
[  4841.649] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.649] sna_accel_do_flush -- no pending write to scanout
[  4841.649] sna_accel_do_throttle -- no pending activity
[  4841.658] sna_wakeup_handler
[  4841.658] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.658] sna_block_handler (tv=0.014000)
[  4841.658] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.658] sna_accel_do_flush -- no pending write to scanout
[  4841.658] sna_accel_do_throttle -- no pending activity
[  4841.658] sna_wakeup_handler
[  4841.658] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.658] sna_block_handler (tv=0.014000)
[  4841.658] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.658] sna_accel_do_flush -- no pending write to scanout
[  4841.658] sna_accel_do_throttle -- no pending activity
[  4841.668] sna_wakeup_handler
[  4841.668] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.668] sna_block_handler (tv=0.014000)
[  4841.668] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.668] sna_accel_do_flush -- no pending write to scanout
[  4841.668] sna_accel_do_throttle -- no pending activity
[  4841.668] sna_wakeup_handler
[  4841.668] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.668] sna_block_handler (tv=0.014000)
[  4841.668] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.668] sna_accel_do_flush -- no pending write to scanout
[  4841.668] sna_accel_do_throttle -- no pending activity
[  4841.678] sna_wakeup_handler
[  4841.678] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.678] sna_block_handler (tv=0.014000)
[  4841.678] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.678] sna_accel_do_flush -- no pending write to scanout
[  4841.678] sna_accel_do_throttle -- no pending activity
[  4841.678] sna_wakeup_handler
[  4841.678] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.678] sna_block_handler (tv=0.014000)
[  4841.678] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.678] sna_accel_do_flush -- no pending write to scanout
[  4841.678] sna_accel_do_throttle -- no pending activity
[  4841.687] sna_wakeup_handler
[  4841.688] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.688] sna_block_handler (tv=0.013000)
[  4841.688] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.688] sna_accel_do_flush -- no pending write to scanout
[  4841.688] sna_accel_do_throttle -- no pending activity
[  4841.688] sna_wakeup_handler
[  4841.688] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.688] sna_block_handler (tv=0.013000)
[  4841.688] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.688] sna_accel_do_flush -- no pending write to scanout
[  4841.688] sna_accel_do_throttle -- no pending activity
[  4841.697] sna_wakeup_handler
[  4841.697] sna_block_handler (tv=583.516000)
[  4841.697] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.697] sna_accel_do_flush -- no pending write to scanout
[  4841.697] sna_accel_do_throttle -- no pending activity
[  4841.707] sna_wakeup_handler
[  4841.707] sna_block_handler (tv=583.506000)
[  4841.707] start_flush: scanout=32 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.707] sna_accel_do_flush -- no pending write to scanout
[  4841.707] sna_accel_do_throttle -- no pending activity
[  4841.990] sna_wakeup_handler
[  4841.990] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4841.990] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4841.990] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4841.990] sna_covering_crtc: box exactly matches crtc [0]
[  4841.990] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4841.990] sna_dri_get_msc(pipe=0)
[  4841.990] sna_wait_vblank
[  4841.990] sna_dri_get_msc: msc=288219, ust=4841980297
[  4841.990] sna_dri_schedule_swap: pixmap=3, back=28 (refs=1/1, flush=1) , fron=32 (refs=2/3, flush=1)
[  4841.990] sna_dri_schedule_swap(target_msc=288158, divisor=0, remainder=0)
[  4841.990] sna_covering_crtc for box=(0, 0), (1440, 900)
[  4841.990] sna_covering_crtc: crtc 0: (0, 0), (1440, 900)
[  4841.990] sna_covering_crtc: box exactly matches crtc [0]
[  4841.990] sna_dri_get_pipe(box=((0, 0), (1440, 900)), pipe=0)
[  4841.990] can_flip: window size: 1440x900, clip=(0, 0), (1440, 900) x 1
[  4841.990] sna_wait_vblank
[  4841.990] sna_dri_schedule_flip: target_msc=288158, current_msc=288219, divisor=0
[  4841.990] sna_dri_schedule_flip: performing immediate swap on pipe 0, pending? 0, mode: 0
[  4841.990] sna_dri_add_frame_event: add[0x20a4230] to window 14680091)
[  4841.990] sna_dri_window_set_chain: head now 0x20a4230
[  4841.990] sna_dri_page_flip()
[  4841.990] sna_page_flip: handle 28 attached
[  4841.990] do_page_flip: crtc 0 active? 1
[  4841.990] get_fb: reusing fb=28 for handle=28
[  4841.990] do_page_flip: crtc 3 [ref? 1] --> fb 28
[  4841.990] do_page_flip: crtc 1 active? 0
[  4841.990] do_page_flip: crtc 2 active? 0
[  4841.990] sna_page_flip: page flipped 1 crtcs
[  4841.990] sna_dri_page_flip: mark handle=28 as scanout, swap front (handle=32, name=4) and back (handle=28, name=3)
[  4841.990] set_bo: pixmap=3, handle=28
[  4841.990] sna_dri_schedule_flip: target_msc=288220
[  4841.990] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.990] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.990] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.990] sna_accel_flush_callback: flush?=0, dirty?=0
[  4841.990] sna_block_handler (tv=583.223000)
[  4841.990] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4841.990] sna_accel_do_flush -- no pending write to scanout
[  4841.990] sna_accel_do_throttle -- no pending activity
[  4842.013] sna_wakeup_handler
[  4842.013] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.013] sna_mode_wakeup: len=32
[  4842.013] sna_dri_page_flip_handler: pending flip_count=1
[  4842.013] sna_dri_flip_event(frame=288221, tv=4842.013620, type=4)
[  4842.013] sna_dri_flip_event: retiring previous scanout handle=32, name=4, refcnt=2
[  4842.013] sna_dri_flip_event: flip complete (drawable gone? 0), msc=288221
[  4842.013] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.013] sna_dri_remove_frame_event: remove[0x20a4230] from window 14680091)
[  4842.013] sna_dri_window_set_chain: head now (nil)
[  4842.013] _sna_dri_destroy_buffer: 0x1db61c0 [handle=28] -- refcnt=3, pixmap=3
[  4842.013] _sna_dri_destroy_buffer: 0x202be10 [handle=32] -- refcnt=2, pixmap=0
[  4842.013] sna_block_handler (tv=583.200000)
[  4842.013] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.013] sna_accel_do_flush -- no pending write to scanout
[  4842.013] sna_accel_do_throttle -- no pending activity
[  4842.016] sna_wakeup_handler
[  4842.016] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.016] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.016] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.016] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.016] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.016] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.017] sna_block_handler (tv=583.196000)
[  4842.017] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.017] sna_accel_do_flush -- no pending write to scanout
[  4842.017] sna_accel_do_throttle -- no pending activity
[  4842.017] sna_wakeup_handler
[  4842.018] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_block_handler (tv=583.195000)
[  4842.018] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.018] sna_accel_do_flush -- no pending write to scanout
[  4842.018] sna_accel_do_throttle -- no pending activity
[  4842.018] sna_wakeup_handler
[  4842.018] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_block_handler (tv=583.195000)
[  4842.018] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.018] sna_accel_do_flush -- no pending write to scanout
[  4842.018] sna_accel_do_throttle -- no pending activity
[  4842.018] sna_wakeup_handler
[  4842.018] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_block_handler (tv=583.195000)
[  4842.018] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.018] sna_accel_do_flush -- no pending write to scanout
[  4842.018] sna_accel_do_throttle -- no pending activity
[  4842.018] sna_wakeup_handler
[  4842.018] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.018] sna_block_handler (tv=583.195000)
[  4842.018] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.018] sna_accel_do_flush -- no pending write to scanout
[  4842.019] sna_accel_do_throttle -- no pending activity
[  4842.033] sna_wakeup_handler
[  4842.033] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.033] sna_dri_create_buffer pixmap=3, (attachment=0, format=32, drawable=1440x900)
[  4842.033] sna_dri_create_buffer: reusing front buffer attachment, pixmap=3, handle=28, name=3
[  4842.033] kgem_bo_flink: flinked handle=28 to name=3, marking non-reusable
[  4842.033] _sna_dri_destroy_buffer: 0x1db61c0 [handle=28] -- refcnt=3, pixmap=3
[  4842.033] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.033] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.033] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.033] sna_block_handler (tv=583.180000)
[  4842.033] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.033] sna_accel_do_flush -- no pending write to scanout
[  4842.033] __kgem_busy: handle=28, busy=0, wedged=0
[  4842.033] __kgem_flush: handle=28, busy?=0
[  4842.033] sna_accel_do_throttle -- no pending activity
[  4842.034] sna_wakeup_handler
[  4842.034] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_block_handler (tv=583.179000)
[  4842.034] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.034] sna_accel_do_flush -- no pending write to scanout
[  4842.034] sna_accel_do_throttle -- no pending activity
[  4842.034] sna_wakeup_handler
[  4842.034] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_block_handler (tv=583.179000)
[  4842.034] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.034] sna_accel_do_flush -- no pending write to scanout
[  4842.034] sna_accel_do_throttle -- no pending activity
[  4842.034] sna_wakeup_handler
[  4842.034] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.034] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.035] sna_block_handler (tv=583.178000)
[  4842.035] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.035] sna_accel_do_flush -- no pending write to scanout
[  4842.035] sna_accel_do_throttle -- no pending activity
[  4842.035] sna_wakeup_handler
[  4842.035] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.035] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.035] sna_accel_flush_callback: flush?=0, dirty?=0
[  4842.035] sna_block_handler (tv=583.178000)
[  4842.035] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
[  4842.035] sna_accel_do_flush -- no pending write to scanout
[  4842.035] sna_accel_do_throttle -- no pending activity
[  4842.103] sna_wakeup_handler
[  4842.103] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
[  4842.103] sna_destroy_pixmap: pixmap=1570, attached?=1
[  4842.103] __sna_pixmap_free_cpu: discarding CPU buffer, handle=41, size=262144
[  4842.103] _kgem_bo_destroy: handle=41, proxy? 0
[  4842.103] __kgem_bo_destroy: handle=41
[  4842.103] __kgem_bo_destroy: handle=41 is snooped
[  4842.103] kgem_bo_move_to_snoop: moving 41 to snoop cachee
[  4842.103] sna_destroy_pixmap: pixmap=1246, attached?=1
[  4842.103] sna_destroy_pixmap: pixmap=6297, attached?=1
[  4842.103] sna_destroy_pixmap: pixmap=6295, attached?=1
[  4842.103] __sna_pixmap_free_cpu: discarding CPU buffer, handle=47, size=2637824
[  4842.103] _kgem_bo_destroy: handle=47, proxy? 0
[  4842.103] __kgem_bo_destroy: handle=47
[  4842.103] __kgem_bo_destroy: handle=47 is snooped
[  4842.103] kgem_bo_move_to_snoop: moving 47 to snoop cachee
[  4842.103] sna_destroy_pixmap: pixmap=6291, attached?=1
[  4842.103] _kgem_bo_destroy: handle=60, proxy? 0
[  4842.103] __kgem_bo_destroy: handle=60
[  4842.103] kgem_bo_move_to_inactive: moving handle=60 to inactive
[  4842.103] sna_destroy_pixmap: pixmap=1184, attached?=1
[  4842.103] sna_destroy_pixmap: pixmap=1164, attached?=1
[  4842.103] sna_destroy_pixmap: pixmap=1138, attached?=1
Xorg.bak: sna_accel.c:348: assert_pixmap_damage: Assertion `priv->mapped != 0 || p->devPrivate.ptr == ((void*)((uintptr_t)(priv->ptr) & ~1))' failed.