Attachment 134724 Details for Bug 103025 – Xorg log tail from second crash

Xorg log tail from second crash

Oct_6_22-12_xorg_old_log_at_crash.txt (text/plain), 75.66 KB, created by Adric Blake on 2017-10-07 10:45:04 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Adric Blake

Created: 2017-10-07 10:45:04 UTC

Size: 75.66 KB

patch

obsolete

>[ 18336.578] _kgem_bo_destroy: handle=91, proxy? 1
>[ 18336.578] __sna_free_pixmap(pixmap=1886742)
>[ 18336.578] sna_glyphs(op=3, nlist=1, src=(25, 142))
>[ 18336.578] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.578] sna_glyphs: discarding mask
>[ 18336.578] glyphs0_to_dst(op=3, src=(25, 142), nlist=1,  dst=(25, 142)+(0, 0))
>[ 18336.578] clipped_glyphs: no, clip region (0, 0), (166, 200) matches drawable pixmap=94857476080096 size=166x200
>[ 18336.578] gen4_render_composite: 0x0, current mode=1
>[ 18336.578] kgem_bo_is_busy: handle=5, domain: 0 exec? 0, rq? 0
>[ 18336.578] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.578] gen4_composite_fallback: dst is already on the GPU, try to use GPU
>[ 18336.578] sna_drawable_use_bo pixmap=1886728, box=((0, 0), (166, 200)), flags=7...
>[ 18336.578] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
>[ 18336.578] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.578] sna_drawable_use_bo: using whole GPU bo
>[ 18336.578] gen4_composite_set_target: pixmap=1886728, format=20020888, size=166x200, pitch=1024, delta=(0,0),damage=0xffffffffffffffff
>[ 18336.578] gen4_composite_picture: (0, 0)x(0, 0), dst=(0, 0)
>[ 18336.578] color_convert: src=ff2c2c2c [20028888]
>[ 18336.578] color_convert: dst=ff2c2c2c [20028888]
>[ 18336.578] sna_render_get_solid: ff2c2c2c
>[ 18336.578] sna_render_get_solid(7) = ff2c2c2c (last)
>[ 18336.578] gen4_composite_channel_convert: repeat 1 -> 1, filter 0 -> 0
>[ 18336.578] gen4_composite_picture: (0, 0)x(0, 0), dst=(0, 0)
>[ 18336.578] sna_transform_is_imprecise_integer_translation: no transform
>[ 18336.578] gen4_composite_picture: integer translation (0, 0), removing
>[ 18336.578] sna_render_pixmap_bo pixmap=1, (0, 0)x(0, 0)/(1024, 1024)
>[ 18336.578] sna_render_pixmap_bo: GPU all damaged
>[ 18336.578] gen4_composite_channel_convert: repeat 0 -> 0, filter 0 -> 0
>[ 18336.578] gen4_choose_composite_emitter: solid, identity mask
>[ 18336.578] kgem_check_bo: num_pages=+257, num_exec=+2
>[ 18336.578] aligning vertex: was 3, now 4 floats per vertex
>[ 18336.578] gen4_composite_get_binding_table(3ff60)
>[ 18336.578] kgem_add_reloc: handle=60, pos=65489, delta=0, domains=40000
>[ 18336.578] kgem_add_reloc: adding proxy [delta=28] for handle=60
>[ 18336.578] kgem_add_handle: handle=60, index=3
>[ 18336.578] [ffd0] bind bo(handle=60, addr=2052124), format=192, width=1, height=1, pitch=4, tiling=0 -> sampler
>[ 18336.578] kgem_add_reloc: handle=5, pos=65481, delta=0, domains=40000
>[ 18336.578] kgem_add_handle: handle=5, index=4
>[ 18336.578] [ffc8] bind bo(handle=5, addr=272199680), format=324, width=1024, height=1024, pitch=1024, tiling=1 -> sampler
>[ 18336.578] gen4_emit_state: flushing dirty (0, 0), forced? 1
>[ 18336.579] __kgem_bo_mark_dirty: handle=43 (proxy? 0)
>[ 18336.579] gen4_emit_pipelined_pointers: has_mask=0, src=(0, 1), mask=(0, 0),kernel=2, blend=3, ca=0, format=20020888
>[ 18336.579] blend op=3, dst=20020888 [A=0] => src=1, dst=19 => offset=a00
>[ 18336.579] gen4_emit_pipelined_pointers: sp=6272, bp=2560
>[ 18336.579] gen4_emit_vertex_elements: first channel 1 floats, offset=4b
>[ 18336.579] gen4_emit_vertex_elements: second channel 2 floats, offset=8b
>[ 18336.579] glyphs0_to_dst: glyph=(25, 131)x(10, 11), unclipped
>[ 18336.579] gen4_render_composite_blt: src=(25, 131)+(0, 0), mask=(112, 48)+(0, 0), dst=(25, 131)+(0, 0), size=(10, 11)
>[ 18336.579] emit_primitive_identity_mask: dst=(25, 131), mask=(112.00, 48.00) x (10.00, 11.00)
>[ 18336.579] glyphs0_to_dst: glyph=(36, 131)x(1, 11), unclipped
>[ 18336.579] gen4_render_composite_blt: src=(36, 131)+(0, 0), mask=(144, 0)+(0, 0), dst=(36, 131)+(0, 0), size=(1, 11)
>[ 18336.579] gen4_vertex_flush[12c] = 3
>[ 18336.579] emit_primitive_identity_mask: dst=(36, 131), mask=(144.00, 0.00) x (1.00, 11.00)
>[ 18336.579] glyphs0_to_dst: glyph=(39, 131)x(1, 11), unclipped
>[ 18336.579] gen4_render_composite_blt: src=(39, 131)+(0, 0), mask=(144, 0)+(0, 0), dst=(39, 131)+(0, 0), size=(1, 11)
>[ 18336.579] gen4_vertex_flush[148] = 3
>[ 18336.579] emit_primitive_identity_mask: dst=(39, 131), mask=(144.00, 0.00) x (1.00, 11.00)
>[ 18336.579] glyphs0_to_dst: glyph=(48, 131)x(3, 13), unclipped
>[ 18336.579] gen4_render_composite_blt: src=(48, 131)+(0, 0), mask=(272, 16)+(0, 0), dst=(48, 131)+(0, 0), size=(3, 13)
>[ 18336.579] gen4_vertex_flush[164] = 3
>[ 18336.579] emit_primitive_identity_mask: dst=(48, 131), mask=(272.00, 16.00) x (3.00, 13.00)
>[ 18336.579] glyphs0_to_dst: glyph=(54, 131)x(6, 11), unclipped
>[ 18336.579] gen4_render_composite_blt: src=(54, 131)+(0, 0), mask=(192, 48)+(0, 0), dst=(54, 131)+(0, 0), size=(6, 11)
>[ 18336.579] gen4_vertex_flush[180] = 3
>[ 18336.579] emit_primitive_identity_mask: dst=(54, 131), mask=(192.00, 48.00) x (6.00, 11.00)
>[ 18336.579] glyphs0_to_dst: glyph=(63, 131)x(3, 13), unclipped
>[ 18336.579] gen4_render_composite_blt: src=(63, 131)+(0, 0), mask=(304, 16)+(0, 0), dst=(63, 131)+(0, 0), size=(3, 13)
>[ 18336.579] gen4_vertex_flush[19c] = 3
>[ 18336.579] emit_primitive_identity_mask: dst=(63, 131), mask=(304.00, 16.00) x (3.00, 13.00)
>[ 18336.579] gen4_render_composite_done()
>[ 18336.579] gen4_vertex_flush[1b8] = 3
>[ 18336.579] sna_validate_gc(0x5645ba1b0dd0) changes=7fffff, previous serial=80000000, drawable=1cca08
>[ 18336.579] sna_validate_gc: recomputing clip
>[ 18336.579] sna_validate_gc: composite clip=1x[(0, 0), (166, 200)] [0x5645ba7ada10]
>[ 18336.579] sna_poly_fill_rect(n=1, PlaneMask: ffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[ 18336.579] sna_poly_fill_rect_extents: [0] = (0, 150)x(166, 25)
>[ 18336.579] sna_poly_fill_rect: extents(0, 150), (166, 175), flags=1
>[ 18336.579] sna_poly_fill_rect: dropping last-cpu hint
>[ 18336.579] sna_drawable_use_bo pixmap=1886728, box=((0, 150), (166, 175)), flags=9...
>[ 18336.579] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=9
>[ 18336.579] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.579] sna_drawable_use_bo: using whole GPU bo
>[ 18336.579] sna_poly_fill_rect: solid fill [f7f7f7], testing for blt
>[ 18336.579] sna_poly_fill_rect_blt pixmap=1886728 x 1 [(0, 150)x(166, 25)...]+(0,0), clipped?=0
>[ 18336.579] sna_poly_fill_rect_blt: using fill_one() fast path: (0, 150), (166, 175). alu=3, pixel=f7f7f7, damage?=0
>[ 18336.579] gen4_render_fill_one: color=f7f7f7
>[ 18336.579] sna_blt_fill_boxes (32, f7f7f7, 3) x 1
>[ 18336.579] sna_blt_fill_box: box=((0, 150), (166, 175))
>[ 18336.579] __kgem_busy: handle=1, busy=0, wedged=0
>[ 18336.579] __kgem_ring_is_idle: ring=0 idle (handle=1)
>[ 18336.579] __kgem_retire_rq: request 1 complete
>[ 18336.579] no_render_context_switch: GPU idle, flushing
>[ 18336.579] gen4_vertex_close: used=12372, vbo active? 20, vb=204, nreloc=2
>[ 18336.579] gen4_vertex_close: reloc[0] = 39
>[ 18336.579] kgem_add_reloc: handle=20, pos=39, delta=0, domains=200000
>[ 18336.579] kgem_add_handle: handle=20, index=5
>[ 18336.579] gen4_vertex_close: reloc[1] = 71
>[ 18336.579] kgem_add_reloc: handle=20, pos=71, delta=0, domains=200000
>[ 18336.579] batch[1/0, flags=1800]: 116 116 65480 65528, nreloc=8, nexec=6, nfence=0, aperture=628 [fenced=0]
>[ 18336.579] kgem_finish_buffers: buffer handle=91, used=586752, exec?=1, write=1, mmapped=cpu, refcnt=1
>[ 18336.579] kgem_finish_buffers: retaining upload buffer (586752/1048576): used=589824, refcnt=1
>[ 18336.579] kgem_fixup_relocs: fixing up 1 self-relocations to handle=0x1, presumed-offset=180000
>[ 18336.579] kgem_fixup_relocs: moving base of self-reloc[0:1] 1 -> -258047
>[ 18336.579] kgem_fixup_relocs: shrinking by 258048
>[ 18336.579] __kgem_busy: handle=1, busy=0, wedged=0
>[ 18336.579] kgem_commit: release handle=1 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=180000
>[ 18336.579] kgem_commit: release handle=43 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=88dc000
>[ 18336.579] kgem_commit: release handle=4 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=29c0000
>[ 18336.579] kgem_commit: release handle=91 (proxy? 0), dirty? 0 flush? 0, snoop? 1 -> offset=145c6000
>[ 18336.579] kgem_commit: release handle=60 (proxy? 1), dirty? 0 flush? 0, snoop? 0 -> offset=0
>[ 18336.579] __kgem_bo_clear_busy: handle=60
>[ 18336.579] __kgem_bo_clear_dirty: handle=60
>[ 18336.579] kgem_commit: release handle=60 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=1f5000
>[ 18336.579] kgem_commit: release handle=5 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=10397000
>[ 18336.579] kgem_commit: release handle=20 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b384000
>[ 18336.580] __kgem_busy: handle=1, busy=131072, wedged=0
>[ 18336.580] kgem_bo_can_map: handle=20, map=0x7fb9c6889000:0x7fb9c6464000:0x0, tiling=0, domain=3, offset=188235776
>[ 18336.580] kgem_add_reloc: handle=43, pos=4, delta=0, domains=28002
>[ 18336.580] kgem_add_handle: handle=43, index=0
>[ 18336.580] __kgem_bo_mark_dirty: handle=43 (proxy? 0)
>[ 18336.580] sna_block_handler (timeout=0)
>[ 18336.580] sna_mode_wakeup: len=32
>[ 18336.580] sna_present_vblank_handler: clearing immediate flag
>[ 18336.580] sna_crtc_record_swap: recording last swap on pipe=0, frame 1100119 [msc=1100119], time 18336.580826
>[ 18336.580] vblank_complete: 1 events complete
>[ 18336.580] vblank_complete: pipe=0 tv=18336.580826 msc=1100119 (target=1100119), event=589632 complete
>[ 18336.580] sna_validate_gc(0x5645b9a61c70) changes=7fffff, previous serial=0, drawable=1bdfd2
>[ 18336.580] sna_validate_gc: recomputing clip
>[ 18336.580] sna_validate_gc: composite clip=0x[(3, 50), (3, 50)] [0x5645ba2f3980]
>[ 18336.580] sna_copy_area: src=pixmap=738075:(0, 0)x(300, 300)+(0, 0) -> dst=pixmap=3:(0, 0)+(3, 50); alu=3, pm=ffffffff, depth=24
>[ 18336.580] sna_copy_area: normal copy
>[ 18336.580] sna_do_copy: src=(0, 0), dst=(0, 0), size=(300x300)
>[ 18336.580] sna_do_copy: unmapped/unrealized dst (pixmap=94857469526640)
>[ 18336.580] sna_sync_fence_set_triggered()
>[ 18336.580] sna_accel_flush: flush?=0, dirty?=0
>[ 18336.580] sna_create_pixmap(16, 16, 32, usage=0)
>[ 18336.580] kgem_can_create_2d: 16x16 @ 32
>[ 18336.580] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=64, height=16
>[ 18336.580] kgem_can_create_2d: untiled size=4096
>[ 18336.580] kgem_choose_tiling: too thin [width 16, 32 bpp] for TILING_X
>[ 18336.580] kgem_choose_tiling: 16x16 -> 0
>[ 18336.580] sna_create_pixmap: usage=0, flags=a
>[ 18336.580] sna_create_pixmap: small buffer [1024], attaching to shadow pixmap
>[ 18336.580] create_pixmap: allocating pixmap 16x16, depth=32/32, size=1032
>[ 18336.580] create_pixmap: serial=1886743, usage=0, 16x16
>[ 18336.580] sna_create_pixmap: serial=1886743, 16x16, usage=0
>[ 18336.580] sna_validate_gc(0x5645ba2b6c50) changes=0, previous serial=1cca16, drawable=1cca18
>[ 18336.580] sna_validate_gc: recomputing clip
>[ 18336.580] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x5645ba233620]
>[ 18336.581] sna_put_image((0, 0)x(16, 16), depth=32, format=2)
>[ 18336.581] try_upload__fast: ignore existing cpu damage (if any)
>[ 18336.581] try_upload__inplace: bo? 0, can map? 0, replaces? 1
>[ 18336.581] try_upload__fast: no, no gpu damage
>[ 18336.581] sna_drawable_move_region_to_cpu(pixmap=1886744 (16x16), [(0, 0), (16, 16)], flags=1)
>[ 18336.581] sna_drawable_move_region_to_cpu: region (0, 0), (16, 16) + (0, 0) subsumes pixmap (16x16)
>[ 18336.581] _sna_pixmap_move_to_cpu(pixmap=1886744, 16x16, flags=1)
>[ 18336.581] _sna_pixmap_move_to_cpu: gpu_bo=0, gpu_damage=0x0, cpu_damage=0x0, is-clear?=0
>[ 18336.581] _sna_pixmap_move_to_cpu: no readback, discarding gpu damage [0], pending clear[0]
>[ 18336.581] operate_inplace: no, inplace operation not suitable
>[ 18336.581] _sna_pixmap_move_to_cpu: marking as damaged
>[ 18336.581] __sna_damage_all(16, 16)
>[ 18336.581] sna_pixmap_free_gpu: handle=0 (pinned? 0)
>[ 18336.581] sna_put_zpixmap_blt: upload(0, 0, 16, 16) x 1 boxes
>[ 18336.581] sna_put_zpixmap_blt: copy box (0, 0)->(0, 0)x(16, 16)
>[ 18336.581] memcpy_blt: src=(0, 0), dst=(0, 0), size=16x16, pitch=64/64
>[ 18336.581] sna_composite(pixmap=1886728, op=3, src=1886744+(0, 0), mask=0+(5, 154), dst=1886728+(5, 154)+(0, 0), size=(16, 16)
>[ 18336.581] sna_compute_composite_region: dst=(5, 154)x(16, 16)
>[ 18336.581] sna_compute_composite_region: initial clip against dst->pDrawable: (5, 154), (21, 170)
>[ 18336.581] clip_to_dst: region: 1x[(5, 154), (21, 170)], clip: 1x[(0, 0), (166, 200)]
>[ 18336.581] sna_compute_composite_region: clip against dst->pCompositeClip: (5, 154), (21, 170)
>[ 18336.581] sna_compute_composite_region: clip against src (16x16 clip=0): (5, 154), (21, 170)
>[ 18336.581] sna_composite: composite region extents:+(0, 0) -> (5, 154), (21, 170) + (0, 0)
>[ 18336.581] gen4_render_composite: 16x16, current mode=3
>[ 18336.581] sna_blt_composite (0, 0), (5, 154), 16x16
>[ 18336.581] sna_blt_composite: unsupported op [3] for blitting
>[ 18336.581] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.581] gen4_composite_fallback: dst is already on the GPU, try to use GPU
>[ 18336.581] sna_drawable_use_bo pixmap=1886728, box=((5, 154), (21, 170)), flags=7...
>[ 18336.581] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
>[ 18336.581] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.581] sna_drawable_use_bo: using whole GPU bo
>[ 18336.581] gen4_composite_set_target: pixmap=1886728, format=20020888, size=166x200, pitch=1024, delta=(0,0),damage=0xffffffffffffffff
>[ 18336.581] gen4_composite_picture: (0, 0)x(16, 16), dst=(5, 154)
>[ 18336.581] sna_transform_is_imprecise_integer_translation: no transform
>[ 18336.581] gen4_composite_picture: integer translation (0, 0), removing
>[ 18336.581] sna_render_pixmap_bo pixmap=1886744, (0, 0)x(16, 16)/(16, 16)
>[ 18336.581] sna_render_pixmap_bo box=(0, 0), (16, 16): (16, 16)/(16, 16)
>[ 18336.581] sna_render_pixmap_bo: offset=(-5, -154), size=(16, 16)
>[ 18336.581] use_cpu_bo: no cpu bo
>[ 18336.581] move_to_gpu: migrating whole pixmap (16x16) for source (0,0),(16,16), count 5? 0
>[ 18336.581] sna_render_pixmap_bo: uploading CPU box (0, 0), (16, 16)
>[ 18336.581] upload: box=(0, 0), (16, 16), pixmap=16x16
>[ 18336.581] sna_drawable_move_region_to_cpu(pixmap=1886744 (16x16), [(0, 0), (16, 16)], flags=2)
>[ 18336.581] sna_drawable_move_region_to_cpu: pixmap=1886744 all damaged on CPU
>[ 18336.581] kgem_can_create_2d: 16x16 @ 32
>[ 18336.581] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=64, height=16
>[ 18336.581] kgem_can_create_2d: untiled size=4096
>[ 18336.581] kgem_choose_tiling: too thin [width 16, 32 bpp] for TILING_X
>[ 18336.581] kgem_choose_tiling: 16x16 -> 0
>[ 18336.581] kgem_upload_source_image : (0, 0), (16, 16), stride=64, bpp=32
>[ 18336.581] kgem_create_buffer_2d: 16x16, 32 bpp, stride=64
>[ 18336.581] kgem_create_buffer: size=1024, flags=3 [write?=1, inplace?=1, last?=0]
>[ 18336.581] kgem_create_buffer: reusing buffer? used=589824 + size=1024, total=1048576
>[ 18336.581] kgem_create_proxy: target handle=91 [proxy? -1], offset=589824, length=1024, io=1
>[ 18336.581] __kgem_bo_init(handle=91, num_pages=1024)
>[ 18336.581] memcpy_blt: src=(0, 0), dst=(0, 0), size=16x16, pitch=64/64
>[ 18336.581] upload: adding upload cache to pixmap=1886744
>[ 18336.581] kgem_proxy_bo_attach: handle=91
>[ 18336.581] sna_blt_composite__convert src=91, dst=43 (redirect? 0)
>[ 18336.581] sna_blt_composite__convert: unsupported op [3] for blitting
>[ 18336.581] gen4_composite_channel_convert: repeat 0 -> 0, filter 0 -> 0
>[ 18336.581] gen4_choose_composite_emitter: identity src, no mask
>[ 18336.581] kgem_check_bo: num_pages=+256, num_exec=+1
>[ 18336.581] aligning vertex: was 4, now 3 floats per vertex
>[ 18336.581] __kgem_busy: handle=1, busy=131072, wedged=0
>[ 18336.581] __kgem_ring_is_idle: last fence handle=1 still busy
>[ 18336.581] kgem_add_reloc: handle=4, pos=8, delta=1, domains=100000
>[ 18336.581] kgem_add_handle: handle=4, index=1
>[ 18336.581] kgem_add_reloc: handle=0, pos=9, delta=1, domains=100000
>[ 18336.581] gen4_composite_get_binding_table(3ffc0)
>[ 18336.581] kgem_add_reloc: handle=43, pos=65513, delta=0, domains=20002
>[ 18336.581] [ffe8] bind bo(handle=43, addr=143507456), format=192, width=166, height=200, pitch=1024, tiling=1 -> render
>[ 18336.581] kgem_add_reloc: handle=91, pos=65505, delta=0, domains=40000
>[ 18336.581] kgem_add_reloc: adding proxy [delta=589824] for handle=91
>[ 18336.581] kgem_add_handle: handle=91, index=2
>[ 18336.581] [ffe0] bind bo(handle=91, addr=342188032), format=192, width=16, height=16, pitch=64, tiling=0 -> sampler
>[ 18336.581] gen4_emit_state: flushing dirty (0, 0), forced? 1
>[ 18336.581] __kgem_bo_mark_dirty: handle=43 (proxy? 0)
>[ 18336.581] gen4_emit_pipelined_pointers: has_mask=2, src=(0, 0), mask=(0, 0),kernel=0, blend=3, ca=0, format=20020888
>[ 18336.581] blend op=3, dst=20020888 [A=0] => src=1, dst=19 => offset=a00
>[ 18336.582] gen4_emit_pipelined_pointers: sp=0, bp=2560
>[ 18336.582] gen4_emit_vertex_elements: first channel 2 floats, offset=4b
>[ 18336.582]   gen4_render_composite_box: (5, 154), (21, 170)
>[ 18336.582] apply_damage: damage=0x0, region=1 [(5, 154), (21, 170) + (0, 0)]
>[ 18336.582] gen4_render_composite_done()
>[ 18336.582] gen4_vertex_flush[c8] = 3
>[ 18336.582] sna_destroy_pixmap: pixmap=1886744, attached?=1
>[ 18336.582] _kgem_bo_destroy: handle=91, proxy? 1
>[ 18336.582] __sna_free_pixmap(pixmap=1886744)
>[ 18336.582] sna_glyphs(op=3, nlist=1, src=(25, 167))
>[ 18336.582] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.582] sna_glyphs: discarding mask
>[ 18336.582] glyphs0_to_dst(op=3, src=(25, 167), nlist=1,  dst=(25, 167)+(0, 0))
>[ 18336.582] clipped_glyphs: no, clip region (0, 0), (166, 200) matches drawable pixmap=94857476080096 size=166x200
>[ 18336.582] gen4_render_composite: 0x0, current mode=1
>[ 18336.582] kgem_bo_is_busy: handle=5, domain: 3 exec? 0, rq? 1
>[ 18336.582] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.582] gen4_composite_fallback: dst is already on the GPU, try to use GPU
>[ 18336.582] sna_drawable_use_bo pixmap=1886728, box=((0, 0), (166, 200)), flags=7...
>[ 18336.582] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
>[ 18336.582] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.582] sna_drawable_use_bo: using whole GPU bo
>[ 18336.582] gen4_composite_set_target: pixmap=1886728, format=20020888, size=166x200, pitch=1024, delta=(0,0),damage=0xffffffffffffffff
>[ 18336.582] gen4_composite_picture: (0, 0)x(0, 0), dst=(0, 0)
>[ 18336.582] color_convert: src=ff2c2c2c [20028888]
>[ 18336.582] color_convert: dst=ff2c2c2c [20028888]
>[ 18336.582] sna_render_get_solid: ff2c2c2c
>[ 18336.582] sna_render_get_solid(7) = ff2c2c2c (last)
>[ 18336.582] gen4_composite_channel_convert: repeat 1 -> 1, filter 0 -> 0
>[ 18336.582] gen4_composite_picture: (0, 0)x(0, 0), dst=(0, 0)
>[ 18336.582] sna_transform_is_imprecise_integer_translation: no transform
>[ 18336.582] gen4_composite_picture: integer translation (0, 0), removing
>[ 18336.582] sna_render_pixmap_bo pixmap=1, (0, 0)x(0, 0)/(1024, 1024)
>[ 18336.582] sna_render_pixmap_bo: GPU all damaged
>[ 18336.582] gen4_composite_channel_convert: repeat 0 -> 0, filter 0 -> 0
>[ 18336.582] gen4_choose_composite_emitter: solid, identity mask
>[ 18336.582] kgem_check_bo: num_pages=+257, num_exec=+2
>[ 18336.582] aligning vertex: was 3, now 4 floats per vertex
>[ 18336.582] gen4_composite_get_binding_table(3ff60)
>[ 18336.582] kgem_add_reloc: handle=60, pos=65489, delta=0, domains=40000
>[ 18336.582] kgem_add_reloc: adding proxy [delta=28] for handle=60
>[ 18336.582] kgem_add_handle: handle=60, index=3
>[ 18336.582] [ffd0] bind bo(handle=60, addr=2052124), format=192, width=1, height=1, pitch=4, tiling=0 -> sampler
>[ 18336.582] kgem_add_reloc: handle=5, pos=65481, delta=0, domains=40000
>[ 18336.582] kgem_add_handle: handle=5, index=4
>[ 18336.582] [ffc8] bind bo(handle=5, addr=272199680), format=324, width=1024, height=1024, pitch=1024, tiling=1 -> sampler
>[ 18336.582] gen4_emit_state: flushing dirty (0, 0), forced? 1
>[ 18336.582] __kgem_bo_mark_dirty: handle=43 (proxy? 0)
>[ 18336.582] gen4_emit_pipelined_pointers: has_mask=0, src=(0, 1), mask=(0, 0),kernel=2, blend=3, ca=0, format=20020888
>[ 18336.582] blend op=3, dst=20020888 [A=0] => src=1, dst=19 => offset=a00
>[ 18336.582] gen4_emit_pipelined_pointers: sp=6272, bp=2560
>[ 18336.582] gen4_emit_vertex_elements: first channel 1 floats, offset=4b
>[ 18336.582] gen4_emit_vertex_elements: second channel 2 floats, offset=8b
>[ 18336.582] glyphs0_to_dst: glyph=(26, 156)x(7, 11), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(26, 156)+(0, 0), mask=(320, 48)+(0, 0), dst=(26, 156)+(0, 0), size=(7, 11)
>[ 18336.582] emit_primitive_identity_mask: dst=(26, 156), mask=(320.00, 48.00) x (7.00, 11.00)
>[ 18336.582] glyphs0_to_dst: glyph=(35, 159)x(5, 8), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(35, 159)+(0, 0), mask=(208, 0)+(0, 0), dst=(35, 159)+(0, 0), size=(5, 8)
>[ 18336.582] gen4_vertex_flush[148] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(35, 159), mask=(208.00, 0.00) x (5.00, 8.00)
>[ 18336.582] glyphs0_to_dst: glyph=(41, 159)x(5, 8), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(41, 159)+(0, 0), mask=(208, 0)+(0, 0), dst=(41, 159)+(0, 0), size=(5, 8)
>[ 18336.582] gen4_vertex_flush[164] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(41, 159), mask=(208.00, 0.00) x (5.00, 8.00)
>[ 18336.582] glyphs0_to_dst: glyph=(47, 159)x(7, 8), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(47, 159)+(0, 0), mask=(176, 0)+(0, 0), dst=(47, 159)+(0, 0), size=(7, 8)
>[ 18336.582] gen4_vertex_flush[180] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(47, 159), mask=(176.00, 0.00) x (7.00, 8.00)
>[ 18336.582] glyphs0_to_dst: glyph=(56, 159)x(5, 8), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(56, 159)+(0, 0), mask=(208, 0)+(0, 0), dst=(56, 159)+(0, 0), size=(5, 8)
>[ 18336.582] gen4_vertex_flush[19c] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(56, 159), mask=(208.00, 0.00) x (5.00, 8.00)
>[ 18336.582] glyphs0_to_dst: glyph=(68, 156)x(3, 13), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(68, 156)+(0, 0), mask=(272, 16)+(0, 0), dst=(68, 156)+(0, 0), size=(3, 13)
>[ 18336.582] gen4_vertex_flush[1b8] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(68, 156), mask=(272.00, 16.00) x (3.00, 13.00)
>[ 18336.582] glyphs0_to_dst: glyph=(74, 156)x(6, 11), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(74, 156)+(0, 0), mask=(192, 48)+(0, 0), dst=(74, 156)+(0, 0), size=(6, 11)
>[ 18336.582] gen4_vertex_flush[1d4] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(74, 156), mask=(192.00, 48.00) x (6.00, 11.00)
>[ 18336.582] glyphs0_to_dst: glyph=(83, 156)x(3, 13), unclipped
>[ 18336.582] gen4_render_composite_blt: src=(83, 156)+(0, 0), mask=(304, 16)+(0, 0), dst=(83, 156)+(0, 0), size=(3, 13)
>[ 18336.582] gen4_vertex_flush[1f0] = 3
>[ 18336.582] emit_primitive_identity_mask: dst=(83, 156), mask=(304.00, 16.00) x (3.00, 13.00)
>[ 18336.582] gen4_render_composite_done()
>[ 18336.583] gen4_vertex_flush[20c] = 3
>[ 18336.583] sna_validate_gc(0x5645ba1b0dd0) changes=7fffff, previous serial=80000000, drawable=1cca08
>[ 18336.583] sna_validate_gc: recomputing clip
>[ 18336.583] sna_validate_gc: composite clip=1x[(0, 0), (166, 200)] [0x5645ba7ada10]
>[ 18336.583] sna_poly_fill_rect(n=1, PlaneMask: ffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[ 18336.583] sna_poly_fill_rect_extents: [0] = (0, 175)x(166, 25)
>[ 18336.583] sna_poly_fill_rect: extents(0, 175), (166, 200), flags=1
>[ 18336.583] sna_poly_fill_rect: dropping last-cpu hint
>[ 18336.583] sna_drawable_use_bo pixmap=1886728, box=((0, 175), (166, 200)), flags=9...
>[ 18336.583] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=9
>[ 18336.583] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.583] sna_drawable_use_bo: using whole GPU bo
>[ 18336.583] sna_poly_fill_rect: solid fill [f7f7f7], testing for blt
>[ 18336.583] sna_poly_fill_rect_blt pixmap=1886728 x 1 [(0, 175)x(166, 25)...]+(0,0), clipped?=0
>[ 18336.583] sna_poly_fill_rect_blt: using fill_one() fast path: (0, 175), (166, 200). alu=3, pixel=f7f7f7, damage?=0
>[ 18336.583] gen4_render_fill_one: color=f7f7f7
>[ 18336.583] sna_blt_fill_boxes (32, f7f7f7, 3) x 1
>[ 18336.583] sna_blt_fill_box: box=((0, 175), (166, 200))
>[ 18336.583] sna_blt_fill_box: matching last fill, converting to scanlines
>[ 18336.583] __kgem_busy: handle=1, busy=131072, wedged=0
>[ 18336.583] __kgem_ring_is_idle: last fence handle=1 still busy
>[ 18336.583] kgem_add_reloc: handle=43, pos=140, delta=0, domains=28002
>[ 18336.583] sna_blt_fill_boxes: emitting 1 boxes out of 1 (batch space 65327)
>[ 18336.583] sna_blt_fill_boxes: (0, 175), (166, 200): f7f7f7
>[ 18336.583] sna_create_pixmap(16, 16, 32, usage=0)
>[ 18336.583] kgem_can_create_2d: 16x16 @ 32
>[ 18336.583] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=64, height=16
>[ 18336.583] kgem_can_create_2d: untiled size=4096
>[ 18336.583] kgem_choose_tiling: too thin [width 16, 32 bpp] for TILING_X
>[ 18336.583] kgem_choose_tiling: 16x16 -> 0
>[ 18336.583] sna_create_pixmap: usage=0, flags=a
>[ 18336.583] sna_create_pixmap: small buffer [1024], attaching to shadow pixmap
>[ 18336.583] create_pixmap: allocating pixmap 16x16, depth=32/32, size=1032
>[ 18336.583] create_pixmap: serial=1886745, usage=0, 16x16
>[ 18336.583] sna_create_pixmap: serial=1886745, 16x16, usage=0
>[ 18336.583] sna_validate_gc(0x5645ba2b6c50) changes=0, previous serial=1cca18, drawable=1cca1a
>[ 18336.583] sna_validate_gc: recomputing clip
>[ 18336.583] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x5645ba233620]
>[ 18336.583] sna_put_image((0, 0)x(16, 16), depth=32, format=2)
>[ 18336.583] try_upload__fast: ignore existing cpu damage (if any)
>[ 18336.583] try_upload__inplace: bo? 0, can map? 0, replaces? 1
>[ 18336.583] try_upload__fast: no, no gpu damage
>[ 18336.583] sna_drawable_move_region_to_cpu(pixmap=1886746 (16x16), [(0, 0), (16, 16)], flags=1)
>[ 18336.583] sna_drawable_move_region_to_cpu: region (0, 0), (16, 16) + (0, 0) subsumes pixmap (16x16)
>[ 18336.583] _sna_pixmap_move_to_cpu(pixmap=1886746, 16x16, flags=1)
>[ 18336.583] _sna_pixmap_move_to_cpu: gpu_bo=0, gpu_damage=0x0, cpu_damage=0x0, is-clear?=0
>[ 18336.583] _sna_pixmap_move_to_cpu: no readback, discarding gpu damage [0], pending clear[0]
>[ 18336.583] operate_inplace: no, inplace operation not suitable
>[ 18336.583] _sna_pixmap_move_to_cpu: marking as damaged
>[ 18336.583] __sna_damage_all(16, 16)
>[ 18336.583] sna_pixmap_free_gpu: handle=0 (pinned? 0)
>[ 18336.583] sna_put_zpixmap_blt: upload(0, 0, 16, 16) x 1 boxes
>[ 18336.583] sna_put_zpixmap_blt: copy box (0, 0)->(0, 0)x(16, 16)
>[ 18336.583] memcpy_blt: src=(0, 0), dst=(0, 0), size=16x16, pitch=64/64
>[ 18336.583] sna_composite(pixmap=1886728, op=3, src=1886746+(0, 0), mask=0+(5, 179), dst=1886728+(5, 179)+(0, 0), size=(16, 16)
>[ 18336.583] sna_compute_composite_region: dst=(5, 179)x(16, 16)
>[ 18336.583] sna_compute_composite_region: initial clip against dst->pDrawable: (5, 179), (21, 195)
>[ 18336.583] clip_to_dst: region: 1x[(5, 179), (21, 195)], clip: 1x[(0, 0), (166, 200)]
>[ 18336.583] sna_compute_composite_region: clip against dst->pCompositeClip: (5, 179), (21, 195)
>[ 18336.583] sna_compute_composite_region: clip against src (16x16 clip=0): (5, 179), (21, 195)
>[ 18336.583] sna_composite: composite region extents:+(0, 0) -> (5, 179), (21, 195) + (0, 0)
>[ 18336.583] gen4_render_composite: 16x16, current mode=3
>[ 18336.583] sna_blt_composite (0, 0), (5, 179), 16x16
>[ 18336.583] sna_blt_composite: unsupported op [3] for blitting
>[ 18336.583] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.583] gen4_composite_fallback: dst is already on the GPU, try to use GPU
>[ 18336.583] sna_drawable_use_bo pixmap=1886728, box=((5, 179), (21, 195)), flags=7...
>[ 18336.583] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
>[ 18336.583] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.583] sna_drawable_use_bo: using whole GPU bo
>[ 18336.583] gen4_composite_set_target: pixmap=1886728, format=20020888, size=166x200, pitch=1024, delta=(0,0),damage=0xffffffffffffffff
>[ 18336.583] gen4_composite_picture: (0, 0)x(16, 16), dst=(5, 179)
>[ 18336.583] sna_transform_is_imprecise_integer_translation: no transform
>[ 18336.583] gen4_composite_picture: integer translation (0, 0), removing
>[ 18336.583] sna_render_pixmap_bo pixmap=1886746, (0, 0)x(16, 16)/(16, 16)
>[ 18336.583] sna_render_pixmap_bo box=(0, 0), (16, 16): (16, 16)/(16, 16)
>[ 18336.583] sna_render_pixmap_bo: offset=(-5, -179), size=(16, 16)
>[ 18336.583] use_cpu_bo: no cpu bo
>[ 18336.583] move_to_gpu: migrating whole pixmap (16x16) for source (0,0),(16,16), count 5? 0
>[ 18336.584] sna_render_pixmap_bo: uploading CPU box (0, 0), (16, 16)
>[ 18336.584] upload: box=(0, 0), (16, 16), pixmap=16x16
>[ 18336.584] sna_drawable_move_region_to_cpu(pixmap=1886746 (16x16), [(0, 0), (16, 16)], flags=2)
>[ 18336.584] sna_drawable_move_region_to_cpu: pixmap=1886746 all damaged on CPU
>[ 18336.584] kgem_can_create_2d: 16x16 @ 32
>[ 18336.584] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=64, height=16
>[ 18336.584] kgem_can_create_2d: untiled size=4096
>[ 18336.584] kgem_choose_tiling: too thin [width 16, 32 bpp] for TILING_X
>[ 18336.584] kgem_choose_tiling: 16x16 -> 0
>[ 18336.584] kgem_upload_source_image : (0, 0), (16, 16), stride=64, bpp=32
>[ 18336.584] kgem_create_buffer_2d: 16x16, 32 bpp, stride=64
>[ 18336.584] kgem_create_buffer: size=1024, flags=3 [write?=1, inplace?=1, last?=0]
>[ 18336.584] kgem_create_buffer: reusing buffer? used=590848 + size=1024, total=1048576
>[ 18336.584] kgem_create_proxy: target handle=91 [proxy? -1], offset=590848, length=1024, io=1
>[ 18336.584] __kgem_bo_init(handle=91, num_pages=1024)
>[ 18336.584] memcpy_blt: src=(0, 0), dst=(0, 0), size=16x16, pitch=64/64
>[ 18336.584] upload: adding upload cache to pixmap=1886746
>[ 18336.584] kgem_proxy_bo_attach: handle=91
>[ 18336.584] sna_blt_composite__convert src=91, dst=43 (redirect? 0)
>[ 18336.584] sna_blt_composite__convert: unsupported op [3] for blitting
>[ 18336.584] gen4_composite_channel_convert: repeat 0 -> 0, filter 0 -> 0
>[ 18336.584] gen4_choose_composite_emitter: identity src, no mask
>[ 18336.584] kgem_check_bo: num_pages=+0, num_exec=+0
>[ 18336.584] aligning vertex: was 4, now 3 floats per vertex
>[ 18336.584] __kgem_busy: handle=1, busy=131072, wedged=0
>[ 18336.584] __kgem_ring_is_idle: last fence handle=1 still busy
>[ 18336.585] gen4_composite_get_binding_table(3ff00)
>[ 18336.585] kgem_add_reloc: handle=91, pos=65465, delta=0, domains=40000
>[ 18336.585] kgem_add_reloc: adding proxy [delta=590848] for handle=91
>[ 18336.585] [ffb8] bind bo(handle=91, addr=342189056), format=192, width=16, height=16, pitch=64, tiling=0 -> sampler
>[ 18336.585] gen4_emit_state: flushing dirty (0, 0), forced? 1
>[ 18336.585] __kgem_bo_mark_dirty: handle=43 (proxy? 0)
>[ 18336.585] gen4_emit_pipelined_pointers: has_mask=2, src=(0, 0), mask=(0, 0),kernel=0, blend=3, ca=0, format=20020888
>[ 18336.585] blend op=3, dst=20020888 [A=0] => src=1, dst=19 => offset=a00
>[ 18336.585] gen4_emit_pipelined_pointers: sp=0, bp=2560
>[ 18336.585] gen4_emit_vertex_elements: first channel 2 floats, offset=4b
>[ 18336.585]   gen4_render_composite_box: (5, 179), (21, 195)
>[ 18336.585] apply_damage: damage=0x0, region=1 [(5, 179), (21, 195) + (0, 0)]
>[ 18336.585] gen4_render_composite_done()
>[ 18336.585] gen4_vertex_flush[2a8] = 3
>[ 18336.585] sna_block_handler (timeout=0)
>[ 18336.585] sna_present_get_crtc: window=54525954 (pixmap=3), box=(0, 0)x(1366, 768)
>[ 18336.585] sna_covering_crtc for box=(0, 0), (1366, 768)
>[ 18336.585] sna_covering_crtc: have PrimaryOutput? 1 marking as desired
>[ 18336.585] sna_box_intersect: (0, 0), (1366, 768) intersect (0, 0), (1366, 768) = (0, 0), (1366, 768)
>[ 18336.585] sna_covering_crtc: box overlaps desired crtc: (0, 0), (1366, 768)
>[ 18336.585] sna_present_get_ust_msc(pipe=0)
>[ 18336.585] sna_wait_vblank(pipe=0, waiting until seq=0 [relative])
>[ 18336.585] sna_crtc_record_swap: recording last swap on pipe=0, frame 1100119 [msc=1100119], time 18336.580826
>[ 18336.585] sna_wait_vblank(pipe=0, waiting until seq=1100120)
>[ 18336.585] sna_present_get_ust_msc: pipe=0, tv=18336.580826 seq=1100120 msc=1100119
>[ 18336.585] sna_present_queue_vblank(pipe=0, event=589633, msc=1100120, last swap=1100119)
>[ 18336.585] [ 18336.585] sna_set_cursor_position(732, 499), cursor? 1
>[ 18336.585] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.585] sna_set_cursor_position: CRTC:36 (732, 499), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.585] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>sna_present_queue_vblank: appending event=589633 to vblank 1100120 x 1
>[ 18336.586] sna_block_handler (timeout=0)
>[ 18336.586] sna_present_get_crtc: window=50331650 (pixmap=3), box=(3, 50)x(300, 300)
>[ 18336.586] sna_covering_crtc for box=(3, 50), (303, 350)
>[ 18336.586] sna_covering_crtc: have PrimaryOutput? 1 marking as desired
>[ 18336.586] sna_box_intersect: (0, 0), (1366, 768) intersect (3, 50), (303, 350) = (3, 50), (303, 350)
>[ 18336.586] sna_covering_crtc: box overlaps desired crtc: (3, 50), (303, 350)
>[ 18336.586] sna_present_get_ust_msc(pipe=0)
>[ 18336.586] sna_present_get_ust_msc: vblank active, reusing last swap msc/ust
>[ 18336.586] sna_present_get_ust_msc: pipe=0, tv=18336.580826 seq=32697 msc=1100119
>[ 18336.586] sna_present_queue_vblank(pipe=0, event=589634, msc=1100120, last swap=1100119)
>[ 18336.586] sna_present_queue_vblank: appending event=589634 to vblank 1100120 x 2
>[ 18336.586] sna_block_handler (timeout=0)
>[ 18336.586] sna_destroy_pixmap: pixmap=1886746, attached?=1
>[ 18336.586] _kgem_bo_destroy: handle=91, proxy? 1
>[ 18336.586] __sna_free_pixmap(pixmap=1886746)
>[ 18336.586] sna_glyphs(op=3, nlist=1, src=(25, 192))
>[ 18336.586] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.586] sna_glyphs: discarding mask
>[ 18336.586] glyphs0_to_dst(op=3, src=(25, 192), nlist=1,  dst=(25, 192)+(0, 0))
>[ 18336.586] clipped_glyphs: no, clip region (0, 0), (166, 200) matches drawable pixmap=94857476080096 size=166x200
>[ 18336.586] gen4_render_composite: 0x0, current mode=1
>[ 18336.586] kgem_bo_is_busy: handle=5, domain: 3 exec? 1, rq? 1
>[ 18336.586] kgem_bo_is_busy: handle=43, domain: 3 exec? 1, rq? 1
>[ 18336.586] gen4_composite_fallback: dst is already on the GPU, try to use GPU
>[ 18336.586] sna_drawable_use_bo pixmap=1886728, box=((0, 0), (166, 200)), flags=7...
>[ 18336.586] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=7
>[ 18336.586] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.586] sna_drawable_use_bo: using whole GPU bo
>[ 18336.586] gen4_composite_set_target: pixmap=1886728, format=20020888, size=166x200, pitch=1024, delta=(0,0),damage=0xffffffffffffffff
>[ 18336.586] gen4_composite_picture: (0, 0)x(0, 0), dst=(0, 0)
>[ 18336.586] color_convert: src=ff2c2c2c [20028888]
>[ 18336.586] color_convert: dst=ff2c2c2c [20028888]
>[ 18336.586] sna_render_get_solid: ff2c2c2c
>[ 18336.586] sna_render_get_solid(7) = ff2c2c2c (last)
>[ 18336.586] gen4_composite_channel_convert: repeat 1 -> 1, filter 0 -> 0
>[ 18336.586] gen4_composite_picture: (0, 0)x(0, 0), dst=(0, 0)
>[ 18336.586] sna_transform_is_imprecise_integer_translation: no transform
>[ 18336.586] gen4_composite_picture: integer translation (0, 0), removing
>[ 18336.586] sna_render_pixmap_bo pixmap=1, (0, 0)x(0, 0)/(1024, 1024)
>[ 18336.586] sna_render_pixmap_bo: GPU all damaged
>[ 18336.586] gen4_composite_channel_convert: repeat 0 -> 0, filter 0 -> 0
>[ 18336.586] gen4_choose_composite_emitter: solid, identity mask
>[ 18336.586] kgem_check_bo: num_pages=+0, num_exec=+0
>[ 18336.586] aligning vertex: was 3, now 4 floats per vertex
>[ 18336.586] gen4_composite_get_binding_table(3fec0)
>[ 18336.586] gen4_emit_state: flushing dirty (0, 0), forced? 1
>[ 18336.586] __kgem_bo_mark_dirty: handle=43 (proxy? 0)
>[ 18336.586] gen4_emit_pipelined_pointers: has_mask=0, src=(0, 1), mask=(0, 0),kernel=2, blend=3, ca=0, format=20020888
>[ 18336.586] blend op=3, dst=20020888 [A=0] => src=1, dst=19 => offset=a00
>[ 18336.586] gen4_emit_pipelined_pointers: sp=6272, bp=2560
>[ 18336.586] gen4_emit_vertex_elements: first channel 1 floats, offset=4b
>[ 18336.586] gen4_emit_vertex_elements: second channel 2 floats, offset=8b
>[ 18336.586] glyphs0_to_dst: glyph=(26, 184)x(7, 8), unclipped
>[ 18336.586] gen4_render_composite_blt: src=(26, 184)+(0, 0), mask=(152, 16)+(0, 0), dst=(26, 184)+(0, 0), size=(7, 8)
>[ 18336.586] emit_primitive_identity_mask: dst=(26, 184), mask=(152.00, 16.00) x (7.00, 8.00)
>[ 18336.586] glyphs0_to_dst: glyph=(35, 184)x(5, 8), unclipped
>[ 18336.586] gen4_render_composite_blt: src=(35, 184)+(0, 0), mask=(208, 0)+(0, 0), dst=(35, 184)+(0, 0), size=(5, 8)
>[ 18336.586] gen4_vertex_flush[314] = 3
>[ 18336.586] emit_primitive_identity_mask: dst=(35, 184), mask=(208.00, 0.00) x (5.00, 8.00)
>[ 18336.586] glyphs0_to_dst: glyph=(41, 184)x(7, 8), unclipped
>[ 18336.586] gen4_render_composite_blt: src=(41, 184)+(0, 0), mask=(176, 16)+(0, 0), dst=(41, 184)+(0, 0), size=(7, 8)
>[ 18336.586] gen4_vertex_flush[330] = 3
>[ 18336.586] emit_primitive_identity_mask: dst=(41, 184), mask=(176.00, 16.00) x (7.00, 8.00)
>[ 18336.586] glyphs0_to_dst: glyph=(50, 184)x(7, 8), unclipped
>[ 18336.586] gen4_render_composite_blt: src=(50, 184)+(0, 0), mask=(184, 0)+(0, 0), dst=(50, 184)+(0, 0), size=(7, 8)
>[ 18336.586] gen4_vertex_flush[34c] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(50, 184), mask=(184.00, 0.00) x (7.00, 8.00)
>[ 18336.587] glyphs0_to_dst: glyph=(59, 184)x(7, 8), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(59, 184)+(0, 0), mask=(152, 16)+(0, 0), dst=(59, 184)+(0, 0), size=(7, 8)
>[ 18336.587] gen4_vertex_flush[368] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(59, 184), mask=(152.00, 16.00) x (7.00, 8.00)
>[ 18336.587] glyphs0_to_dst: glyph=(68, 181)x(7, 11), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(68, 181)+(0, 0), mask=(224, 48)+(0, 0), dst=(68, 181)+(0, 0), size=(7, 11)
>[ 18336.587] gen4_vertex_flush[384] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(68, 181), mask=(224.00, 48.00) x (7.00, 11.00)
>[ 18336.587] glyphs0_to_dst: glyph=(77, 184)x(7, 11), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(77, 184)+(0, 0), mask=(272, 48)+(0, 0), dst=(77, 184)+(0, 0), size=(7, 11)
>[ 18336.587] gen4_vertex_flush[3a0] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(77, 184), mask=(272.00, 48.00) x (7.00, 11.00)
>[ 18336.587] glyphs0_to_dst: glyph=(87, 190)x(1, 2), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(87, 190)+(0, 0), mask=(336, 16)+(0, 0), dst=(87, 190)+(0, 0), size=(1, 2)
>[ 18336.587] gen4_vertex_flush[3bc] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(87, 190), mask=(336.00, 16.00) x (1.00, 2.00)
>[ 18336.587] glyphs0_to_dst: glyph=(91, 184)x(6, 8), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(91, 184)+(0, 0), mask=(144, 16)+(0, 0), dst=(91, 184)+(0, 0), size=(6, 8)
>[ 18336.587] gen4_vertex_flush[3d8] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(91, 184), mask=(144.00, 16.00) x (6.00, 8.00)
>[ 18336.587] glyphs0_to_dst: glyph=(99, 184)x(7, 8), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(99, 184)+(0, 0), mask=(176, 0)+(0, 0), dst=(99, 184)+(0, 0), size=(7, 8)
>[ 18336.587] gen4_vertex_flush[3f4] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(99, 184), mask=(176.00, 0.00) x (7.00, 8.00)
>[ 18336.587] glyphs0_to_dst: glyph=(108, 184)x(13, 8), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(108, 184)+(0, 0), mask=(128, 48)+(0, 0), dst=(108, 184)+(0, 0), size=(13, 8)
>[ 18336.587] gen4_vertex_flush[410] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(108, 184), mask=(128.00, 48.00) x (13.00, 8.00)
>[ 18336.587] glyphs0_to_dst: glyph=(129, 181)x(3, 13), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(129, 181)+(0, 0), mask=(272, 16)+(0, 0), dst=(129, 181)+(0, 0), size=(3, 13)
>[ 18336.587] gen4_vertex_flush[42c] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(129, 181), mask=(272.00, 16.00) x (3.00, 13.00)
>[ 18336.587] glyphs0_to_dst: glyph=(135, 181)x(6, 11), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(135, 181)+(0, 0), mask=(192, 48)+(0, 0), dst=(135, 181)+(0, 0), size=(6, 11)
>[ 18336.587] gen4_vertex_flush[448] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(135, 181), mask=(192.00, 48.00) x (6.00, 11.00)
>[ 18336.587] glyphs0_to_dst: glyph=(144, 181)x(3, 13), unclipped
>[ 18336.587] gen4_render_composite_blt: src=(144, 181)+(0, 0), mask=(304, 16)+(0, 0), dst=(144, 181)+(0, 0), size=(3, 13)
>[ 18336.587] gen4_vertex_flush[464] = 3
>[ 18336.587] emit_primitive_identity_mask: dst=(144, 181), mask=(304.00, 16.00) x (3.00, 13.00)
>[ 18336.587] gen4_render_composite_done()
>[ 18336.587] gen4_vertex_flush[480] = 3
>[ 18336.587] sna_validate_gc(0x5645ba1e0560) changes=e0000, previous serial=801cbf55, drawable=1cbf55
>[ 18336.587] sna_validate_gc: recomputing clip
>[ 18336.587] sna_validate_gc: composite clip=0x[(525, 694), (525, 694)] [0x5645ba1e2500]
>[ 18336.587] sna_copy_area: src=pixmap=1886728:(0, 0)x(166, 200)+(0, 0) -> dst=pixmap=3:(2, 65)+(3, 50); alu=3, pm=ffffffff, depth=24
>[ 18336.587] sna_copy_area: normal copy
>[ 18336.587] sna_do_copy: src=(0, 0), dst=(2, 65), size=(166x200)
>[ 18336.587] sna_do_copy: after drawable: src=(0, 0), dst=(5, 115), size=(166x200)
>[ 18336.587] sna_do_copy: dst extents (5, 115), (171, 315), dst clip extents (525, 694), (525, 694), dst size=1227x655
>[ 18336.587] sna_do_copy: dst clipped out
>[ 18336.587] sna_destroy_pixmap: pixmap=1886728, attached?=1
>[ 18336.587] _kgem_bo_destroy: handle=43, proxy? 0
>[ 18336.587] __kgem_bo_destroy: handle=43, size=262144
>[ 18336.587] __kgem_bo_destroy: handle=43 -> active
>[ 18336.587] __sna_free_pixmap(pixmap=1886728)
>[ 18336.588] sna_create_pixmap(1210, 200, 24, usage=0)
>[ 18336.588] kgem_can_create_2d: 1210x200 @ 24
>[ 18336.588] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=4840, height=200
>[ 18336.588] kgem_can_create_2d: untiled size=970752
>[ 18336.588] kgem_choose_tiling: TLB miss between lines 1210x200 (pitch=4840), forcing tiling 1
>[ 18336.588] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=4840, height=200
>[ 18336.588] kgem_can_create_2d: tiled[-1] size=970752
>[ 18336.588] sna_create_pixmap: usage=0, flags=1b
>[ 18336.588] sna_create_pixmap: creating GPU pixmap 1210x200, stride=4840, flags=1b
>[ 18336.588] __pop_freed_pixmap: reusing freed pixmap=1886728 header
>[ 18336.588] create_pixmap_hdr: pixmap=1886747, width=1210, height=200, usage=0
>[ 18336.588] sna_create_pixmap: serial=1886747, 1210x200, usage=0
>[ 18336.588] sna_composite_rectangles(pixmap=1886748, op=1, fff7f7f7 x 3 [(0, 0)x(1210, 25) ...])
>[ 18336.588] sna_composite_rectangles: converted to op 1
>[ 18336.588] sna_composite_rectangles[0] (0, 0)x(1210, 25) -> (0, 0), (1210, 25)
>[ 18336.588] sna_composite_rectangles[1] (0, 100)x(1210, 25) -> (0, 100), (1210, 125)
>[ 18336.588] sna_composite_rectangles[2] (0, 175)x(1210, 25) -> (0, 175), (1210, 200)
>[ 18336.588] sna_composite_rectangles: nrects=3, region=(0, 0), (1210, 200) x 3
>[ 18336.588] sna_composite_rectangles: clipped extents (0, 0),(1210, 200) x 3
>[ 18336.588] sna_composite_rectangles: pixmap +(0, 0) extents (0, 0),(1210, 200)
>[ 18336.588] sna_composite_rectangles: dropping last-cpu hint
>[ 18336.588] sna_drawable_use_bo pixmap=1886748, box=((0, 0), (1210, 200)), flags=1...
>[ 18336.588] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=1
>[ 18336.588] sna_drawable_use_bo: gpu? 0, damaged? 0; cpu? 0, damaged? 0
>[ 18336.588] sna_pixmap_move_to_gpu(pixmap=1886748, usage=0), flags=b
>[ 18336.588] sna_pixmap_move_to_gpu: CPU damage? 0
>[ 18336.588] sna_pixmap_move_to_gpu: creating GPU bo (1210x200@32), create=b
>[ 18336.588] kgem_choose_tiling: TLB miss between lines 1210x200 (pitch=4840), forcing tiling 1
>[ 18336.588] kgem_choose_tiling: TLB miss between lines 1210x200 (pitch=4840), forcing tiling 1
>[ 18336.588] sna_pixmap_alloc_gpu: pixmap=1886748
>[ 18336.588] kgem_create_2d(1210x200, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=0)
>[ 18336.588] kgem_surface_size: tile_width=512, tile_height=8 => aligned pitch=5120, height=200
>[ 18336.588] kgem_set_tiling: handle=89, tiling=1 [1], pitch=5120 [5120]: 1
>[ 18336.588] kgem_bo_remove_from_inactive: removing handle=89 from inactive
>[ 18336.588]   from inactive: pitch=5120, tiling=1: handle=89, id=1228333
>[ 18336.588] __sna_damage_all(1210, 200)
>[ 18336.588] sna_pixmap_move_to_gpu: marking as all-damaged for GPU
>[ 18336.588] sna_pixmap_mark_active: pixmap=1886748, handle=89
>[ 18336.588] sna_drawable_use_bo: allocated GPU bo for operation
>[ 18336.588] sna_drawable_use_bo: using GPU bo with damage? 0
>[ 18336.588] sna_blt_fill_boxes (32, fff7f7f7, 3) x 3
>[ 18336.588] __kgem_busy: handle=1, busy=0, wedged=0
>[ 18336.588] __kgem_retire_rq: request 1 complete
>[ 18336.588] kgem_set_mode: flushing before new bo
>[ 18336.588] gen4_vertex_close: used=12660, vbo active? 20, vb=204, nreloc=2
>[ 18336.588] gen4_vertex_close: reloc[0] = 46
>[ 18336.588] kgem_add_reloc: handle=20, pos=46, delta=0, domains=200000
>[ 18336.588] kgem_add_handle: handle=20, index=5
>[ 18336.588] gen4_vertex_close: reloc[1] = 78
>[ 18336.588] kgem_add_reloc: handle=20, pos=78, delta=0, domains=200000
>[ 18336.588] batch[1/0, flags=1800]: 294 294 65456 65528, nreloc=11, nexec=6, nfence=0, aperture=628 [fenced=0]
>[ 18336.588] kgem_finish_buffers: buffer handle=91, used=591872, exec?=1, write=1, mmapped=cpu, refcnt=1
>[ 18336.588] kgem_finish_buffers: retaining upload buffer (591872/1048576): used=593920, refcnt=1
>[ 18336.588] kgem_fixup_relocs: fixing up 1 self-relocations to handle=0x1, presumed-offset=180000
>[ 18336.588] kgem_fixup_relocs: moving base of self-reloc[0:2] 1 -> -258047
>[ 18336.588] kgem_fixup_relocs: shrinking by 258048
>[ 18336.588] __kgem_busy: handle=1, busy=0, wedged=0
>[ 18336.588] kgem_commit: release handle=1 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=180000
>[ 18336.588] kgem_commit: release handle=43 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=88dc000
>[ 18336.588] kgem_commit: release handle=4 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=29c0000
>[ 18336.588] kgem_commit: release handle=91 (proxy? 0), dirty? 0 flush? 0, snoop? 1 -> offset=145c6000
>[ 18336.589] kgem_commit: release handle=60 (proxy? 1), dirty? 0 flush? 0, snoop? 0 -> offset=0
>[ 18336.589] __kgem_bo_clear_busy: handle=60
>[ 18336.589] __kgem_bo_clear_dirty: handle=60
>[ 18336.589] kgem_commit: release handle=60 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=1f5000
>[ 18336.589] kgem_commit: release handle=5 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=10397000
>[ 18336.589] kgem_commit: release handle=20 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=b384000
>[ 18336.589] __kgem_busy: handle=1, busy=131072, wedged=0
>[ 18336.589] kgem_bo_can_map: handle=20, map=0x7fb9c6889000:0x7fb9c6464000:0x0, tiling=0, domain=3, offset=188235776
>[ 18336.589] kgem_add_reloc: handle=89, pos=4, delta=0, domains=28002
>[ 18336.589] kgem_add_handle: handle=89, index=0
>[ 18336.589] __kgem_bo_mark_dirty: handle=89 (proxy? 0)
>[ 18336.589] sna_blt_fill_boxes: emitting 3 boxes out of 3 (batch space 65511)
>[ 18336.589] sna_blt_fill_boxes: (0, 0), (1210, 25): fff7f7f7
>[ 18336.589] sna_blt_fill_boxes: (0, 100), (1210, 125): fff7f7f7
>[ 18336.589] sna_blt_fill_boxes: (0, 175), (1210, 200): fff7f7f7
>[ 18336.589] sna_validate_gc(0x5645ba1b0dd0) changes=7fffff, previous serial=80000000, drawable=1cca1c
>[ 18336.589] sna_validate_gc: recomputing clip
>[ 18336.589] sna_validate_gc: composite clip=3x[(0, 0), (1210, 200)] [0x5645ba7ada10]
>[ 18336.589] sna_poly_fill_rect(n=1, PlaneMask: ffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[ 18336.589] sna_poly_fill_rect_extents: [0] = (0, 0)x(266, 25)
>[ 18336.589] sna_poly_fill_rect: extents(0, 0), (266, 25), flags=3
>[ 18336.589] sna_poly_fill_rect: dropping last-cpu hint
>[ 18336.589] sna_drawable_use_bo pixmap=1886748, box=((0, 0), (266, 25)), flags=1...
>[ 18336.589] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=1
>[ 18336.589] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[ 18336.589] sna_drawable_use_bo: using whole GPU bo
>[ 18336.589] sna_poly_fill_rect: solid fill [f7f7f7], testing for blt
>[ 18336.589] sna_poly_fill_rect_blt pixmap=1886748 x 1 [(0, 0)x(266, 25)...]+(0,0), clipped?=2
>[ 18336.589] sna_blt_fill(alu=3, pixel=f7f7f7, bpp=32)
>[ 18336.589] kgem_add_reloc: handle=89, pos=22, delta=0, domains=28002
>[ 18336.589] _sna_blt_fill_boxes: f7f7f7 x 1
>[ 18336.589] _sna_blt_fill_boxes: emitting 1 boxes out of 1 (batch space 65493)
>[ 18336.589] sna_create_pixmap(16, 16, 32, usage=0)
>[ 18336.589] kgem_can_create_2d: 16x16 @ 32
>[ 18336.589] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=64, height=16
>[ 18336.589] kgem_can_create_2d: untiled size=4096
>[ 18336.589] kgem_choose_tiling: too thin [width 16, 32 bpp] for TILING_X
>[ 18336.589] kgem_choose_tiling: 16x16 -> 0
>[ 18336.589] sna_create_pixmap: usage=0, flags=a
>[ 18336.589] sna_create_pixmap: small buffer [1024], attaching to shadow pixmap
>[ 18336.589] create_pixmap: allocating pixmap 16x16, depth=32/32, size=1032
>[ 18336.589] create_pixmap: serial=1886749, usage=0, 16x16
>[ 18336.589] sna_create_pixmap: serial=1886749, 16x16, usage=0
>[ 18336.589] sna_validate_gc(0x5645ba2b6c50) changes=0, previous serial=1cca1a, drawable=1cca1e
>[ 18336.589] sna_validate_gc: recomputing clip
>[ 18336.589] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x5645ba233620]
>[ 18336.589] sna_put_image((0, 0)x(16, 16), depth=32, format=2)
>[ 18336.589] try_upload__fast: ignore existing cpu damage (if any)
>[ 18336.589] try_upload__inplace: bo? 0, can map? 0, replaces? 1
>[ 18336.589] try_upload__fast: no, no gpu damage
>[ 18336.589] sna_drawable_move_region_to_cpu(pixmap=1886750 (16x16), [(0, 0), (16, 16)], flags=1)
>[ 18336.589] sna_drawable_move_region_to_cpu: region (0, 0), (16, 16) + (0, 0) subsumes pixmap (16x16)
>[ 18336.589] _sna_pixmap_move_to_cpu(pixmap=1886750, 16x16, flags=1)
>[ 18336.589] _sna_pixmap_move_to_cpu: gpu_bo=0, gpu_damage=0x0, cpu_damage=0x0, is-clear?=0
>[ 18336.589] _sna_pixmap_move_to_cpu: no readback, discarding gpu damage [0], pending clear[0]
>[ 18336.589] operate_inplace: no, inplace operation not suitable
>[ 18336.589] _sna_pixmap_move_to_cpu: marking as damaged
>[ 18336.589] __sna_damage_all(16, 16)
>[ 18336.589] sna_pixmap_free_gpu: handle=0 (pinned? 0)
>[ 18336.589] sna_put_zpixmap_blt: upload(0, 0, 16, 16) x 1 boxes
>[ 18336.589] sna_put_zpixmap_blt: copy box (0, 0)->(0, 0)x(16, 16)
>[ 18336.589] memcpy_blt: src=(0, 0), dst=(0, 0), size=16x16, pitch=64/64
>[ 18336.589] sna_block_handler (timeout=0)
>[ 18336.592] sna_create_pixmap(1233, 24, 24, usage=0)
>[ 18336.592] kgem_can_create_2d: 1233x24 @ 24
>[ 18336.592] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=4936, height=24
>[ 18336.592] kgem_can_create_2d: untiled size=118784
>[ 18336.593] kgem_choose_tiling: TLB miss between lines 1233x24 (pitch=4932), forcing tiling 1
>[ 18336.593] kgem_surface_size: tile_width=8, tile_height=1 => aligned pitch=4936, height=24
>[ 18336.593] kgem_can_create_2d: tiled[-1] size=118784
>[ 18336.593] sna_create_pixmap: usage=0, flags=1b
>[ 18336.593] sna_create_pixmap: creating GPU pixmap 1233x24, stride=4932, flags=1b
>[ 18336.593] __pop_freed_pixmap: reusing freed pixmap=1886740 header
>[ 18336.593] create_pixmap_hdr: pixmap=1886751, width=1233, height=24, usage=0
>[ 18336.593] sna_create_pixmap: serial=1886751, 1233x24, usage=0
>[ 18336.593] sna_composite_rectangles(pixmap=1886752, op=0, 0 x 1 [(0, 0)x(1233, 24) ...])
>[ 18336.593] sna_composite_rectangles: converted to op 0
>[ 18336.593] sna_composite_rectangles[0] (0, 0)x(1233, 24) -> (0, 0), (1233, 24)
>[ 18336.593] sna_composite_rectangles: nrects=1, region=(0, 0), (1233, 24) x 1
>[ 18336.593] sna_composite_rectangles: clipped extents (0, 0),(1233, 24) x 1
>[ 18336.593] sna_composite_rectangles: pixmap +(0, 0) extents (0, 0),(1233, 24)
>[ 18336.593] sna_composite_rectangles: dropping last-cpu hint
>[ 18336.593] sna_drawable_use_bo pixmap=1886752, box=((0, 0), (1233, 24)), flags=19...
>[ 18336.593] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=19
>[ 18336.593] sna_drawable_use_bo: gpu? 0, damaged? 0; cpu? 0, damaged? 0
>[ 18336.593] sna_pixmap_move_to_gpu(pixmap=1886752, usage=0), flags=b
>[ 18336.593] sna_pixmap_move_to_gpu: CPU damage? 0
>[ 18336.593] sna_pixmap_move_to_gpu: creating GPU bo (1233x24@32), create=b
>[ 18336.593] kgem_choose_tiling: TLB miss between lines 1233x24 (pitch=4932), forcing tiling 1
>[ 18336.593] kgem_choose_tiling: TLB miss between lines 1233x24 (pitch=4932), forcing tiling 1
>[ 18336.593] sna_pixmap_alloc_gpu: pixmap=1886752
>[ 18336.593] kgem_create_2d(1233x24, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=0)
>[ 18336.593] kgem_surface_size: tile_width=512, tile_height=8 => aligned pitch=5120, height=24
>[ 18336.594] kgem_set_tiling: handle=43, tiling=1 [1], pitch=1024 [5120]: 1
>[ 18336.599] sna_set_cursor_position(726, 511), cursor? 1
>[ 18336.599] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.599] sna_set_cursor_position: CRTC:36 (726, 511), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.600] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.613] sna_set_cursor_position(719, 523), cursor? 1
>[ 18336.613] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.613] sna_set_cursor_position: CRTC:36 (719, 523), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.614] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.630] sna_set_cursor_position(711, 537), cursor? 1
>[ 18336.631] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.631] sna_set_cursor_position: CRTC:36 (711, 537), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.631] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.644] sna_set_cursor_position(704, 553), cursor? 1
>[ 18336.645] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.645] sna_set_cursor_position: CRTC:36 (704, 553), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.645] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.662] sna_set_cursor_position(697, 576), cursor? 1
>[ 18336.662] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.662] sna_set_cursor_position: CRTC:36 (697, 576), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.662] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.674] sna_set_cursor_position(693, 584), cursor? 1
>[ 18336.674] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.674] sna_set_cursor_position: CRTC:36 (693, 584), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.675] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.688] sna_set_cursor_position(691, 590), cursor? 1
>[ 18336.688] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.688] sna_set_cursor_position: CRTC:36 (691, 590), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.688] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.702] sna_set_cursor_position(689, 595), cursor? 1
>[ 18336.702] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.702] sna_set_cursor_position: CRTC:36 (689, 595), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.702] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.717] sna_set_cursor_position(686, 599), cursor? 1
>[ 18336.717] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.717] sna_set_cursor_position: CRTC:36 (686, 599), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.717] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.730] sna_set_cursor_position(683, 604), cursor? 1
>[ 18336.730] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.730] sna_set_cursor_position: CRTC:36 (683, 604), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.730] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.744] sna_set_cursor_position(679, 611), cursor? 1
>[ 18336.744] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.744] sna_set_cursor_position: CRTC:36 (679, 611), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.744] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.757] sna_set_cursor_position(678, 620), cursor? 1
>[ 18336.757] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.757] sna_set_cursor_position: CRTC:36 (678, 620), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.757] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.766] sna_set_cursor_position(674, 627), cursor? 1
>[ 18336.767] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.767] sna_set_cursor_position: CRTC:36 (674, 627), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.767] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.781] sna_set_cursor_position(672, 632), cursor? 1
>[ 18336.781] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.781] sna_set_cursor_position: CRTC:36 (672, 632), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.781] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.796] sna_set_cursor_position(669, 638), cursor? 1
>[ 18336.796] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.796] sna_set_cursor_position: CRTC:36 (669, 638), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.796] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.809] sna_set_cursor_position(666, 643), cursor? 1
>[ 18336.809] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.809] sna_set_cursor_position: CRTC:36 (666, 643), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.809] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.826] sna_set_cursor_position(660, 652), cursor? 1
>[ 18336.826] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.826] sna_set_cursor_position: CRTC:36 (660, 652), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.826] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.844] sna_set_cursor_position(659, 655), cursor? 1
>[ 18336.844] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.844] sna_set_cursor_position: CRTC:36 (659, 655), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.844] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.854] sna_set_cursor_position(658, 655), cursor? 1
>[ 18336.854] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.854] sna_set_cursor_position: CRTC:36 (658, 655), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.854] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.866] sna_set_cursor_position(657, 656), cursor? 1
>[ 18336.866] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.866] sna_set_cursor_position: CRTC:36 (657, 656), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.867] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.896] sna_set_cursor_position(657, 657), cursor? 1
>[ 18336.896] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.896] sna_set_cursor_position: CRTC:36 (657, 657), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.896] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.909] sna_set_cursor_position(656, 658), cursor? 1
>[ 18336.909] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.909] sna_set_cursor_position: CRTC:36 (656, 658), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.909] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18336.930] sna_set_cursor_position(656, 659), cursor? 1
>[ 18336.930] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18336.930] sna_set_cursor_position: CRTC:36 (656, 659), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18336.930] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.016] sna_set_cursor_position(655, 659), cursor? 1
>[ 18337.016] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.016] sna_set_cursor_position: CRTC:36 (655, 659), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.016] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.035] sna_set_cursor_position(654, 659), cursor? 1
>[ 18337.035] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.035] sna_set_cursor_position: CRTC:36 (654, 659), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.035] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.082] sna_set_cursor_position(653, 659), cursor? 1
>[ 18337.082] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.082] sna_set_cursor_position: CRTC:36 (653, 659), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.082] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.149] sna_set_cursor_position(653, 660), cursor? 1
>[ 18337.149] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.149] sna_set_cursor_position: CRTC:36 (653, 660), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.149] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.452] sna_set_cursor_position(653, 668), cursor? 1
>[ 18337.452] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.452] sna_set_cursor_position: CRTC:36 (653, 668), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.452] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.472] sna_set_cursor_position(644, 684), cursor? 1
>[ 18337.472] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.472] sna_set_cursor_position: CRTC:36 (644, 684), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.472] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.490] sna_set_cursor_position(631, 710), cursor? 1
>[ 18337.490] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.490] sna_set_cursor_position: CRTC:36 (631, 710), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.490] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.506] sna_set_cursor_position(625, 723), cursor? 1
>[ 18337.506] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.506] sna_set_cursor_position: CRTC:36 (625, 723), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.506] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.519] sna_set_cursor_position(619, 736), cursor? 1
>[ 18337.519] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.519] sna_set_cursor_position: CRTC:36 (619, 736), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.519] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.538] sna_set_cursor_position(608, 760), cursor? 1
>[ 18337.538] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.538] sna_set_cursor_position: CRTC:36 (608, 760), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.538] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.557] sna_set_cursor_position(599, 763), cursor? 1
>[ 18337.557] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.557] sna_set_cursor_position: CRTC:36 (599, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.557] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.574] sna_set_cursor_position(590, 763), cursor? 1
>[ 18337.574] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.574] sna_set_cursor_position: CRTC:36 (590, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.574] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.587] sna_set_cursor_position(587, 763), cursor? 1
>[ 18337.587] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.587] sna_set_cursor_position: CRTC:36 (587, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.587] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.606] sna_set_cursor_position(576, 763), cursor? 1
>[ 18337.606] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.606] sna_set_cursor_position: CRTC:36 (576, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.606] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.627] sna_set_cursor_position(569, 763), cursor? 1
>[ 18337.627] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.627] sna_set_cursor_position: CRTC:36 (569, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.627] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.644] sna_set_cursor_position(562, 763), cursor? 1
>[ 18337.644] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.644] sna_set_cursor_position: CRTC:36 (562, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.644] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.657] sna_set_cursor_position(559, 763), cursor? 1
>[ 18337.657] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.657] sna_set_cursor_position: CRTC:36 (559, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.658] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.674] sna_set_cursor_position(557, 763), cursor? 1
>[ 18337.674] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.674] sna_set_cursor_position: CRTC:36 (557, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.674] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.694] sna_set_cursor_position(553, 763), cursor? 1
>[ 18337.694] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.694] sna_set_cursor_position: CRTC:36 (553, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.694] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.713] sna_set_cursor_position(550, 763), cursor? 1
>[ 18337.713] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.713] sna_set_cursor_position: CRTC:36 (550, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.713] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.729] sna_set_cursor_position(549, 763), cursor? 1
>[ 18337.729] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.729] sna_set_cursor_position: CRTC:36 (549, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.729] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.750] sna_set_cursor_position(545, 763), cursor? 1
>[ 18337.750] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.750] sna_set_cursor_position: CRTC:36 (545, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.750] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.768] sna_set_cursor_position(544, 763), cursor? 1
>[ 18337.768] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.768] sna_set_cursor_position: CRTC:36 (544, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.768] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.782] sna_set_cursor_position(543, 763), cursor? 1
>[ 18337.782] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.782] sna_set_cursor_position: CRTC:36 (543, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.782] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.810] sna_set_cursor_position(542, 763), cursor? 1
>[ 18337.810] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.810] sna_set_cursor_position: CRTC:36 (542, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.810] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.838] sna_set_cursor_position(541, 763), cursor? 1
>[ 18337.838] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.838] sna_set_cursor_position: CRTC:36 (541, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.838] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.870] sna_set_cursor_position(540, 763), cursor? 1
>[ 18337.870] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.870] sna_set_cursor_position: CRTC:36 (540, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.870] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18337.891] sna_set_cursor_position(539, 763), cursor? 1
>[ 18337.891] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18337.891] sna_set_cursor_position: CRTC:36 (539, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18337.891] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18338.273] (EE)sna_set_cursor_position(538, 763), cursor? 1
>[ 18338.321] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18338.321] sna_set_cursor_position: CRTC:36 (538, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18338.321] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18338.371]  
>[ 18338.371] (EE) Backtrace:
>[ 18340.963] (EE) 0: /usr/lib/xorg/modules/drivers/intel_drv.so (kgem_create_2d+0x1dba) [0x7fb9cabaa918]
>[ 18340.992] (EE) 1: /usr/lib/xorg/modules/drivers/intel_drv.so (sna_pixmap_alloc_gpu+0xe7) [0x7fb9cabbd48b]
>[ 18340.992] (EE) 2: /usr/lib/xorg/modules/drivers/intel_drv.so (sna_pixmap_move_to_gpu+0x6bd) [0x7fb9cabc82ab]
>[ 18340.993] (EE) 3: /usr/lib/xorg/modules/drivers/intel_drv.so (sna_drawable_use_bo+0xa3e) [0x7fb9cabc66cd]
>[ 18340.994] (EE) 4: /usr/lib/xorg/modules/drivers/intel_drv.so (sna_composite_rectangles+0x1004) [0x7fb9cac17b96]
>[ 18341.018] sna_set_cursor_position(537, 763), cursor? 1
>[ 18341.018] __sna_get_cursor: current cursor handle=11, serial=7331 [expected 7331]
>[ 18341.018] sna_set_cursor_position: CRTC:36 (537, 763), handle=0, flags=2 (old cursor handle=11), move? 2, update handle? 0
>[ 18341.018] sna_set_cursor_position: CRTC:46 (0, 0), handle=0, flags=0 (old cursor handle=0), move? 0, update handle? 0
>[ 18341.122] (EE) 5: /usr/lib/xorg-server/Xorg (AddTraps+0x4c61) [0x5645b88a5121]
>[ 18341.122] (EE) 6: /usr/lib/xorg-server/Xorg (SendErrorToClient+0x368) [0x5645b87c41e8]
>[ 18341.163] (EE) 7: /usr/lib/xorg-server/Xorg (InitFonts+0x420) [0x5645b87c8210]
>[ 18341.569] (EE) 8: /usr/lib/libc.so.6 (__libc_start_main+0xea) [0x7fb9ce184f6a]
>[ 18341.569] (EE) 9: /usr/lib/xorg-server/Xorg (_start+0x2a) [0x5645b87b1eaa]
>[ 18341.569] (EE) 
>[ 18341.569] (EE) 
>Fatal server error:
>[ 18341.569] (EE) kgem_create_2d:5734 assertion 'bo->pitch >= pitch' failed
>[ 18341.569] (EE) 
>[ 18341.569] (EE) 
>Please consult the The X.Org Foundation support 
>	 at http://wiki.x.org
> for help. 
>[ 18341.662] (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information.
>[ 18341.662] (EE) 
>[ 18341.990] (II) AIGLX: Suspending AIGLX clients for VT switch
>[ 18341.990] sna_leave_vt
>[ 18342.055] sna_mode_reset
>[ 18342.055] sna_disable_cursors
>[ 18342.055] sna_crtc_disable_cursor: CRTC:36, handle=11
>[ 18342.098] __sna_crtc_disable: releasing handle=9 from scanout, active=0
>[ 18342.098] rotation_set: obj=37, type=eeeeeeee prop=39 set-rotation=1
>[ 18342.098] sna_mode_wakeup: len=32
>[ 18342.098] sna_present_vblank_handler: clearing immediate flag
>[ 18342.098] sna_crtc_record_swap: recording last swap on pipe=0, frame 1100120 [msc=1100120], time 18336.597494
>[ 18342.098] vblank_complete: 2 events complete
>[ 18342.098] vblank_complete: pipe=0 tv=18336.597494 msc=1100120 (target=1100120), event=589633 complete
>[ 18342.098] sna_validate_gc(0x5645b9a61c70) changes=7fffff, previous serial=0, drawable=1ca036
>[ 18342.098] sna_validate_gc: recomputing clip
>[ 18342.098] sna_validate_gc: composite clip=0x[(0, 26), (0, 26)] [0x5645ba7b3030]
>[ 18342.098] sna_copy_area: src=pixmap=1830502:(0, 0)x(1366, 768)+(0, 0) -> dst=pixmap=3:(0, 0)+(0, 0); alu=3, pm=ffffffff, depth=24
>[ 18342.098] sna_copy_area: normal copy
>[ 18342.098] sna_do_copy: src=(0, 0), dst=(0, 0), size=(1366x768)
>[ 18342.098] sna_do_copy: unmapped/unrealized dst (pixmap=94857469526640)
>[ 18342.098] sna_sync_fence_set_triggered()
>[ 18342.098] sna_accel_flush: flush?=0, dirty?=0
>[ 18342.098] vblank_complete: pipe=0 tv=18336.597494 msc=1100120 (target=1100120), event=589634 complete
>[ 18342.098] sna_validate_gc(0x5645b9a61c70) changes=7fffff, previous serial=0, drawable=1bdfd2
>[ 18342.098] sna_validate_gc: recomputing clip
>[ 18342.098] sna_validate_gc: composite clip=0x[(3, 50), (3, 50)] [0x5645ba2f3980]
>[ 18342.098] sna_copy_area: src=pixmap=738075:(0, 0)x(300, 300)+(0, 0) -> dst=pixmap=3:(0, 0)+(3, 50); alu=3, pm=ffffffff, depth=24
>[ 18342.098] sna_copy_area: normal copy
>[ 18342.098] sna_do_copy: src=(0, 0), dst=(0, 0), size=(300x300)
>[ 18342.098] sna_do_copy: unmapped/unrealized dst (pixmap=94857469526640)
>[ 18342.098] sna_sync_fence_set_triggered()
>[ 18342.098] sna_accel_flush: flush?=0, dirty?=0
>[ 18342.098] sna_accel_leave
>[ 18342.098] sna_scanout_flush (time=18336588), cpu damage? 0, exec? 0 nbatch=30, busy? 0
>[ 18342.098] stop_flush: scanout=0 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[ 18342.098] sna_accel_disarm_timer[0] (time=18336588)
>[ 18342.218] (EE) Server terminated with error (1). Closing log file.

Actions: View

Attachments on bug 103025: 134543 | 134705 | 134706 | 134707 | 134724 | 134725 | 134731 | 134732 | 134733 | 134743 | 134744 | 134745 | 136074 | 136075 | 136126 | 136129 | 136142 | 136146 | 136147 | 136174 | 136232 | 136235 | 137781 | 140636 | 140899 | 144828 | 144829 | 144830

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.