Attachment 92450 Details for Bug 70905 – Xorg.0.log --enable-debug-full damage box is beyond the pixmap

Xorg.0.log --enable-debug-full damage box is beyond the pixmap

Xorg.0.log-debug-notearfree-cut (text/plain), 136.27 KB, created by Janusz on 2014-01-20 13:44:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Janusz

Created: 2014-01-20 13:44:38 UTC

Size: 136.27 KB

patch

obsolete

>[  3586.342] 
>X.Org X Server 1.15.0
>Release Date: 2013-12-27
>[  3586.342] X Protocol Version 11, Revision 0
>[  3586.342] Build Operating System: Linux 3.12.0-gentoo-gnu x86_64 Gentoo
>[  3586.342] Current Operating System: Linux asus 3.12.7-gentoo-gnu #1 SMP PREEMPT Fri Jan 17 15:51:11 CET 2014 x86_64
>[  3586.342] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.12.7-gentoo-gnu root=/dev/sda2 ro quiet nosplash rootfstype=ext4
>[  3586.342] Build Date: 06 January 2014  07:55:40PM
>[  3586.342]  
>[  3586.342] Current version of pixman: 0.32.4
>[  3586.342] 	Before reporting problems, check http://wiki.x.org
>	to make sure that you have the latest version.
>[  3586.342] Markers: (--) probed, (**) from config file, (==) default setting,
>	(++) from command line, (!!) notice, (II) informational,
>	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
>[  3586.342] (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jan 20 12:49:40 2014
>[  3586.344] (==) Using config directory: "/etc/X11/xorg.conf.d"
>[  3586.344] (==) Using system config directory "/usr/share/X11/xorg.conf.d"
>[  3586.345] (==) No Layout section.  Using the first Screen section.
>[  3586.345] (==) No screen section available. Using defaults.
>[  3586.345] (**) |-->Screen "Default Screen Section" (0)
>[  3586.345] (**) |   |-->Monitor "<default monitor>"
>[  3586.345] (==) No monitor specified for screen "Default Screen Section".
>	Using a default monitor configuration.
>[  3586.346] (==) Automatically adding devices
>[  3586.346] (==) Automatically enabling devices
>[  3586.346] (==) Automatically adding GPU devices
>[  3586.347] (==) FontPath set to:
>	/usr/share/fonts/misc/,
>	/usr/share/fonts/TTF/,
>	/usr/share/fonts/OTF/,
>	/usr/share/fonts/Type1/,
>	/usr/share/fonts/100dpi/,
>	/usr/share/fonts/75dpi/
>[  3586.347] (==) ModulePath set to "/usr/lib64/xorg/modules"
>[  3586.347] (II) The server relies on udev to provide the list of input devices.
>	If no devices become available, reconfigure udev or disable AutoAddDevices.
>[  3586.347] (II) Loader magic: 0x807c40
>[  3586.347] (II) Module ABI versions:
>[  3586.347] 	X.Org ANSI C Emulation: 0.4
>[  3586.347] 	X.Org Video Driver: 15.0
>[  3586.347] 	X.Org XInput driver : 20.0
>[  3586.347] 	X.Org Server Extension : 8.0
>[  3586.348] (II) xfree86: Adding drm device (/dev/dri/card0)
>[  3586.351] (--) PCI:*(0:0:2:0) 8086:2a42:1043:1862 rev 7, Mem @ 0xfe400000/4194304, 0xd0000000/268435456, I/O @ 0x0000dc00/8
>[  3586.351] (--) PCI: (0:0:2:1) 8086:2a43:1043:1862 rev 7, Mem @ 0xfe800000/1048576
>[  3586.351] Initializing built-in extension Generic Event Extension
>[  3586.351] Initializing built-in extension SHAPE
>[  3586.351] Initializing built-in extension MIT-SHM
>[  3586.351] Initializing built-in extension XInputExtension
>[  3586.351] Initializing built-in extension XTEST
>[  3586.351] Initializing built-in extension BIG-REQUESTS
>[  3586.351] Initializing built-in extension SYNC
>[  3586.351] Initializing built-in extension XKEYBOARD
>[  3586.354] Initializing built-in extension XC-MISC
>[  3586.354] Initializing built-in extension XINERAMA
>[  3586.354] Initializing built-in extension XFIXES
>[  3586.354] Initializing built-in extension RENDER
>[  3586.354] Initializing built-in extension RANDR
>[  3586.354] Initializing built-in extension COMPOSITE
>[  3586.354] Initializing built-in extension DAMAGE
>[  3586.354] Initializing built-in extension MIT-SCREEN-SAVER
>[  3586.354] Initializing built-in extension DOUBLE-BUFFER
>[  3586.354] Initializing built-in extension RECORD
>[  3586.354] Initializing built-in extension DPMS
>[  3586.354] Initializing built-in extension Present
>[  3586.354] Initializing built-in extension DRI3
>[  3586.354] Initializing built-in extension X-Resource
>[  3586.354] Initializing built-in extension XVideo
>[  3586.354] Initializing built-in extension XVideo-MotionCompensation
>[  3586.354] Initializing built-in extension XFree86-VidModeExtension
>[  3586.354] Initializing built-in extension XFree86-DGA
>[  3586.354] Initializing built-in extension XFree86-DRI
>[  3586.354] Initializing built-in extension DRI2
>[  3586.354] (II) LoadModule: "glx"
>[  3586.359] (II) Loading /usr/lib64/xorg/modules/extensions/libglx.so
>[  3586.364] (II) Module glx: vendor="X.Org Foundation"
>[  3586.364] 	compiled for 1.15.0, module version = 1.0.0
>[  3586.364] 	ABI class: X.Org Server Extension, version 8.0
>[  3586.364] (==) AIGLX enabled
>[  3586.364] Loading extension GLX
>[  3586.364] (==) Matched intel as autoconfigured driver 0
>[  3586.364] (==) Matched intel as autoconfigured driver 1
>[  3586.364] (==) Matched modesetting as autoconfigured driver 2
>[  3586.364] (==) Matched fbdev as autoconfigured driver 3
>[  3586.364] (==) Matched vesa as autoconfigured driver 4
>[  3586.364] (==) Assigned the driver to the xf86ConfigLayout
>[  3586.364] (II) LoadModule: "intel"
>[  3586.365] (II) Loading /usr/lib64/xorg/modules/drivers/intel_drv.so
>[  3586.365] (II) Module intel: vendor="X.Org Foundation"
>[  3586.365] 	compiled for 1.15.0, module version = 2.99.907
>[  3586.365] 	Module class: X.Org Video Driver
>[  3586.365] 	ABI class: X.Org Video Driver, version 15.0
>[  3586.365] (II) LoadModule: "modesetting"
>[  3586.368] (WW) Warning, couldn't open module modesetting
>[  3586.368] (II) UnloadModule: "modesetting"
>[  3586.368] (II) Unloading modesetting
>[  3586.368] (EE) Failed to load module "modesetting" (module does not exist, 0)
>[  3586.368] (II) LoadModule: "fbdev"
>[  3586.368] (WW) Warning, couldn't open module fbdev
>[  3586.368] (II) UnloadModule: "fbdev"
>[  3586.368] (II) Unloading fbdev
>[  3586.371] (EE) Failed to load module "fbdev" (module does not exist, 0)
>[  3586.371] (II) LoadModule: "vesa"
>[  3586.371] (WW) Warning, couldn't open module vesa
>[  3586.371] (II) UnloadModule: "vesa"
>[  3586.371] (II) Unloading vesa
>[  3586.371] (EE) Failed to load module "vesa" (module does not exist, 0)
>[  3586.371] (II) intel: Driver for Intel(R) Integrated Graphics Chipsets:
>	i810, i810-dc100, i810e, i815, i830M, 845G, 854, 852GM/855GM, 865G,
>	915G, E7221 (i915), 915GM, 945G, 945GM, 945GME, Pineview GM,
>	Pineview G, 965G, G35, 965Q, 946GZ, 965GM, 965GME/GLE, G33, Q35, Q33,
>	GM45, 4 Series, G45/G43, Q45/Q43, G41, B43
>[  3586.372] (II) intel: Driver for Intel(R) HD Graphics: 2000-5000
>[  3586.372] (II) intel: Driver for Intel(R) Iris(TM) Graphics: 5100
>[  3586.372] (II) intel: Driver for Intel(R) Iris(TM) Pro Graphics: 5200
>[  3586.372] (++) using VT number 7
>
>[  3586.382] sna_init_scrn: entity_num=0
>[  3586.382] (II) intel(0): SNA compiled from 2.99.907-31-gde0797e
>[  3586.382] (II) intel(0): SNA compiled with assertions enabled
>[  3586.382] (II) intel(0): SNA compiled with memory allocation reporting enabled
>[  3586.383] (II) intel(0): SNA compiled with extra pixmap/damage validation
>[  3586.383] pixman version: 0.32.4
>[  3586.383] num_cores: processors=0x00000001, cores=0x00000003
>[  3586.383] sna_threads_init: creating a thread pool of 2 threads
>[  3586.383] sna_pre_init flags=0, numEntities=1
>[  3586.384] sna_acpi_open
>[  3586.384] (--) intel(0): Integrated Graphics Chipset: Intel(R) GM45
>[  3586.384] (--) intel(0): CPU: x86-64, sse2, sse3, ssse3
>[  3586.384] (II) intel(0): Creating default Display subsection in Screen section
>	"Default Screen Section" for depth/fbbpp 24/32
>[  3586.385] (==) intel(0): Depth 24, (--) framebuffer bpp 32
>[  3586.385] (==) intel(0): RGB weight 888
>[  3586.385] (==) intel(0): Default visual is TrueColor
>[  3586.385] kgem_init: fd=9, gen=37
>[  3586.385] kgem_init: has BLT ring? 0
>[  3586.385] kgem_init: has relaxed delta? 1
>[  3586.385] kgem_init: has relaxed fencing? 1
>[  3586.385] kgem_init: has shared last-level-cache? 0
>[  3586.385] kgem_init: has write-through caching for scanouts? 0
>[  3586.385] kgem_init: has set-cache-level? 1
>[  3586.385] kgem_init: has userptr? 0
>[  3586.385] kgem_init: has create2? 0
>[  3586.385] kgem_init: has no-reloc? 1
>[  3586.385] kgem_init: has handle-lut? 1
>[  3586.385] kgem_init: semaphores enabled? 0
>[  3586.385] kgem_init: can blt to cpu? 1
>[  3586.385] kgem_init: can render to Y-tiled surfaces? 0
>[  3586.385] kgem_init: can use privileged batchbuffers? 1
>[  3586.385] kgem_init: can use pinned batchbuffers (to avoid CS w/a)? 1
>[  3586.385] kgem_init_pinned_batches: new handle=1, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=2, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=3, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=4, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=5, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=6, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=7, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=8, num_pages=1
>[  3586.385] kgem_init_pinned_batches: new handle=9, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=10, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=11, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=12, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=13, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=14, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=15, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=16, num_pages=1
>[  3586.386] kgem_init_pinned_batches: new handle=17, num_pages=4
>[  3586.386] kgem_init_pinned_batches: new handle=18, num_pages=4
>[  3586.386] kgem_init_pinned_batches: new handle=19, num_pages=4
>[  3586.386] kgem_init_pinned_batches: new handle=20, num_pages=4
>[  3586.386] kgem_init: maximum batch size? 65528
>[  3586.386] kgem_init: last-level cache size: 1048576 bytes, threshold in pages: 128
>[  3586.386] kgem_init: cpu bo enabled 1: llc? 0, set-cache-level? 1, userptr? 0
>[  3586.386] kgem_init: aperture size 2147483648, available now 2142855168
>[  3586.386] kgem_init: aperture low=715827882 [682], high=1610612736 [1536]
>[  3586.386] kgem_init: aperture mappable=268435456 [256 MiB]
>[  3586.386] kgem_init: buffer size=262144 [256 KiB]
>[  3586.386] kgem_init: total ram=4081745920
>[  3586.386] kgem_init: maximum object size=1207959552
>[  3586.386] kgem_init: large object thresold=134217728
>[  3586.386] kgem_init: max object sizes (gpu=268435456, cpu=1207959552, tile upload=67108864, copy=134217728)
>[  3586.386] kgem_init: max fences=14
>[  3586.386] sna_pre_init: swapbuffer wait? enabled
>[  3586.386] sna_pre_init: vsync? enabled
>[  3586.386] sna_pre_init: page flips? enabled
>[  3586.386] sna_pre_init: triple buffer? enabled
>[  3586.388] (**) intel(0): Framebuffer tiled
>[  3586.388] (**) intel(0): Pixmaps tiled
>[  3586.388] (**) intel(0): "Tear free" disabled
>[  3586.388] (**) intel(0): Forcing per-crtc-pixmaps? no
>[  3586.388] sna_crtc_init
>[  3586.388] sna_crtc_init: created handle=21 for cursor on CRTC:3
>[  3586.388] sna_crtc_init: attached crtc[0] id=3, pipe=0
>[  3586.388] sna_crtc_init
>[  3586.388] sna_crtc_init: created handle=22 for cursor on CRTC:4
>[  3586.388] sna_crtc_init: attached crtc[1] id=4, pipe=1
>[  3586.388] sna_output_init(num=0)
>[  3586.388] (II) intel(0): Output LVDS1 has no monitor section
>[  3586.388] has_device_backlight: scanning /sys/bus/pci/devices/0000:00:02.0/backlight
>[  3586.388] has_device_backlight: acpi_video0
>[  3586.388] sna_output_backlight_get: level=2 (max=15)
>[  3586.388] (--) intel(0): found backlight control interface acpi_video0 (type 'firmware')
>[  3586.388] sna_output_init: created output 'LVDS1' 0 [5]  (possible crtc:3, possible clones:1), edid=0, dpms=2, crtc=3
>[  3586.388] sna_output_init(num=1)
>[  3586.390] (II) intel(0): Output VGA1 has no monitor section
>[  3586.390] sna_output_init: created output 'VGA1' 1 [11]  (possible crtc:7, possible clones:2), edid=0, dpms=2, crtc=0
>[  3586.390] sna_output_init(num=2)
>[  3586.390] (II) intel(0): Output HDMI1 has no monitor section
>[  3586.390] sna_output_init: created output 'HDMI1' 2 [14]  (possible crtc:7, possible clones:4), edid=0, dpms=2, crtc=0
>[  3586.390] sna_output_init(num=3)
>[  3586.390] (II) intel(0): Output DP1 has no monitor section
>[  3586.390] sna_output_init: created output 'DP1' 3 [18]  (possible crtc:7, possible clones:8), edid=0, dpms=2, crtc=0
>[  3586.390] sna_output_init(num=4)
>[  3586.390] (II) intel(0): Output DP2 has no monitor section
>[  3586.390] sna_output_init: created output 'DP2' 4 [20]  (possible crtc:7, possible clones:10), edid=0, dpms=2, crtc=0
>[  3586.390] sna_mode_compute_possible_outputs: updated output 'LVDS1' 0 [5] (possible crtc:3, possible clones:1)
>[  3586.390] sna_mode_compute_possible_outputs: updated output 'VGA1' 1 [11] (possible crtc:3, possible clones:2)
>[  3586.390] sna_mode_compute_possible_outputs: updated output 'HDMI1' 2 [14] (possible crtc:3, possible clones:4)
>[  3586.390] sna_mode_compute_possible_outputs: updated output 'DP1' 3 [18] (possible crtc:3, possible clones:8)
>[  3586.390] sna_mode_compute_possible_outputs: updated output 'DP2' 4 [20] (possible crtc:3, possible clones:10)
>[  3586.390] add_fake_output(late=0, num_fake=1)
>[  3586.390] (II) intel(0): Output VIRTUAL1 has no monitor section
>[  3586.390] crtc_init_gamma: CRTC:3, pipe=0: gamma set?=1
>[  3586.390] sna_probe_initial_configuration: CRTC:3, pipe=0: has mode?=1
>[  3586.390] kmode: 1366x768, clock=72000, 1366 1414 1446 1494 0, 768 771 777 803 0, flags=a, type=48
>[  3586.390] crtc_init_gamma: CRTC:4, pipe=1: gamma set?=0
>[  3586.390] sna_probe_initial_configuration: CRTC:4, pipe=1: has mode?=0
>[  3586.390] (--) intel(0): Output LVDS1 using initial mode 1366x768 on pipe 0
>[  3586.390] sna_probe_initial_configuration: SetScrnInfoModes = 0x25db140
>[  3586.390] (==) intel(0): DPI set to (96, 96)
>[  3586.390] (II) Loading sub module "dri2"
>[  3586.390] (II) LoadModule: "dri2"
>[  3586.390] (II) Module "dri2" already built-in
>[  3586.390] (==) Depth 24 pixmap format is 32 bpp
>[  3586.390] sna_screen_init
>[  3586.391] sna_accel_init
>[  3586.391] sna_picture_init
>[  3586.391] uploaded 85504 bytes of static state
>[  3586.391] kgem_create_linear(85504)
>[  3586.391] search_linear_cache: num_pages=21, flags=2, use_active? 0, use_large=0 [max=65536]
>[  3586.391] search_linear_cache: inactive and cache bucket empty
>[  3586.391] search_linear_cache: active cache bucket empty
>[  3586.391] kgem_create_linear: new handle=23, num_pages=21
>[  3586.391] gem_write(handle=23, offset=0, len=85504)
>[  3586.393] kgem_bo_write: flush=0, domain=1
>[  3586.393] __kgem_busy: handle=23, busy=0, wedged=0
>[  3586.393] kgem_bo_retire: retiring bo handle=23 (needed flush? 0), rq? 0 [busy?=0]
>[  3586.393] sna_accel_init(backend=Eaglelake (gen4.5), prefer_gpu=3)
>[  3586.393] (II) intel(0): SNA initialized with Eaglelake (gen4.5) backend
>[  3586.393] (==) intel(0): Backing store enabled
>[  3586.393] (==) intel(0): Silken mouse enabled
>[  3586.393] (II) intel(0): HW Cursor enabled
>[  3586.393] (II) intel(0): RandR 1.2 enabled, ignore the following RandR disabled message.
>[  3586.393] (==) intel(0): DPMS enabled
>[  3586.393] sna_video_sprite_setup: 0 sprite planes
>[  3586.393] (II) intel(0): [XvMC] xvmc_vld driver initialized.
>[  3586.393] sna_dri_open()
>[  3586.393] sna_dri_open: loading dri driver 'i965' [gen=37] for device '/dev/dri/card0'
>[  3586.394] (II) intel(0): [DRI2] Setup complete
>[  3586.394] (II) intel(0): [DRI2]   DRI driver: i965
>[  3586.394] (II) intel(0): [DRI2]   VDPAU driver: i965
>[  3586.394] (II) intel(0): direct rendering: DRI2 Enabled
>[  3586.394] sna_uevent_init
>[  3586.394] (==) intel(0): hotplug detection: "enabled"
>[  3586.394] sna_uevent_init: installed uvent handler
>[  3586.394] (--) RandR disabled
>[  3586.440] (II) AIGLX: enabled GLX_MESA_copy_sub_buffer
>[  3586.440] (II) AIGLX: enabled GLX_ARB_create_context
>[  3586.440] (II) AIGLX: enabled GLX_ARB_create_context_profile
>[  3586.440] (II) AIGLX: enabled GLX_EXT_create_context_es2_profile
>[  3586.440] (II) AIGLX: enabled GLX_INTEL_swap_event
>[  3586.440] (II) AIGLX: enabled GLX_SGI_swap_control and GLX_MESA_swap_control
>[  3586.440] (II) AIGLX: enabled GLX_EXT_framebuffer_sRGB
>[  3586.440] (II) AIGLX: enabled GLX_ARB_fbconfig_float
>[  3586.440] (II) AIGLX: GLX_EXT_texture_from_pixmap backed by buffer objects
>[  3586.440] (II) AIGLX: Loaded and initialized i965
>[  3586.440] (II) GLX: Initialized DRI2 GL provider for screen 0
>[  3586.441] sna_create_screen_resources(1366x768@24)
>[  3586.441] sna_accel_create
>[  3586.441] sna_glyphs_create
>[  3586.441] sna_create_pixmap(1024, 1024, 8, usage=11)
>[  3586.441] kgem_can_create_2d: 1024x1024 @ 8
>[  3586.441] kgem_can_create_2d: untiled size=1048576
>[  3586.441] kgem_choose_tiling: 1024x1024 -> 1
>[  3586.442] kgem_can_create_2d: tiled[1] size=1048576
>[  3586.443] sna_create_pixmap: usage=17, flags=b
>[  3586.443] sna_pixmap_create_scratch(1024, 1024, 8, tiling=2)
>[  3586.443] kgem_choose_tiling: 1024x1024 -> 1
>[  3586.443] create_pixmap: allocating pixmap 0x0, depth=8, size=0
>[  3586.443] create_pixmap: serial=1, usage=1, 0x0
>[  3586.443] kgem_create_2d(1024x1024, bpp=8, tiling=1, exact=0, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
>[  3586.443]   new pitch=1024, tiling=1, handle=24, id=1, num_pages=256 [256], bucket=8
>[  3586.443] __sna_damage_all(1024, 1024)
>[  3586.443] sna_create_pixmap(1024, 1024, 32, usage=11)
>[  3586.443] kgem_can_create_2d: 1024x1024 @ 32
>[  3586.443] kgem_can_create_2d: untiled size=4194304
>[  3586.443] kgem_choose_tiling: TLB miss between lines 1024x1024 (pitch=4096), forcing tiling 1
>[  3586.443] kgem_can_create_2d: tiled[-1] size=4194304
>[  3586.443] sna_create_pixmap: usage=17, flags=b
>[  3586.443] sna_pixmap_create_scratch(1024, 1024, 32, tiling=2)
>[  3586.443] kgem_choose_tiling: TLB miss between lines 1024x1024 (pitch=4096), forcing tiling 1
>[  3586.443] create_pixmap: allocating pixmap 0x0, depth=32, size=0
>[  3586.443] create_pixmap: serial=2, usage=1, 0x0
>[  3586.443] kgem_create_2d(1024x1024, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
>[  3586.443]   new pitch=4096, tiling=1, handle=25, id=2, num_pages=1024 [1024], bucket=10
>[  3586.443] __sna_damage_all(1024, 1024)
>[  3586.443] sna_gradients_create
>[  3586.443] sna_alpha_cache_init
>[  3586.443] kgem_create_linear(1052)
>[  3586.443] search_linear_cache: num_pages=1, flags=2, use_active? 0, use_large=0 [max=65536]
>[  3586.443] search_linear_cache: inactive and cache bucket empty
>[  3586.443] search_linear_cache: active cache bucket empty
>[  3586.443] kgem_create_linear: new handle=26, num_pages=1
>[  3586.443] kgem_create_proxy: target handle=26 [proxy? -1], offset=0, length=4, io=0
>[  3586.443] kgem_create_proxy: target handle=26 [proxy? -1], offset=4, length=4, io=0
>[  3586.443] kgem_create_proxy: target handle=26 [proxy? -1], offset=8, length=4, io=0
>[  3586.443] kgem_create_proxy: target handle=26 [proxy? -1], offset=12, length=4, io=0
>[  3586.443] kgem_create_proxy: target handle=26 [proxy? -1], offset=16, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=20, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=24, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=28, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=32, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=36, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=40, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=44, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=48, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=52, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=56, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=60, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=64, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=68, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=72, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=76, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=80, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=84, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=88, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=92, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=96, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=100, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=104, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=108, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=112, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=116, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=120, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=124, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=128, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=132, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=136, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=140, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=144, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=148, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=152, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=156, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=160, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=164, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=168, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=172, length=4, io=0
>[  3586.445] kgem_create_proxy: target handle=26 [proxy? -1], offset=176, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=180, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=184, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=188, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=192, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=196, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=200, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=204, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=208, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=212, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=216, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=220, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=224, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=228, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=232, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=236, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=240, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=244, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=248, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=252, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=256, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=260, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=264, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=268, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=272, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=276, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=280, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=284, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=288, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=292, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=296, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=300, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=304, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=308, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=312, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=316, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=320, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=324, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=328, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=332, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=336, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=340, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=344, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=348, length=4, io=0
>[  3586.446] kgem_create_proxy: target handle=26 [proxy? -1], offset=352, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=356, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=360, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=364, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=368, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=372, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=376, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=380, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=384, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=388, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=392, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=396, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=400, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=404, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=408, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=412, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=416, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=420, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=424, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=428, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=432, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=436, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=440, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=444, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=448, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=452, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=456, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=460, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=464, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=468, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=472, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=476, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=480, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=484, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=488, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=492, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=496, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=500, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=504, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=508, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=512, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=516, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=520, length=4, io=0
>[  3586.447] kgem_create_proxy: target handle=26 [proxy? -1], offset=524, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=528, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=532, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=536, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=540, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=544, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=548, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=552, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=556, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=560, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=564, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=568, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=572, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=576, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=580, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=584, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=588, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=592, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=596, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=600, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=604, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=608, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=612, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=616, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=620, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=624, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=628, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=632, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=636, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=640, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=644, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=648, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=652, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=656, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=660, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=664, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=668, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=672, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=676, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=680, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=684, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=688, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=692, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=696, length=4, io=0
>[  3586.448] kgem_create_proxy: target handle=26 [proxy? -1], offset=700, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=704, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=708, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=712, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=716, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=720, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=724, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=728, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=732, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=736, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=740, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=744, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=748, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=752, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=756, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=760, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=764, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=768, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=772, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=776, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=780, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=784, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=788, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=792, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=796, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=800, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=804, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=808, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=812, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=816, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=820, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=824, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=828, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=832, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=836, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=840, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=844, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=848, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=852, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=856, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=860, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=864, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=868, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=872, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=876, length=4, io=0
>[  3586.449] kgem_create_proxy: target handle=26 [proxy? -1], offset=880, length=4, io=0
>[  3586.450] kgem_create_proxy: target handle=26 [proxy? -1], offset=884, length=4, io=0
>[  3586.450] kgem_create_proxy: target handle=26 [proxy? -1], offset=888, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=892, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=896, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=900, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=904, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=908, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=912, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=916, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=920, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=924, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=928, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=932, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=936, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=940, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=944, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=948, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=952, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=956, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=960, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=964, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=968, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=972, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=976, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=980, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=984, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=988, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=992, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=996, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1000, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1004, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1008, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1012, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1016, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1020, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1024, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1028, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1032, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1036, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1040, length=4, io=0
>[  3586.454] kgem_create_proxy: target handle=26 [proxy? -1], offset=1044, length=4, io=0
>[  3586.455] kgem_create_proxy: target handle=26 [proxy? -1], offset=1048, length=4, io=0
>[  3586.455] gem_write(handle=26, offset=0, len=1052)
>[  3586.455] kgem_bo_write: flush=0, domain=1
>[  3586.455] __kgem_busy: handle=26, busy=0, wedged=0
>[  3586.455] kgem_bo_retire: retiring bo handle=26 (needed flush? 0), rq? 0 [busy?=0]
>[  3586.455] sna_solid_cache_init
>[  3586.455] kgem_create_linear(4096)
>[  3586.455] search_linear_cache: num_pages=1, flags=2, use_active? 0, use_large=0 [max=65536]
>[  3586.455] search_linear_cache: inactive and cache bucket empty
>[  3586.455] search_linear_cache: active cache bucket empty
>[  3586.455] kgem_create_linear: new handle=27, num_pages=1
>[  3586.455] sna_create_pixmap(1366, 768, 24, usage=10)
>[  3586.455] kgem_can_create_2d: 1366x768 @ 24
>[  3586.455] kgem_can_create_2d: untiled size=4198400
>[  3586.455] kgem_choose_tiling: TLB miss between lines 1366x768 (pitch=5464), forcing tiling 1
>[  3586.455] kgem_can_create_2d: tiled[-1] size=4198400
>[  3586.455] sna_create_pixmap: usage=16, flags=b
>[  3586.455] sna_create_pixmap: creating GPU pixmap 1366x768, stride=5464, flags=b
>[  3586.455] create_pixmap: allocating pixmap 0x0, depth=24, size=0
>[  3586.455] create_pixmap: serial=3, usage=16, 0x0
>[  3586.455] sna_pixmap_force_to_gpu(pixmap=0x260da30, flags=1)
>[  3586.455] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=41
>[  3586.455] sna_pixmap_move_to_gpu: CPU damage? 0
>[  3586.455] sna_pixmap_move_to_gpu: creating GPU bo (1366x768@32), create=b
>[  3586.455] kgem_create_2d(1366x768, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=1, prime?=0, temp?=0)
>[  3586.455]   new pitch=5632, tiling=1, handle=28, id=266, num_pages=1056 [1056], bucket=10
>[  3586.455] __sna_damage_all(1366, 768)
>[  3586.455] sna_pixmap_move_to_gpu: marking as all-damaged for GPU
>[  3586.455] sna_pixmap_mark_active: pixmap=3, handle=28
>[  3586.455] sna_set_screen_pixmap: changing from pixmap=0 to pixmap=3, (sna->front=0)
>[  3586.455] sna_copy_fbcon
>[  3586.455] sna_copy_fbcon: found fbcon, size=1366x768, depth=24, bpp=32
>[  3586.455] kgem_create_for_name(name=1)
>[  3586.455] kgem_create_for_name: new handle=30
>[  3586.455] sna_copy_fbcon: fbcon handle=30
>[  3586.455] gen4_render_copy_boxes x 1
>[  3586.455] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(0, 1), pitch=(5504, 5632)
>[  3586.456]   sna_blt_copy_boxes: box=(0, 0)x(1366, 768)
>[  3586.456] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
>[  3586.456] kgem_add_handle: handle=28, index=0
>[  3586.456] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
>[  3586.456] kgem_add_reloc: handle=30, pos=7, delta=0, domains=00028000
>[  3586.456] kgem_add_handle: handle=30, index=1
>[  3586.456] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2088 [fenced=0]
>[  3586.456] gem_write__cachealigned(handle=16, offset=0, len=40)
>[  3586.469] kgem_commit: release handle=16 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=45d000
>[  3586.469] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=46e000
>[  3586.469] kgem_commit: release handle=30 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=46000
>[  3586.469] _kgem_bo_destroy: handle=30, proxy? 0
>[  3586.469] __kgem_bo_destroy: handle=30
>[  3586.469] __kgem_bo_destroy: handle=30, not reusable
>[  3586.469] kgem_bo_free: handle=30
>[  3586.469] kgem_bo_free: releasing (nil):(nil) vma for handle=30, count=0
>[  3586.469] sna_set_desired_mode
>[  3586.469] (II) intel(0): switch to mode 1366x768@60.0 on LVDS1 using pipe 0, position (0, 0), rotation normal, reflection none
>[  3586.469] sna_pixmap_force_to_gpu(pixmap=0x260da30, flags=3)
>[  3586.469] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
>[  3586.469] sna_pixmap_move_to_gpu: already all-damaged
>[  3586.469] sna_pixmap_mark_active: pixmap=3, handle=28
>[  3586.469] use_shadow? bounds (0, 0), (1366, 768), framebufer 1366x768
>[  3586.469] sna_crtc_attach: attaching to framebuffer
>[  3586.469] sna_pixmap_force_to_gpu(pixmap=0x260da30, flags=3)
>[  3586.469] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
>[  3586.469] sna_pixmap_move_to_gpu: already all-damaged
>[  3586.469] sna_pixmap_mark_active: pixmap=3, handle=28
>[  3586.469] get_fb: create fb 1366x768@24/32
>[  3586.469] sna_crtc_apply
>[  3586.469] sna_crtc_apply: attaching output 'LVDS1' 0 [5] to crtc:3 (pipe 0) (possible crtc:3, possible clones:1)
>[  3586.469] sna_crtc_apply: applying crtc [3, pipe=0] mode=1366x768+0+0@72000, fb=23 update to 1 outputs [5...]
>[  3586.474] sna_crtc_force_outputs_on(pipe=0), currently? 3
>[  3586.474] sna_output_dpms(LVDS1): dpms=0 (current: 3), active? 1
>[  3586.474] sna_output_dpms_backlight(LVDS1) -- 3 -> 0
>[  3586.474] sna_output_backlight_set: level=2, max=15
>[  3586.478] sna_crtc_randr: transform? 0, bounds (0, 0), (1366, 768)
>[  3586.478] sna_output_dpms(VGA1): dpms=3 (current: 3), active? 0
>[  3586.478] sna_output_dpms(HDMI1): dpms=3 (current: 3), active? 0
>[  3586.478] sna_output_dpms(DP1): dpms=3 (current: 3), active? 0
>[  3586.478] sna_output_dpms(DP2): dpms=3 (current: 3), active? 0
>[  3586.478] sna_crtc_dpms(pipe 1, dpms mode -> 3):= active=0
>[  3586.478] sna_crtc_config_notify
>[  3586.478] sna_mode_update: crtc=3, valid?=1, fb attached?=23, expected=23
>[  3586.478] sna_mode_update: crtc=4, valid?=0, fb attached?=0, expected=0
>[  3586.478] update_flush_interval: CRTC:0 (pipe 0) vrefresh=60.015904
>[  3586.478] update_flush_interval: CRTC:1 (pipe 1) disabled
>[  3586.478] max_vrefresh=60, vblank_interval=16 ms
>[  3586.478] sna_mode_update: crtc=3, valid?=1, fb attached?=23, expected=23
>[  3586.478] sna_mode_update: crtc=4, valid?=0, fb attached?=0, expected=0
>[  3586.478] update_flush_interval: CRTC:0 (pipe 0) vrefresh=60.015904
>[  3586.478] update_flush_interval: CRTC:1 (pipe 1) disabled
>[  3586.478] max_vrefresh=60, vblank_interval=16 ms
>[  3586.478] (II) intel(0): Setting screen physical size to 361 x 203
>[  3586.478] sna_mode_resize (1366, 768) -> (1366, 768)
>[  3586.478] sna_create_pixmap(16, 16, 1, usage=0)
>[  3586.478] kgem_can_create_2d: 16x16 @ 1
>[  3586.478] kgem_can_create_2d: unhandled depth 1
>[  3586.478] sna_create_pixmap: can not use GPU, just creating shadow
>[  3586.478] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  3586.478] create_pixmap: serial=4, usage=0, 16x16
>[  3586.478] sna_validate_gc changes=7fffff
>[  3586.478] sna_validate_gc: recomputing clip
>[  3586.478] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.478] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  3586.478] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  3586.478] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  3586.478] sna_poly_fill_rect: fallback -- unattached
>[  3586.478] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  3586.478] sna_gc_move_to_cpu, changes=7fffff
>[  3586.478] sfbValidateGC changes=77ffff
>[  3586.478] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  3586.479] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  3586.479] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  3586.479] sna_drawable_move_region_to_cpu(pixmap=4 (16x16), [(0, 0), (16, 16)], flags=5)
>[  3586.479] sna_drawable_move_region_to_cpu: not attached to 0x260e500
>[  3586.479] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  3586.479] sfbPolyFillRect x 1
>[  3586.479] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  3586.479] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.479] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.479] fbFillBox box=(0, 0), (16, 16)
>[  3586.479] sfbFill (0, 0)x(16, 16), style=0
>[  3586.656] sna_realize_font (key=0)
>[  3586.657] sna_realize_font (key=0)
>[  3586.657] sna_create_pixmap(16, 16, 1, usage=1)
>[  3586.657] kgem_can_create_2d: 16x16 @ 1
>[  3586.657] kgem_can_create_2d: unhandled depth 1
>[  3586.657] sna_create_pixmap: can not use GPU, just creating shadow
>[  3586.657] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  3586.657] create_pixmap: serial=6, usage=1, 16x16
>[  3586.657] sna_validate_gc changes=7fffff
>[  3586.657] sna_validate_gc: recomputing clip
>[  3586.657] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.657] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  3586.657] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  3586.657] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  3586.657] sna_poly_fill_rect: fallback -- unattached
>[  3586.657] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  3586.657] sna_gc_move_to_cpu, changes=7fffff
>[  3586.657] sfbValidateGC changes=77ffff
>[  3586.657] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3586.657] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  3586.657] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  3586.657] sna_drawable_move_region_to_cpu(pixmap=6 (16x16), [(0, 0), (16, 16)], flags=5)
>[  3586.657] sna_drawable_move_region_to_cpu: not attached to 0x280a1f0
>[  3586.657] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  3586.657] sfbPolyFillRect x 1
>[  3586.657] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  3586.658] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.658] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.658] fbFillBox box=(0, 0), (16, 16)
>[  3586.658] sfbFill (0, 0)x(16, 16), style=0
>[  3586.658] sna_validate_gc changes=4
>[  3586.658] sna_validate_gc: recomputing clip
>[  3586.658] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.658] sna_font_too_large? (16 + 17) x 17: 99 > 124
>[  3586.658] sna_glyph_blt (7, 7) x 1, fg=00000001, bg=ffffffff alu=cc
>[  3586.658] sna_drawable_use_bo pixmap=6, box=((0, 0), (16, 16)), flags=1...
>[  3586.658] sna_drawable_use_bo: not attached
>[  3586.658] sna_poly_text16: fallback
>[  3586.658] sna_gc_move_to_cpu, changes=4
>[  3586.658] sfbValidateGC changes=4
>[  3586.658] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  3586.658] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  3586.658] sna_drawable_move_region_to_cpu(pixmap=6 (16x16), [(0, 0), (16, 16)], flags=3)
>[  3586.658] sna_drawable_move_region_to_cpu: not attached to 0x280a1f0
>[  3586.658] sna_poly_text16: fallback -- fbPolyGlyphBlt
>[  3586.658] sfbPolyGlyphBlt x 1
>[  3586.658] sfbPushImage (0, 0)x(16, 16)fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.658] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.658] sfbBlt 16x16, alu=7, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.658] sna_get_image: pixmap=6 (0, 0)x(16, 16), format=1, mask=1, depth=1
>[  3586.658] sna_drawable_move_region_to_cpu(pixmap=6 (16x16), [(0, 0), (16, 16)], flags=34)
>[  3586.658] sna_drawable_move_region_to_cpu: not attached to 0x280a1f0
>[  3586.658] sfbGetImage (0, 0)x(16, 16)
>[  3586.658] sfbBlt 16x16, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.658] sfbBlt fast blt, src_stride=4, dst_stride=4, width=2 (offset=2075184)
>[  3586.658] sna_destroy_pixmap: pixmap=6, attached?=0
>[  3586.658] sna_create_pixmap(16, 16, 1, usage=1)
>[  3586.658] kgem_can_create_2d: 16x16 @ 1
>[  3586.658] kgem_can_create_2d: unhandled depth 1
>[  3586.658] sna_create_pixmap: can not use GPU, just creating shadow
>[  3586.658] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  3586.658] create_pixmap: serial=7, usage=1, 16x16
>[  3586.658] sna_validate_gc changes=7fffff
>[  3586.658] sna_validate_gc: recomputing clip
>[  3586.658] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.658] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  3586.659] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  3586.659] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  3586.659] sna_poly_fill_rect: fallback -- unattached
>[  3586.659] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  3586.659] sna_gc_move_to_cpu, changes=7fffff
>[  3586.659] sfbValidateGC changes=77ffff
>[  3586.659] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3586.659] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  3586.659] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  3586.659] sna_drawable_move_region_to_cpu(pixmap=7 (16x16), [(0, 0), (16, 16)], flags=5)
>[  3586.659] sna_drawable_move_region_to_cpu: not attached to 0x280a1f0
>[  3586.659] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  3586.659] sfbPolyFillRect x 1
>[  3586.659] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  3586.659] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.659] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.659] fbFillBox box=(0, 0), (16, 16)
>[  3586.659] sfbFill (0, 0)x(16, 16), style=0
>[  3586.659] sna_validate_gc changes=4
>[  3586.659] sna_validate_gc: recomputing clip
>[  3586.659] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.659] sna_font_too_large? (16 + 17) x 17: 99 > 124
>[  3586.659] sna_glyph_blt (7, 7) x 1, fg=00000001, bg=ffffffff alu=cc
>[  3586.659] sna_drawable_use_bo pixmap=7, box=((1, 1), (15, 15)), flags=1...
>[  3586.659] sna_drawable_use_bo: not attached
>[  3586.659] sna_poly_text16: fallback
>[  3586.659] sna_gc_move_to_cpu, changes=4
>[  3586.659] sfbValidateGC changes=4
>[  3586.659] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  3586.659] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  3586.659] sna_drawable_move_region_to_cpu(pixmap=7 (16x16), [(1, 1), (15, 15)], flags=3)
>[  3586.659] sna_drawable_move_region_to_cpu: not attached to 0x280a1f0
>[  3586.659] sna_poly_text16: fallback -- fbPolyGlyphBlt
>[  3586.659] sfbPolyGlyphBlt x 1
>[  3586.659] sfbPushImage (1, 1)x(14, 14)fbClipBoxes: box=(1, 1),(15, 15); region=(1, 1),(15, 15) x 1
>[  3586.659] fbDrawableRun: box=(1, 1), (15, 15), clip=(1, 1), (15, 15)
>[  3586.659] sfbBlt 14x14, alu=7, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.659] sna_get_image: pixmap=7 (0, 0)x(16, 16), format=1, mask=1, depth=1
>[  3586.659] sna_drawable_move_region_to_cpu(pixmap=7 (16x16), [(0, 0), (16, 16)], flags=34)
>[  3586.659] sna_drawable_move_region_to_cpu: not attached to 0x280a1f0
>[  3586.659] sfbGetImage (0, 0)x(16, 16)
>[  3586.659] sfbBlt 16x16, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.659] sfbBlt fast blt, src_stride=4, dst_stride=4, width=2 (offset=752)
>[  3586.659] sna_destroy_pixmap: pixmap=7, attached?=0
>[  3586.660] sna_change_window_attributes
>[  3586.660] sna_validate_gc changes=7fffff
>[  3586.660] sna_validate_gc: recomputing clip
>[  3586.660] sna_validate_gc: composite clip=1x[(0, 0), (1366, 768)]
>[  3586.660] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  3586.660] sna_poly_fill_rect_extents: [0] = (0, 0)x(1366, 768)
>[  3586.660] sna_poly_fill_rect: extents(0, 0), (1366, 768), flags=1
>[  3586.660] discard_cpu_damage: discarding existing CPU damage
>[  3586.660] sna_poly_fill_rect: promoting to full GPU
>[  3586.660] sna_poly_fill_rect: dropping last-cpu hint
>[  3586.660] sna_drawable_use_bo pixmap=3, box=((0, 0), (1366, 768)), flags=19...
>[  3586.660] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=9
>[  3586.660] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[  3586.660] sna_drawable_use_bo: using whole GPU bo
>[  3586.660] sna_poly_fill_rect: solid fill [00000000], testing for blt
>[  3586.660] sna_poly_fill_rect_blt x 1 [(0, 0)x(1366, 768)...]+(0,0), clipped?=0
>[  3586.660] gen4_render_fill_one: color=00000000
>[  3586.660] sna_blt_fill_boxes (32, 00000000, 3) x 1
>[  3586.660] sna_blt_fill_box: box=((0, 0), (1366, 768))
>[  3586.660] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
>[  3586.660] kgem_add_handle: handle=28, index=0
>[  3586.660] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
>[  3586.660] sna_poly_fill_rect_blt: pixmap=3, marking clear [00000000]
>[  3586.744] (II) config/udev: Adding input device Power Button (/dev/input/event2)
>[  3586.745] (**) Power Button: Applying InputClass "evdev keyboard catchall"
>[  3586.745] (II) LoadModule: "evdev"
>[  3586.745] (II) Loading /usr/lib64/xorg/modules/input/evdev_drv.so
>[  3586.745] (II) Module evdev: vendor="X.Org Foundation"
>[  3586.745] 	compiled for 1.15.0, module version = 2.8.1
>[  3586.745] 	Module class: X.Org XInput Driver
>[  3586.745] 	ABI class: X.Org XInput driver, version 20.0
>[  3586.745] (II) Using input driver 'evdev' for 'Power Button'
>[  3586.745] (**) Power Button: always reports core events
>[  3586.745] (**) evdev: Power Button: Device: "/dev/input/event2"
>[  3586.745] (--) evdev: Power Button: Vendor 0 Product 0x1
>[  3586.745] (--) evdev: Power Button: Found keys
>[  3586.746] (II) evdev: Power Button: Configuring as keyboard
>[  3586.746] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/LNXPWRBN:00/input/input2/event2"
>[  3586.746] (II) XINPUT: Adding extended input device "Power Button" (type: KEYBOARD, id 6)
>[  3586.746] (**) Option "xkb_rules" "evdev"
>[  3586.746] (**) Option "xkb_model" "pc104"
>[  3586.746] (**) Option "xkb_layout" "us"
>[  3586.810] (II) config/udev: Adding input device Video Bus (/dev/input/event3)
>[  3586.810] (**) Video Bus: Applying InputClass "evdev keyboard catchall"
>[  3586.810] (II) Using input driver 'evdev' for 'Video Bus'
>[  3586.810] (**) Video Bus: always reports core events
>[  3586.810] (**) evdev: Video Bus: Device: "/dev/input/event3"
>[  3586.810] (--) evdev: Video Bus: Vendor 0 Product 0x6
>[  3586.810] (--) evdev: Video Bus: Found keys
>[  3586.810] (II) evdev: Video Bus: Configuring as keyboard
>[  3586.811] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/device:00/PNP0A08:00/LNXVIDEO:00/input/input3/event3"
>[  3586.811] (II) XINPUT: Adding extended input device "Video Bus" (type: KEYBOARD, id 7)
>[  3586.811] (**) Option "xkb_rules" "evdev"
>[  3586.811] (**) Option "xkb_model" "pc104"
>[  3586.811] (**) Option "xkb_layout" "us"
>[  3586.812] (II) config/udev: Adding input device Lid Switch (/dev/input/event1)
>[  3586.812] (II) No input driver specified, ignoring this device.
>[  3586.812] (II) This device may have been added with another device file.
>[  3586.813] (II) config/udev: Adding input device Sleep Button (/dev/input/event0)
>[  3586.813] (**) Sleep Button: Applying InputClass "evdev keyboard catchall"
>[  3586.813] (II) Using input driver 'evdev' for 'Sleep Button'
>[  3586.813] (**) Sleep Button: always reports core events
>[  3586.813] (**) evdev: Sleep Button: Device: "/dev/input/event0"
>[  3586.813] (--) evdev: Sleep Button: Vendor 0 Product 0x3
>[  3586.813] (--) evdev: Sleep Button: Found keys
>[  3586.813] (II) evdev: Sleep Button: Configuring as keyboard
>[  3586.813] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/device:00/PNP0C0E:00/input/input0/event0"
>[  3586.813] (II) XINPUT: Adding extended input device "Sleep Button" (type: KEYBOARD, id 8)
>[  3586.813] (**) Option "xkb_rules" "evdev"
>[  3586.813] (**) Option "xkb_model" "pc104"
>[  3586.813] (**) Option "xkb_layout" "us"
>[  3586.814] (II) config/udev: Adding drm device (/dev/dri/card0)
>[  3586.815] (II) config/udev: Adding input device Asus Laptop extra buttons (/dev/input/event4)
>[  3586.815] (**) Asus Laptop extra buttons: Applying InputClass "evdev keyboard catchall"
>[  3586.815] (II) Using input driver 'evdev' for 'Asus Laptop extra buttons'
>[  3586.815] (**) Asus Laptop extra buttons: always reports core events
>[  3586.815] (**) evdev: Asus Laptop extra buttons: Device: "/dev/input/event4"
>[  3586.815] (--) evdev: Asus Laptop extra buttons: Vendor 0 Product 0
>[  3586.815] (--) evdev: Asus Laptop extra buttons: Found keys
>[  3586.815] (II) evdev: Asus Laptop extra buttons: Configuring as keyboard
>[  3586.815] (**) Option "config_info" "udev:/sys/devices/platform/asus_laptop/input/input5/event4"
>[  3586.815] (II) XINPUT: Adding extended input device "Asus Laptop extra buttons" (type: KEYBOARD, id 9)
>[  3586.815] (**) Option "xkb_rules" "evdev"
>[  3586.815] (**) Option "xkb_model" "pc104"
>[  3586.816] (**) Option "xkb_layout" "us"
>[  3586.817] (II) config/udev: Adding input device AT Translated Set 2 keyboard (/dev/input/event5)
>[  3586.817] (**) AT Translated Set 2 keyboard: Applying InputClass "evdev keyboard catchall"
>[  3586.817] (II) Using input driver 'evdev' for 'AT Translated Set 2 keyboard'
>[  3586.817] (**) AT Translated Set 2 keyboard: always reports core events
>[  3586.817] (**) evdev: AT Translated Set 2 keyboard: Device: "/dev/input/event5"
>[  3586.817] (--) evdev: AT Translated Set 2 keyboard: Vendor 0x1 Product 0x1
>[  3586.817] (--) evdev: AT Translated Set 2 keyboard: Found keys
>[  3586.817] (II) evdev: AT Translated Set 2 keyboard: Configuring as keyboard
>[  3586.817] (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio0/input/input4/event5"
>[  3586.817] (II) XINPUT: Adding extended input device "AT Translated Set 2 keyboard" (type: KEYBOARD, id 10)
>[  3586.817] (**) Option "xkb_rules" "evdev"
>[  3586.817] (**) Option "xkb_model" "pc104"
>[  3586.817] (**) Option "xkb_layout" "us"
>[  3586.818] (II) config/udev: Adding input device ETPS/2 Elantech Touchpad (/dev/input/event6)
>[  3586.818] (**) ETPS/2 Elantech Touchpad: Applying InputClass "evdev touchpad catchall"
>[  3586.818] (**) ETPS/2 Elantech Touchpad: Applying InputClass "touchpad catchall"
>[  3586.818] (**) ETPS/2 Elantech Touchpad: Applying InputClass "Default clickpad buttons"
>[  3586.819] (II) LoadModule: "synaptics"
>[  3586.819] (II) Loading /usr/lib64/xorg/modules/input/synaptics_drv.so
>[  3586.819] (II) Module synaptics: vendor="X.Org Foundation"
>[  3586.819] 	compiled for 1.15.0, module version = 1.7.1
>[  3586.819] 	Module class: X.Org XInput Driver
>[  3586.819] 	ABI class: X.Org XInput driver, version 20.0
>[  3586.819] (II) Using input driver 'synaptics' for 'ETPS/2 Elantech Touchpad'
>[  3586.819] (**) ETPS/2 Elantech Touchpad: always reports core events
>[  3586.819] (**) Option "Device" "/dev/input/event6"
>[  3586.837] (II) synaptics: ETPS/2 Elantech Touchpad: ignoring touch events for semi-multitouch device
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: x-axis range 0 - 1088 (res 0)
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: y-axis range 0 - 704 (res 0)
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: pressure range 0 - 255
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: finger width range 0 - 15
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: buttons: left right double triple
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: Vendor 0x2 Product 0xe
>[  3586.837] (--) synaptics: ETPS/2 Elantech Touchpad: touchpad found
>[  3586.837] (**) ETPS/2 Elantech Touchpad: always reports core events
>[  3586.848] (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio4/input/input14/event6"
>[  3586.848] (II) XINPUT: Adding extended input device "ETPS/2 Elantech Touchpad" (type: TOUCHPAD, id 11)
>[  3586.848] (**) synaptics: ETPS/2 Elantech Touchpad: (accel) MinSpeed is now constant deceleration 2.5
>[  3586.848] (**) synaptics: ETPS/2 Elantech Touchpad: (accel) MaxSpeed is now 1.75
>[  3586.848] (**) synaptics: ETPS/2 Elantech Touchpad: (accel) AccelFactor is now 0.154
>[  3586.848] (**) ETPS/2 Elantech Touchpad: (accel) keeping acceleration scheme 1
>[  3586.848] (**) ETPS/2 Elantech Touchpad: (accel) acceleration profile 1
>[  3586.848] (**) ETPS/2 Elantech Touchpad: (accel) acceleration factor: 2.000
>[  3586.848] (**) ETPS/2 Elantech Touchpad: (accel) acceleration threshold: 4
>[  3586.849] (--) synaptics: ETPS/2 Elantech Touchpad: touchpad found
>[  3586.849] (II) config/udev: Adding input device ETPS/2 Elantech Touchpad (/dev/input/mouse0)
>[  3586.849] (**) ETPS/2 Elantech Touchpad: Ignoring device from InputClass "touchpad ignore duplicates"
>[  3586.861] sna_crtc_dpms(pipe 0, dpms mode -> 0):= active=1
>[  3586.861] sna_output_dpms(LVDS1): dpms=0 (current: 0), active? 1
>[  3586.862] sna_block_handler (tv=599.999000)
>[  3586.862] kgem_retire, need_retire?=1
>[  3586.862] kgem_retire__flushing: 0 bo on flushing list
>[  3586.862] __kgem_busy: handle=16, busy=0, wedged=0
>[  3586.862] __kgem_retire_rq: request 16 complete
>[  3586.862] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
>[  3586.862] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  3586.862] kgem_retire -- retired=0, need_retire=0
>[  3586.862] sna_accel_block_handler: GPU idle, flushing
>[  3586.862] batch[3/0, flags=1c00]: 8 8 65528 65528, nreloc=1, nexec=1, nfence=0, aperture=1056 [fenced=0]
>[  3586.862] gem_write__cachealigned(handle=15, offset=0, len=32)
>[  3586.862] kgem_commit: release handle=15 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=45c000
>[  3586.862] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=46e000
>[  3586.862] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.862] sna_accel_do_flush -- no pending write to scanout
>[  3586.862] __kgem_busy: handle=28, busy=65537, wedged=0
>[  3586.862] __kgem_flush: handle=28, busy?=1
>[  3586.863] timer_enable (time=3586863), starting timer 1
>[  3586.863] sna_wakeup_handler
>[  3586.863] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.863] sna_block_handler (tv=599.998000)
>[  3586.863] kgem_retire, need_retire?=1
>[  3586.863] kgem_retire__flushing: 0 bo on flushing list
>[  3586.863] __kgem_busy: handle=15, busy=65537, wedged=0
>[  3586.863] kgem_retire__requests_ring: ring=0, 1 outstanding requests, oldest=15
>[  3586.863] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  3586.863] kgem_retire -- retired=0, need_retire=1
>[  3586.863] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.863] sna_accel_do_flush -- no pending write to scanout
>[  3586.863] sna_wakeup_handler
>[  3586.863] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.863] sna_block_handler (tv=0.000000)
>[  3586.863] sna_wakeup_handler
>[  3586.863] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.863] sna_block_handler (tv=0.000000)
>[  3586.863] sna_wakeup_handler
>[  3586.863] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.864] sna_block_handler (tv=0.000000)
>[  3586.864] sna_wakeup_handler
>[  3586.864] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.864] sna_block_handler (tv=0.000000)
>[  3586.864] sna_wakeup_handler
>[  3586.864] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.864] sna_block_handler (tv=0.000000)
>[  3586.864] sna_wakeup_handler
>[  3586.864] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.865] sna_block_handler (tv=0.000000)
>[  3586.865] sna_wakeup_handler
>[  3586.865] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.865] sna_block_handler (tv=0.000000)
>[  3586.865] sna_wakeup_handler
>[  3586.865] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.865] sna_block_handler (tv=0.000000)
>[  3586.865] sna_wakeup_handler
>[  3586.865] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.865] sna_block_handler (tv=0.000000)
>[  3586.865] sna_wakeup_handler
>[  3586.866] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.866] sna_block_handler (tv=0.000000)
>[  3586.866] sna_wakeup_handler
>[  3586.866] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.866] sna_block_handler (tv=0.000000)
>[  3586.866] sna_wakeup_handler
>[  3586.866] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.866] sna_block_handler (tv=0.000000)
>[  3586.866] sna_wakeup_handler
>[  3586.866] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.866] sna_block_handler (tv=0.000000)
>[  3586.866] sna_wakeup_handler
>[  3586.866] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.866] sna_block_handler (tv=0.000000)
>[  3586.866] sna_wakeup_handler
>[  3586.866] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.867] sna_block_handler (tv=0.000000)
>[  3586.867] sna_wakeup_handler
>[  3586.867] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.867] sna_block_handler (tv=0.000000)
>[  3586.867] sna_wakeup_handler
>[  3586.867] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.867] sna_block_handler (tv=0.000000)
>[  3586.867] sna_wakeup_handler
>[  3586.867] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.867] sna_block_handler (tv=0.000000)
>[  3586.867] sna_wakeup_handler
>[  3586.867] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.867] sna_block_handler (tv=0.000000)
>[  3586.867] sna_wakeup_handler
>[  3586.868] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.868] sna_block_handler (tv=0.000000)
>[  3586.868] sna_wakeup_handler
>[  3586.868] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.868] sna_block_handler (tv=0.000000)
>[  3586.868] sna_wakeup_handler
>[  3586.868] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.868] sna_block_handler (tv=0.000000)
>[  3586.868] sna_wakeup_handler
>[  3586.868] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.871] sna_block_handler (tv=0.000000)
>[  3586.871] sna_wakeup_handler
>[  3586.871] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.871] sna_block_handler (tv=0.000000)
>[  3586.871] sna_wakeup_handler
>[  3586.871] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.871] sna_block_handler (tv=0.000000)
>[  3586.871] sna_wakeup_handler
>[  3586.871] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  3586.872] sna_block_handler (tv=599.989000)
>[  3586.872] kgem_retire, need_retire?=1
>[  3586.872] kgem_retire__flushing: 0 bo on flushing list
>[  3586.872] __kgem_busy: handle=15, busy=0, wedged=0
>[  3586.872] __kgem_retire_rq: request 15 complete
>[  3586.872] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
>[  3586.872] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  3586.872] kgem_retire -- retired=0, need_retire=0
>[  3586.872] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.872] sna_accel_do_flush -- no pending write to scanout
>[  3586.872] sna_wakeup_handler
>[  3586.872] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.872] sna_block_handler (tv=599.989000)
>[  3586.872] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.872] sna_accel_do_flush -- no pending write to scanout
>[  3586.872] sna_wakeup_handler
>[  3586.872] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.872] sna_block_handler (tv=599.989000)
>[  3586.872] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.872] sna_accel_do_flush -- no pending write to scanout
>[  3586.872] sna_wakeup_handler
>[  3586.872] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.872] sna_block_handler (tv=599.989000)
>[  3586.872] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.872] sna_accel_do_flush -- no pending write to scanout
>[  3586.872] sna_wakeup_handler
>[  3586.872] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.872] sna_block_handler (tv=599.989000)
>[  3586.872] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.872] sna_accel_do_flush -- no pending write to scanout
>[  3586.872] sna_wakeup_handler
>[  3586.872] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.872] sna_block_handler (tv=599.989000)
>[  3586.873] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.873] sna_accel_do_flush -- no pending write to scanout
>[  3586.874] sna_wakeup_handler
>[  3586.874] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.874] sna_block_handler (tv=599.987000)
>[  3586.874] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.874] sna_accel_do_flush -- no pending write to scanout
>[  3586.874] sna_wakeup_handler
>[  3586.874] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.874] sna_block_handler (tv=599.987000)
>[  3586.874] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.875] sna_accel_do_flush -- no pending write to scanout
>[  3586.875] sna_wakeup_handler
>[  3586.876] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.876] sna_create_pixmap(16, 16, 1, usage=1)
>[  3586.876] kgem_can_create_2d: 16x16 @ 1
>[  3586.876] kgem_can_create_2d: unhandled depth 1
>[  3586.876] sna_create_pixmap: can not use GPU, just creating shadow
>[  3586.876] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  3586.876] create_pixmap: serial=8, usage=1, 16x16
>[  3586.876] sna_validate_gc changes=7fffff
>[  3586.876] sna_validate_gc: recomputing clip
>[  3586.876] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.876] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  3586.876] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  3586.876] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  3586.876] sna_poly_fill_rect: fallback -- unattached
>[  3586.876] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  3586.876] sna_gc_move_to_cpu, changes=7fffff
>[  3586.876] sfbValidateGC changes=77ffff
>[  3586.876] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3586.876] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  3586.876] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  3586.876] sna_drawable_move_region_to_cpu(pixmap=8 (16x16), [(0, 0), (16, 16)], flags=5)
>[  3586.876] sna_drawable_move_region_to_cpu: not attached to 0x29cafd0
>[  3586.876] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  3586.876] sfbPolyFillRect x 1
>[  3586.876] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  3586.876] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.876] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.876] fbFillBox box=(0, 0), (16, 16)
>[  3586.876] sfbFill (0, 0)x(16, 16), style=0
>[  3586.876] sna_validate_gc changes=4
>[  3586.876] sna_validate_gc: recomputing clip
>[  3586.876] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.876] sna_font_too_large? (16 + 17) x 17: 99 > 124
>[  3586.876] sna_glyph_blt (15, 9) x 1, fg=00000001, bg=ffffffff alu=cc
>[  3586.876] sna_drawable_use_bo pixmap=8, box=((0, 0), (16, 16)), flags=1...
>[  3586.876] sna_drawable_use_bo: not attached
>[  3586.876] sna_poly_text16: fallback
>[  3586.876] sna_gc_move_to_cpu, changes=4
>[  3586.876] sfbValidateGC changes=4
>[  3586.876] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  3586.876] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  3586.876] sna_drawable_move_region_to_cpu(pixmap=8 (16x16), [(0, 0), (16, 16)], flags=3)
>[  3586.876] sna_drawable_move_region_to_cpu: not attached to 0x29cafd0
>[  3586.877] sna_poly_text16: fallback -- fbPolyGlyphBlt
>[  3586.877] sfbPolyGlyphBlt x 1
>[  3586.877] sfbPushImage (0, 0)x(16, 16)fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.877] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.877] sfbBlt 16x16, alu=7, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.877] sna_get_image: pixmap=8 (0, 0)x(16, 16), format=1, mask=1, depth=1
>[  3586.877] sna_drawable_move_region_to_cpu(pixmap=8 (16x16), [(0, 0), (16, 16)], flags=34)
>[  3586.877] sna_drawable_move_region_to_cpu: not attached to 0x29cafd0
>[  3586.877] sfbGetImage (0, 0)x(16, 16)
>[  3586.877] sfbBlt 16x16, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.877] sfbBlt fast blt, src_stride=4, dst_stride=4, width=2 (offset=-40240)
>[  3586.877] sna_destroy_pixmap: pixmap=8, attached?=0
>[  3586.877] sna_create_pixmap(16, 16, 1, usage=1)
>[  3586.877] kgem_can_create_2d: 16x16 @ 1
>[  3586.877] kgem_can_create_2d: unhandled depth 1
>[  3586.877] sna_create_pixmap: can not use GPU, just creating shadow
>[  3586.877] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  3586.877] create_pixmap: serial=9, usage=1, 16x16
>[  3586.877] sna_validate_gc changes=7fffff
>[  3586.877] sna_validate_gc: recomputing clip
>[  3586.877] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.877] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  3586.877] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  3586.877] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  3586.877] sna_poly_fill_rect: fallback -- unattached
>[  3586.877] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  3586.877] sna_gc_move_to_cpu, changes=7fffff
>[  3586.877] sfbValidateGC changes=77ffff
>[  3586.877] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3586.877] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  3586.877] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  3586.877] sna_drawable_move_region_to_cpu(pixmap=9 (16x16), [(0, 0), (16, 16)], flags=5)
>[  3586.877] sna_drawable_move_region_to_cpu: not attached to 0x29cafd0
>[  3586.877] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  3586.877] sfbPolyFillRect x 1
>[  3586.877] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  3586.877] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.877] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.877] fbFillBox box=(0, 0), (16, 16)
>[  3586.877] sfbFill (0, 0)x(16, 16), style=0
>[  3586.877] sna_validate_gc changes=4
>[  3586.877] sna_validate_gc: recomputing clip
>[  3586.877] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)]
>[  3586.877] sna_font_too_large? (16 + 17) x 17: 99 > 124
>[  3586.878] sna_glyph_blt (15, 9) x 1, fg=00000001, bg=ffffffff alu=cc
>[  3586.878] sna_drawable_use_bo pixmap=9, box=((0, 0), (16, 16)), flags=1...
>[  3586.878] sna_drawable_use_bo: not attached
>[  3586.878] sna_poly_text16: fallback
>[  3586.878] sna_gc_move_to_cpu, changes=4
>[  3586.878] sfbValidateGC changes=4
>[  3586.878] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  3586.878] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  3586.878] sna_drawable_move_region_to_cpu(pixmap=9 (16x16), [(0, 0), (16, 16)], flags=3)
>[  3586.878] sna_drawable_move_region_to_cpu: not attached to 0x29cafd0
>[  3586.878] sna_poly_text16: fallback -- fbPolyGlyphBlt
>[  3586.878] sfbPolyGlyphBlt x 1
>[  3586.878] sfbPushImage (0, 0)x(16, 16)fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  3586.878] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  3586.878] sfbBlt 16x16, alu=7, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.878] sna_get_image: pixmap=9 (0, 0)x(16, 16), format=1, mask=1, depth=1
>[  3586.878] sna_drawable_move_region_to_cpu(pixmap=9 (16x16), [(0, 0), (16, 16)], flags=34)
>[  3586.878] sna_drawable_move_region_to_cpu: not attached to 0x29cafd0
>[  3586.878] sfbGetImage (0, 0)x(16, 16)
>[  3586.878] sfbBlt 16x16, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3586.878] sfbBlt fast blt, src_stride=4, dst_stride=4, width=2 (offset=35248)
>[  3586.878] sna_destroy_pixmap: pixmap=9, attached?=0
>[  3586.879] sna_change_window_attributes
>[  3586.879] sna_block_handler (tv=599.982000)
>[  3586.879] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.879] sna_accel_do_flush -- no pending write to scanout
>[  3586.912] sna_wakeup_handler
>[  3586.912] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.912] sna_block_handler (tv=599.949000)
>[  3586.912] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.912] sna_accel_do_flush -- no pending write to scanout
>[  3586.912] sna_accel_do_throttle (time=3586912), triggered
>[  3586.912] sna_accel_throttle (time=3586912)
>[  3586.912] sna_accel_disarm_timer[1] (time=3586912)
>[  3586.912] sna_wakeup_handler
>[  3586.912] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.912] sna_block_handler (tv=0.000000)
>[  3586.912] sna_wakeup_handler
>[  3586.912] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.913] sna_block_handler (tv=599.948000)
>[  3586.913] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.913] sna_accel_do_flush -- no pending write to scanout
>[  3586.913] sna_accel_do_throttle -- no pending activity
>[  3586.936] sna_wakeup_handler
>[  3586.937] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.937] sna_block_handler (tv=599.924000)
>[  3586.937] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.937] sna_accel_do_flush -- no pending write to scanout
>[  3586.937] sna_accel_do_throttle -- no pending activity
>[  3586.937] sna_wakeup_handler
>[  3586.937] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.937] sna_block_handler (tv=0.000000)
>[  3586.937] sna_wakeup_handler
>[  3586.937] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.937] sna_block_handler (tv=599.924000)
>[  3586.937] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.937] sna_accel_do_flush -- no pending write to scanout
>[  3586.937] sna_accel_do_throttle -- no pending activity
>[  3586.941] sna_wakeup_handler
>[  3586.941] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.941] sna_block_handler (tv=599.920000)
>[  3586.941] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.941] sna_accel_do_flush -- no pending write to scanout
>[  3586.941] sna_accel_do_throttle -- no pending activity
>[  3586.941] sna_wakeup_handler
>[  3586.941] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.941] sna_block_handler (tv=599.920000)
>[  3586.941] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.941] sna_accel_do_flush -- no pending write to scanout
>[  3586.941] sna_accel_do_throttle -- no pending activity
>[  3586.941] sna_wakeup_handler
>[  3586.941] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.941] sna_block_handler (tv=599.920000)
>[  3586.941] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.941] sna_accel_do_flush -- no pending write to scanout
>[  3586.941] sna_accel_do_throttle -- no pending activity
>[  3586.941] sna_wakeup_handler
>[  3586.941] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.941] sna_block_handler (tv=599.920000)
>[  3586.941] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.941] sna_accel_do_flush -- no pending write to scanout
>[  3586.941] sna_accel_do_throttle -- no pending activity
>[  3586.941] sna_wakeup_handler
>[  3586.942] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.942] sna_block_handler (tv=599.919000)
>[  3586.942] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.942] sna_accel_do_flush -- no pending write to scanout
>[  3586.942] sna_accel_do_throttle -- no pending activity
>[  3586.942] sna_wakeup_handler
>[  3586.942] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.942] sna_block_handler (tv=599.919000)
>[  3586.942] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.942] sna_accel_do_flush -- no pending write to scanout
>[  3586.942] sna_accel_do_throttle -- no pending activity
>[  3586.942] sna_wakeup_handler
>[  3586.942] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.942] sna_block_handler (tv=599.919000)
>[  3586.942] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.942] sna_accel_do_flush -- no pending write to scanout
>[  3586.942] sna_accel_do_throttle -- no pending activity
>[  3586.942] sna_wakeup_handler
>[  3586.942] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.942] sna_block_handler (tv=599.919000)
>[  3586.942] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.942] sna_accel_do_flush -- no pending write to scanout
>[  3586.942] sna_accel_do_throttle -- no pending activity
>[  3586.942] sna_wakeup_handler
>[  3586.942] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.942] sna_block_handler (tv=599.919000)
>[  3586.942] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.942] sna_accel_do_flush -- no pending write to scanout
>[  3586.942] sna_accel_do_throttle -- no pending activity
>[  3586.942] sna_wakeup_handler
>[  3586.942] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.942] sna_block_handler (tv=599.919000)
>[  3586.942] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.943] sna_accel_do_flush -- no pending write to scanout
>[  3586.943] sna_accel_do_throttle -- no pending activity
>[  3586.943] sna_wakeup_handler
>[  3586.943] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3586.943] sna_block_handler (tv=599.918000)
>[  3586.943] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3586.943] sna_accel_do_flush -- no pending write to scanout
>[  3586.943] sna_accel_do_throttle -- no pending activity
>[  3586.943] sna_wakeup_handler
>
>
>==============================================================================
>
>[  3791.237] sna_block_handler (tv=0.013000)
>[  3791.237] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.237] sna_accel_do_flush -- no pending write to scanout
>[  3791.237] sna_accel_do_throttle -- no pending activity
>[  3791.241] sna_wakeup_handler
>[  3791.241] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.241] sna_block_handler (tv=0.009000)
>[  3791.241] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.241] sna_accel_do_flush -- no pending write to scanout
>[  3791.241] sna_accel_do_throttle -- no pending activity
>[  3791.241] sna_wakeup_handler
>[  3791.241] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.242] sna_copy_area: src=(514, 656)x(34, 30)+(0, 0) -> dst=(514, 656)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.242] sna_do_copy: src=(514, 656), dst=(514, 656), size=(34x30)
>[  3791.242] sna_do_copy: after drawable: src=(514, 656), dst=(651, 676), size=(34x30)
>[  3791.242] sna_do_copy: dst extents (651, 676), (685, 706)
>[  3791.242] sna_do_copy: dst clipped out
>[  3791.242] sna_block_handler (tv=0.008000)
>[  3791.242] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.242] sna_accel_do_flush -- no pending write to scanout
>[  3791.242] sna_accel_do_throttle -- no pending activity
>[  3791.248] sna_wakeup_handler
>[  3791.249] sna_block_handler (tv=0.013000)
>[  3791.249] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.249] sna_accel_do_flush -- no pending write to scanout
>[  3791.249] sna_accel_do_throttle -- no pending activity
>[  3791.249] sna_wakeup_handler
>[  3791.250] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.250] sna_block_handler (tv=0.012000)
>[  3791.250] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.250] sna_accel_do_flush -- no pending write to scanout
>[  3791.250] sna_accel_do_throttle -- no pending activity
>[  3791.250] sna_wakeup_handler
>[  3791.250] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.250] sna_copy_area: src=(201, 645)x(661, 41)+(0, 0) -> dst=(201, 645)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.251] sna_do_copy: src=(201, 645), dst=(201, 645), size=(661x41)
>[  3791.251] sna_do_copy: after drawable: src=(201, 645), dst=(338, 665), size=(661x41)
>[  3791.251] sna_do_copy: dst extents (338, 665), (999, 706)
>[  3791.251] sna_do_copy: dst clipped out
>[  3791.251] sna_block_handler (tv=0.011000)
>[  3791.251] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.251] sna_accel_do_flush -- no pending write to scanout
>[  3791.251] sna_accel_do_throttle -- no pending activity
>[  3791.260] sna_wakeup_handler
>[  3791.260] sna_block_handler (tv=0.014000)
>[  3791.260] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.260] sna_accel_do_flush -- no pending write to scanout
>[  3791.260] sna_accel_do_throttle -- no pending activity
>[  3791.269] sna_wakeup_handler
>[  3791.269] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.269] sna_block_handler (tv=0.005000)
>[  3791.269] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.269] sna_accel_do_flush -- no pending write to scanout
>[  3791.269] sna_accel_do_throttle -- no pending activity
>[  3791.270] sna_wakeup_handler
>[  3791.270] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.270] sna_copy_area: src=(201, 644)x(661, 42)+(0, 0) -> dst=(201, 644)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.270] sna_do_copy: src=(201, 644), dst=(201, 644), size=(661x42)
>[  3791.270] sna_do_copy: after drawable: src=(201, 644), dst=(338, 664), size=(661x42)
>[  3791.270] sna_do_copy: dst extents (338, 664), (999, 706)
>[  3791.270] sna_do_copy: dst clipped out
>[  3791.270] sna_block_handler (tv=0.004000)
>[  3791.270] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.270] sna_accel_do_flush -- no pending write to scanout
>[  3791.270] sna_accel_do_throttle -- no pending activity
>[  3791.273] sna_wakeup_handler
>[  3791.273] sna_block_handler (tv=0.014000)
>[  3791.273] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.273] sna_accel_do_flush -- no pending write to scanout
>[  3791.273] sna_accel_do_throttle -- no pending activity
>[  3791.283] sna_wakeup_handler
>[  3791.283] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.283] sna_block_handler (tv=0.004000)
>[  3791.283] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.283] sna_accel_do_flush -- no pending write to scanout
>[  3791.283] sna_accel_do_throttle -- no pending activity
>[  3791.283] sna_wakeup_handler
>[  3791.283] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.284] sna_copy_area: src=(201, 643)x(661, 43)+(0, 0) -> dst=(201, 643)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.284] sna_do_copy: src=(201, 643), dst=(201, 643), size=(661x43)
>[  3791.284] sna_do_copy: after drawable: src=(201, 643), dst=(338, 663), size=(661x43)
>[  3791.284] sna_do_copy: dst extents (338, 663), (999, 706)
>[  3791.284] sna_do_copy: dst clipped out
>[  3791.284] sna_block_handler (tv=0.003000)
>[  3791.284] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.284] sna_accel_do_flush -- no pending write to scanout
>[  3791.284] sna_accel_do_throttle -- no pending activity
>[  3791.285] sna_wakeup_handler
>[  3791.285] sna_block_handler (tv=0.014000)
>[  3791.285] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.285] sna_accel_do_flush -- no pending write to scanout
>[  3791.285] sna_accel_do_throttle -- no pending activity
>[  3791.297] sna_wakeup_handler
>[  3791.297] sna_block_handler (tv=0.014000)
>[  3791.297] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.297] sna_accel_do_flush -- no pending write to scanout
>[  3791.297] sna_accel_do_throttle -- no pending activity
>[  3791.298] sna_wakeup_handler
>[  3791.298] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.299] sna_block_handler (tv=0.012000)
>[  3791.299] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.299] sna_accel_do_flush -- no pending write to scanout
>[  3791.299] sna_accel_do_throttle -- no pending activity
>[  3791.299] sna_wakeup_handler
>[  3791.299] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.299] sna_block_handler (tv=0.012000)
>[  3791.299] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.299] sna_accel_do_flush -- no pending write to scanout
>[  3791.299] sna_accel_do_throttle -- no pending activity
>[  3791.299] sna_wakeup_handler
>[  3791.299] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.299] sna_copy_area: src=(201, 641)x(661, 45)+(0, 0) -> dst=(201, 641)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.299] sna_do_copy: src=(201, 641), dst=(201, 641), size=(661x45)
>[  3791.299] sna_do_copy: after drawable: src=(201, 641), dst=(338, 661), size=(661x45)
>[  3791.299] sna_do_copy: dst extents (338, 661), (999, 706)
>[  3791.299] sna_do_copy: dst clipped out
>[  3791.300] sna_block_handler (tv=0.012000)
>[  3791.300] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.300] sna_accel_do_flush -- no pending write to scanout
>[  3791.300] sna_accel_do_throttle -- no pending activity
>[  3791.309] sna_wakeup_handler
>[  3791.309] sna_block_handler (tv=0.014000)
>[  3791.309] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.309] sna_accel_do_flush -- no pending write to scanout
>[  3791.309] sna_accel_do_throttle -- no pending activity
>[  3791.315] sna_wakeup_handler
>[  3791.315] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.315] sna_block_handler (tv=0.008000)
>[  3791.315] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.315] sna_accel_do_flush -- no pending write to scanout
>[  3791.315] sna_accel_do_throttle -- no pending activity
>[  3791.316] sna_wakeup_handler
>[  3791.316] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.317] sna_copy_area: src=(201, 640)x(661, 46)+(0, 0) -> dst=(201, 640)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.317] sna_do_copy: src=(201, 640), dst=(201, 640), size=(661x46)
>[  3791.317] sna_do_copy: after drawable: src=(201, 640), dst=(338, 660), size=(661x46)
>[  3791.317] sna_do_copy: dst extents (338, 660), (999, 706)
>[  3791.317] sna_do_copy: dst clipped out
>[  3791.317] sna_block_handler (tv=0.006000)
>[  3791.317] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.317] sna_accel_do_flush -- no pending write to scanout
>[  3791.317] sna_accel_do_throttle -- no pending activity
>[  3791.321] sna_wakeup_handler
>[  3791.322] sna_block_handler (tv=0.013000)
>[  3791.322] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.322] sna_accel_do_flush -- no pending write to scanout
>[  3791.322] sna_accel_do_throttle -- no pending activity
>[  3791.324] sna_wakeup_handler
>[  3791.324] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.324] sna_change_window_attributes
>[  3791.324] sna_block_handler (tv=0.011000)
>[  3791.324] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.324] sna_accel_do_flush -- no pending write to scanout
>[  3791.324] sna_accel_do_throttle -- no pending activity
>[  3791.333] sna_wakeup_handler
>[  3791.333] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.333] sna_block_handler (tv=0.002000)
>[  3791.333] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.333] sna_accel_do_flush -- no pending write to scanout
>[  3791.333] sna_accel_do_throttle -- no pending activity
>[  3791.333] sna_wakeup_handler
>[  3791.333] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.333] sna_copy_area: src=(201, 639)x(661, 47)+(0, 0) -> dst=(201, 639)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.333] sna_do_copy: src=(201, 639), dst=(201, 639), size=(661x47)
>[  3791.333] sna_do_copy: after drawable: src=(201, 639), dst=(338, 659), size=(661x47)
>[  3791.333] sna_do_copy: dst extents (338, 659), (999, 706)
>[  3791.333] sna_do_copy: dst clipped out
>[  3791.333] sna_block_handler (tv=0.002000)
>[  3791.333] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.333] sna_accel_do_flush -- no pending write to scanout
>[  3791.333] sna_accel_do_throttle -- no pending activity
>[  3791.334] sna_wakeup_handler
>[  3791.334] sna_block_handler (tv=0.014000)
>[  3791.334] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.334] sna_accel_do_flush -- no pending write to scanout
>[  3791.334] sna_accel_do_throttle -- no pending activity
>[  3791.339] sna_wakeup_handler
>[  3791.339] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.340] sna_block_handler (tv=0.008000)
>[  3791.340] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.340] sna_accel_do_flush -- no pending write to scanout
>[  3791.340] sna_accel_do_throttle -- no pending activity
>[  3791.340] sna_wakeup_handler
>[  3791.340] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.340] sna_copy_area: src=(514, 648)x(34, 34)+(0, 0) -> dst=(514, 648)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.340] sna_do_copy: src=(514, 648), dst=(514, 648), size=(34x34)
>[  3791.340] sna_do_copy: after drawable: src=(514, 648), dst=(651, 668), size=(34x34)
>[  3791.340] sna_do_copy: dst extents (651, 668), (685, 702)
>[  3791.340] sna_do_copy: dst clipped out
>[  3791.341] sna_block_handler (tv=0.007000)
>[  3791.341] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.341] sna_accel_do_flush -- no pending write to scanout
>[  3791.341] sna_accel_do_throttle -- no pending activity
>[  3791.348] sna_wakeup_handler
>[  3791.348] sna_block_handler (tv=0.013000)
>[  3791.348] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.348] sna_accel_do_flush -- no pending write to scanout
>[  3791.348] sna_accel_do_throttle -- no pending activity
>[  3791.348] sna_wakeup_handler
>[  3791.348] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.348] sna_copy_area: src=(201, 638)x(661, 48)+(0, 0) -> dst=(201, 638)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.348] sna_do_copy: src=(201, 638), dst=(201, 638), size=(661x48)
>[  3791.348] sna_do_copy: after drawable: src=(201, 638), dst=(338, 658), size=(661x48)
>[  3791.348] sna_do_copy: dst extents (338, 658), (999, 706)
>[  3791.348] sna_do_copy: dst clipped out
>[  3791.348] sna_block_handler (tv=0.013000)
>[  3791.348] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.348] sna_accel_do_flush -- no pending write to scanout
>[  3791.348] sna_accel_do_throttle -- no pending activity
>[  3791.357] sna_wakeup_handler
>[  3791.357] sna_block_handler (tv=0.014000)
>[  3791.357] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.357] sna_accel_do_flush -- no pending write to scanout
>[  3791.357] sna_accel_do_throttle -- no pending activity
>[  3791.366] sna_wakeup_handler
>[  3791.366] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.368] sna_copy_area: src=(201, 637)x(661, 49)+(0, 0) -> dst=(201, 637)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.368] sna_do_copy: src=(201, 637), dst=(201, 637), size=(661x49)
>[  3791.368] sna_do_copy: after drawable: src=(201, 637), dst=(338, 657), size=(661x49)
>[  3791.368] sna_do_copy: dst extents (338, 657), (999, 706)
>[  3791.368] sna_do_copy: dst clipped out
>[  3791.368] sna_block_handler (tv=0.003000)
>[  3791.368] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.368] sna_accel_do_flush -- no pending write to scanout
>[  3791.368] sna_accel_do_throttle -- no pending activity
>[  3791.370] sna_wakeup_handler
>[  3791.370] sna_block_handler (tv=0.014000)
>[  3791.370] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.370] sna_accel_do_flush -- no pending write to scanout
>[  3791.370] sna_accel_do_throttle -- no pending activity
>[  3791.381] sna_wakeup_handler
>[  3791.381] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.381] sna_block_handler (tv=0.003000)
>[  3791.381] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.381] sna_accel_do_flush -- no pending write to scanout
>[  3791.381] sna_accel_do_throttle -- no pending activity
>[  3791.382] sna_wakeup_handler
>[  3791.382] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.382] sna_copy_area: src=(201, 636)x(661, 50)+(0, 0) -> dst=(201, 636)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.382] sna_do_copy: src=(201, 636), dst=(201, 636), size=(661x50)
>[  3791.382] sna_do_copy: after drawable: src=(201, 636), dst=(338, 656), size=(661x50)
>[  3791.382] sna_do_copy: dst extents (338, 656), (999, 706)
>[  3791.382] sna_do_copy: dst clipped out
>[  3791.382] sna_block_handler (tv=1060.460000)
>[  3791.382] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.382] sna_accel_do_flush -- no pending write to scanout
>[  3791.382] sna_accel_do_throttle -- no pending activity
>[  3791.396] sna_wakeup_handler
>[  3791.396] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.396] sna_block_handler (tv=1060.446000)
>[  3791.396] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.396] sna_accel_do_flush -- no pending write to scanout
>[  3791.396] sna_accel_do_throttle -- no pending activity
>[  3791.397] sna_wakeup_handler
>[  3791.397] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.397] sna_copy_area: src=(201, 636)x(661, 50)+(0, 0) -> dst=(201, 636)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.397] sna_do_copy: src=(201, 636), dst=(201, 636), size=(661x50)
>[  3791.398] sna_do_copy: after drawable: src=(201, 636), dst=(338, 656), size=(661x50)
>[  3791.398] sna_do_copy: dst extents (338, 656), (999, 706)
>[  3791.398] sna_do_copy: dst clipped out
>[  3791.398] sna_block_handler (tv=1060.444000)
>[  3791.398] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.398] sna_accel_do_flush -- no pending write to scanout
>[  3791.398] sna_accel_do_throttle -- no pending activity
>[  3791.414] sna_wakeup_handler
>[  3791.414] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.414] sna_block_handler (tv=1060.428000)
>[  3791.414] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.414] sna_accel_do_flush -- no pending write to scanout
>[  3791.414] sna_accel_do_throttle -- no pending activity
>[  3791.414] sna_wakeup_handler
>[  3791.414] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.415] sna_copy_area: src=(201, 635)x(661, 51)+(0, 0) -> dst=(201, 635)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.415] sna_do_copy: src=(201, 635), dst=(201, 635), size=(661x51)
>[  3791.415] sna_do_copy: after drawable: src=(201, 635), dst=(338, 655), size=(661x51)
>[  3791.415] sna_do_copy: dst extents (338, 655), (999, 706)
>[  3791.415] sna_do_copy: dst clipped out
>[  3791.415] sna_block_handler (tv=1060.427000)
>[  3791.415] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.415] sna_accel_do_flush -- no pending write to scanout
>[  3791.415] sna_accel_do_throttle -- no pending activity
>[  3791.428] sna_wakeup_handler
>[  3791.428] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.428] sna_block_handler (tv=1060.414000)
>[  3791.428] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.428] sna_accel_do_flush -- no pending write to scanout
>[  3791.428] sna_accel_do_throttle -- no pending activity
>[  3791.429] sna_wakeup_handler
>[  3791.429] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.429] sna_block_handler (tv=1060.413000)
>[  3791.429] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.429] sna_accel_do_flush -- no pending write to scanout
>[  3791.429] sna_accel_do_throttle -- no pending activity
>[  3791.429] sna_wakeup_handler
>[  3791.429] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.429] sna_copy_area: src=(201, 635)x(661, 51)+(0, 0) -> dst=(201, 635)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.429] sna_do_copy: src=(201, 635), dst=(201, 635), size=(661x51)
>[  3791.429] sna_do_copy: after drawable: src=(201, 635), dst=(338, 655), size=(661x51)
>[  3791.429] sna_do_copy: dst extents (338, 655), (999, 706)
>[  3791.430] sna_do_copy: dst clipped out
>[  3791.430] sna_block_handler (tv=1060.412000)
>[  3791.430] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.430] sna_accel_do_flush -- no pending write to scanout
>[  3791.430] sna_accel_do_throttle -- no pending activity
>[  3791.440] sna_wakeup_handler
>[  3791.440] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.440] sna_block_handler (tv=1060.402000)
>[  3791.440] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.440] sna_accel_do_flush -- no pending write to scanout
>[  3791.441] sna_accel_do_throttle -- no pending activity
>[  3791.441] sna_wakeup_handler
>[  3791.441] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.441] sna_copy_area: src=(514, 644)x(34, 34)+(0, 0) -> dst=(514, 644)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.441] sna_do_copy: src=(514, 644), dst=(514, 644), size=(34x34)
>[  3791.441] sna_do_copy: after drawable: src=(514, 644), dst=(651, 664), size=(34x34)
>[  3791.441] sna_do_copy: dst extents (651, 664), (685, 698)
>[  3791.441] sna_do_copy: dst clipped out
>[  3791.441] sna_block_handler (tv=1060.401000)
>[  3791.441] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.441] sna_accel_do_flush -- no pending write to scanout
>[  3791.441] sna_accel_do_throttle -- no pending activity
>[  3791.447] sna_wakeup_handler
>[  3791.447] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.447] sna_copy_area: src=(201, 635)x(661, 51)+(0, 0) -> dst=(201, 635)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.448] sna_do_copy: src=(201, 635), dst=(201, 635), size=(661x51)
>[  3791.448] sna_do_copy: after drawable: src=(201, 635), dst=(338, 655), size=(661x51)
>[  3791.448] sna_do_copy: dst extents (338, 655), (999, 706)
>[  3791.448] sna_do_copy: dst clipped out
>[  3791.448] sna_block_handler (tv=1060.394000)
>[  3791.448] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.448] sna_accel_do_flush -- no pending write to scanout
>[  3791.448] sna_accel_do_throttle -- no pending activity
>[  3791.464] sna_wakeup_handler
>[  3791.464] sna_block_handler (tv=0.001000)
>[  3791.464] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.464] sna_accel_do_flush -- no pending write to scanout
>[  3791.464] sna_accel_do_throttle -- no pending activity
>[  3791.465] sna_wakeup_handler
>[  3791.465] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.465] sna_block_handler (tv=0.014000)
>[  3791.465] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.465] sna_accel_do_flush -- no pending write to scanout
>[  3791.465] sna_accel_do_throttle -- no pending activity
>[  3791.478] sna_wakeup_handler
>[  3791.478] sna_block_handler (tv=0.014000)
>[  3791.478] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.478] sna_accel_do_flush -- no pending write to scanout
>[  3791.478] sna_accel_do_throttle -- no pending activity
>[  3791.479] sna_wakeup_handler
>[  3791.479] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.479] sna_change_window_attributes
>[  3791.479] sna_block_handler (tv=0.013000)
>[  3791.479] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.479] sna_accel_do_flush -- no pending write to scanout
>[  3791.479] sna_accel_do_throttle -- no pending activity
>[  3791.490] sna_wakeup_handler
>[  3791.490] sna_block_handler (tv=0.014000)
>[  3791.490] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.490] sna_accel_do_flush -- no pending write to scanout
>[  3791.490] sna_accel_do_throttle -- no pending activity
>[  3791.501] sna_wakeup_handler
>[  3791.501] sna_block_handler (tv=1060.341000)
>[  3791.501] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.501] sna_accel_do_flush -- no pending write to scanout
>[  3791.501] sna_accel_do_throttle -- no pending activity
>[  3791.502] sna_wakeup_handler
>[  3791.502] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.502] sna_block_handler (tv=1060.340000)
>[  3791.502] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.502] sna_accel_do_flush -- no pending write to scanout
>[  3791.502] sna_accel_do_throttle -- no pending activity
>[  3791.503] sna_wakeup_handler
>[  3791.503] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.503] sna_block_handler (tv=1060.339000)
>[  3791.503] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.503] sna_accel_do_flush -- no pending write to scanout
>[  3791.503] sna_accel_do_throttle -- no pending activity
>[  3791.505] sna_wakeup_handler
>[  3791.505] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.505] sna_copy_area: src=(285, 687)x(672, 41)+(0, 0) -> dst=(285, 687)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.505] sna_do_copy: src=(285, 687), dst=(285, 687), size=(672x41)
>[  3791.505] sna_do_copy: after drawable: src=(285, 687), dst=(422, 707), size=(672x41)
>[  3791.505] sna_do_copy: dst extents (422, 707), (1094, 748)
>[  3791.505] sna_do_copy: pixmap -- no source clipping
>[  3791.505] sna_do_copy: src extents (285, 687), (957, 728) x 1
>[  3791.505] sna_do_copy: copy region (422, 707), (1094, 748) x 3
>[  3791.505] sna_copy_boxes (boxes=3x[(422, 707), (1094, 733)...], src=+(-137, -20), alu=3, src.size=1120x736, dst.size=1366x768)
>[  3791.505] sna_copy_boxes: dst=(priv=0x260dab0, gpu_bo=28, cpu_bo=0), src=(priv=(nil), gpu_bo=0, cpu_bo=0), replaces=0
>[  3791.505] copy_prefer_gpu: source unattached, use cpu
>[  3791.505] sna_drawable_use_bo pixmap=3, box=((422, 707), (1094, 748)), flags=0...
>[  3791.505] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=0
>[  3791.505] kgem_bo_is_busy: handle=28, domain: 2 exec? 0, rq? 0
>[  3791.505] sna_drawable_use_bo: try cpu as GPU bo is idle
>[  3791.505] sna_copy_boxes__inplace - no, bad dst tiling [X]
>[  3791.505] sna_copy_boxes: fallback -- src=(-137, -20), dst=(0, 0)
>[  3791.505] sna_drawable_move_region_to_cpu(pixmap=3 (1366x768), [(422, 707), (1094, 748)], flags=5)
>[  3791.505] sna_drawable_move_region_to_cpu: marking for inplace hint (0, 1)
>[  3791.505] kgem_bo_is_busy: handle=28, domain: 2 exec? 0, rq? 0
>[  3791.505] kgem_bo_is_busy: handle=28, domain: 2 exec? 0, rq? 0
>[  3791.505] kgem_bo_mapped: map=0x7f314b5cf000:(nil), tiling=1, domain=2
>[  3791.505] region_inplace: yes, already mapped, continuing
>[  3791.506] kgem_bo_mapped: map=0x7f314b5cf000:(nil), tiling=1, domain=2
>[  3791.506] kgem_bo_can_map: map=0x7f314b5cf000:(nil), tiling=1, domain=2, offset=4644864
>[  3791.506] kgem_bo_is_busy: handle=28, domain: 2 exec? 0, rq? 0
>[  3791.506] sna_drawable_move_region_to_cpu: try to operate inplace
>[  3791.506] kgem_bo_map: handle=28, offset=4644864, tiling=1, map=0x7f314b5cf000:(nil), domain=2
>[  3791.506] sna_drawable_move_region_to_cpu: operate inplace
>[  3791.506] sna_copy_boxes: memcpy_blt(box=(422, 707), (672, 26), src=(-137, -20), pitches=(4480, 5632))
>[  3791.506] memcpy_blt: src=(422, 707), dst=(422, 707), size=672x26, pitch=4480/5632
>[  3791.506] sna_copy_boxes: memcpy_blt(box=(422, 733), (101, 15), src=(-137, -20), pitches=(4480, 5632))
>[  3791.506] memcpy_blt: src=(422, 733), dst=(422, 733), size=101x15, pitch=4480/5632
>[  3791.506] sna_copy_boxes: memcpy_blt(box=(590, 733), (504, 15), src=(-137, -20), pitches=(4480, 5632))
>[  3791.506] memcpy_blt: src=(590, 733), dst=(590, 733), size=504x15, pitch=4480/5632
>[  3791.506] sna_block_handler (tv=1060.336000)
>[  3791.506] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.506] sna_accel_do_flush -- no pending write to scanout
>[  3791.506] sna_accel_do_throttle -- no pending activity
>[  3791.549] sna_wakeup_handler
>[  3791.549] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.549] sna_block_handler (tv=1060.293000)
>[  3791.549] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.549] sna_accel_do_flush -- no pending write to scanout
>[  3791.549] sna_accel_do_throttle -- no pending activity
>[  3791.551] sna_wakeup_handler
>[  3791.551] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.551] sna_block_handler (tv=1060.291000)
>[  3791.551] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.551] sna_accel_do_flush -- no pending write to scanout
>[  3791.551] sna_accel_do_throttle -- no pending activity
>[  3791.552] sna_wakeup_handler
>[  3791.552] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.552] sna_copy_area: src=(201, 86)x(661, 600)+(0, 0) -> dst=(201, 86)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.552] sna_do_copy: src=(201, 86), dst=(201, 86), size=(661x600)
>[  3791.552] sna_do_copy: after drawable: src=(201, 86), dst=(338, 106), size=(661x600)
>[  3791.552] sna_do_copy: dst extents (338, 106), (999, 706)
>[  3791.552] sna_do_copy: dst clipped out
>[  3791.552] sna_block_handler (tv=1060.290000)
>[  3791.552] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.552] sna_accel_do_flush -- no pending write to scanout
>[  3791.552] sna_accel_do_throttle -- no pending activity
>[  3791.639] sna_wakeup_handler
>[  3791.639] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.639] sna_block_handler (tv=1060.203000)
>[  3791.640] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.640] sna_accel_do_flush -- no pending write to scanout
>[  3791.640] sna_accel_do_throttle -- no pending activity
>[  3791.640] sna_wakeup_handler
>[  3791.640] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.640] sna_copy_area: src=(514, 644)x(34, 34)+(0, 0) -> dst=(514, 644)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.640] sna_do_copy: src=(514, 644), dst=(514, 644), size=(34x34)
>[  3791.640] sna_do_copy: after drawable: src=(514, 644), dst=(651, 664), size=(34x34)
>[  3791.640] sna_do_copy: dst extents (651, 664), (685, 698)
>[  3791.640] sna_do_copy: dst clipped out
>[  3791.640] sna_block_handler (tv=1060.202000)
>[  3791.640] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.641] sna_accel_do_flush -- no pending write to scanout
>[  3791.641] sna_accel_do_throttle -- no pending activity
>[  3791.706] sna_wakeup_handler
>[  3791.706] sna_block_handler (tv=0.001000)
>[  3791.706] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.706] sna_accel_do_flush -- no pending write to scanout
>[  3791.706] sna_accel_do_throttle -- no pending activity
>[  3791.707] sna_wakeup_handler
>[  3791.707] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.707] sna_block_handler (tv=0.014000)
>[  3791.707] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.707] sna_accel_do_flush -- no pending write to scanout
>[  3791.707] sna_accel_do_throttle -- no pending activity
>[  3791.719] sna_wakeup_handler
>[  3791.719] sna_block_handler (tv=0.014000)
>[  3791.719] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.719] sna_accel_do_flush -- no pending write to scanout
>[  3791.719] sna_accel_do_throttle -- no pending activity
>[  3791.719] sna_wakeup_handler
>[  3791.719] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.719] sna_change_window_attributes
>[  3791.719] sna_block_handler (tv=0.014000)
>[  3791.719] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.719] sna_accel_do_flush -- no pending write to scanout
>[  3791.719] sna_accel_do_throttle -- no pending activity
>[  3791.731] sna_wakeup_handler
>[  3791.731] sna_block_handler (tv=0.014000)
>[  3791.731] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.731] sna_accel_do_flush -- no pending write to scanout
>[  3791.731] sna_accel_do_throttle -- no pending activity
>[  3791.737] sna_wakeup_handler
>[  3791.737] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.737] sna_change_window_attributes
>[  3791.737] sna_block_handler (tv=0.008000)
>[  3791.737] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.737] sna_accel_do_flush -- no pending write to scanout
>[  3791.737] sna_accel_do_throttle -- no pending activity
>[  3791.742] sna_wakeup_handler
>[  3791.742] sna_block_handler (tv=0.014000)
>[  3791.742] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.742] sna_accel_do_flush -- no pending write to scanout
>[  3791.742] sna_accel_do_throttle -- no pending activity
>[  3791.749] sna_wakeup_handler
>[  3791.749] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.749] sna_block_handler (tv=0.007000)
>[  3791.749] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.749] sna_accel_do_flush -- no pending write to scanout
>[  3791.750] sna_accel_do_throttle -- no pending activity
>[  3791.752] sna_wakeup_handler
>[  3791.752] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.752] sna_copy_area: src=(514, 86)x(348, 592)+(0, 0) -> dst=(514, 86)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.752] sna_do_copy: src=(514, 86), dst=(514, 86), size=(348x592)
>[  3791.752] sna_do_copy: after drawable: src=(514, 86), dst=(651, 106), size=(348x592)
>[  3791.752] sna_do_copy: dst extents (651, 106), (999, 698)
>[  3791.752] sna_do_copy: dst clipped out
>[  3791.753] sna_block_handler (tv=0.003000)
>[  3791.753] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.753] sna_accel_do_flush -- no pending write to scanout
>[  3791.753] sna_accel_do_throttle -- no pending activity
>[  3791.755] sna_wakeup_handler
>[  3791.755] sna_block_handler (tv=0.014000)
>[  3791.755] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.755] sna_accel_do_flush -- no pending write to scanout
>[  3791.756] sna_accel_do_throttle -- no pending activity
>[  3791.767] sna_wakeup_handler
>[  3791.767] sna_block_handler (tv=0.014000)
>[  3791.767] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.768] sna_accel_do_flush -- no pending write to scanout
>[  3791.768] sna_accel_do_throttle -- no pending activity
>[  3791.779] sna_wakeup_handler
>[  3791.779] sna_block_handler (tv=0.014000)
>[  3791.779] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.779] sna_accel_do_flush -- no pending write to scanout
>[  3791.780] sna_accel_do_throttle -- no pending activity
>[  3791.790] sna_wakeup_handler
>[  3791.790] sna_block_handler (tv=0.014000)
>[  3791.790] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.790] sna_accel_do_flush -- no pending write to scanout
>[  3791.791] sna_accel_do_throttle -- no pending activity
>[  3791.795] sna_wakeup_handler
>[  3791.795] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.796] sna_change_window_attributes
>[  3791.796] sna_block_handler (tv=0.008000)
>[  3791.796] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.796] sna_accel_do_flush -- no pending write to scanout
>[  3791.796] sna_accel_do_throttle -- no pending activity
>[  3791.804] sna_wakeup_handler
>[  3791.804] sna_block_handler (tv=0.014000)
>[  3791.804] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.804] sna_accel_do_flush -- no pending write to scanout
>[  3791.804] sna_accel_do_throttle -- no pending activity
>[  3791.816] sna_wakeup_handler
>[  3791.817] sna_block_handler (tv=0.013000)
>[  3791.817] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.817] sna_accel_do_flush -- no pending write to scanout
>[  3791.817] sna_accel_do_throttle -- no pending activity
>[  3791.828] sna_wakeup_handler
>[  3791.828] sna_block_handler (tv=0.013000)
>[  3791.828] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.828] sna_accel_do_flush -- no pending write to scanout
>[  3791.828] sna_accel_do_throttle -- no pending activity
>[  3791.840] sna_wakeup_handler
>[  3791.840] sna_block_handler (tv=0.014000)
>[  3791.840] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.840] sna_accel_do_flush -- no pending write to scanout
>[  3791.840] sna_accel_do_throttle -- no pending activity
>[  3791.853] sna_wakeup_handler
>[  3791.854] sna_block_handler (tv=0.013000)
>[  3791.854] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.854] sna_accel_do_flush -- no pending write to scanout
>[  3791.854] sna_accel_do_throttle -- no pending activity
>[  3791.866] sna_wakeup_handler
>[  3791.866] sna_block_handler (tv=0.014000)
>[  3791.866] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.866] sna_accel_do_flush -- no pending write to scanout
>[  3791.866] sna_accel_do_throttle -- no pending activity
>[  3791.872] sna_wakeup_handler
>[  3791.872] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.872] sna_block_handler (tv=0.008000)
>[  3791.872] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.872] sna_accel_do_flush -- no pending write to scanout
>[  3791.872] sna_accel_do_throttle -- no pending activity
>[  3791.874] sna_wakeup_handler
>[  3791.874] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.874] sna_copy_area: src=(514, 86)x(348, 592)+(0, 0) -> dst=(514, 86)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.874] sna_do_copy: src=(514, 86), dst=(514, 86), size=(348x592)
>[  3791.874] sna_do_copy: after drawable: src=(514, 86), dst=(651, 106), size=(348x592)
>[  3791.875] sna_do_copy: dst extents (651, 106), (999, 698)
>[  3791.875] sna_do_copy: dst clipped out
>[  3791.875] sna_block_handler (tv=0.005000)
>[  3791.875] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.875] sna_accel_do_flush -- no pending write to scanout
>[  3791.875] sna_accel_do_throttle -- no pending activity
>[  3791.878] sna_wakeup_handler
>[  3791.878] sna_block_handler (tv=0.014000)
>[  3791.878] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.878] sna_accel_do_flush -- no pending write to scanout
>[  3791.878] sna_accel_do_throttle -- no pending activity
>[  3791.891] sna_wakeup_handler
>[  3791.891] sna_block_handler (tv=0.014000)
>[  3791.891] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.891] sna_accel_do_flush -- no pending write to scanout
>[  3791.891] sna_accel_do_throttle -- no pending activity
>[  3791.898] sna_wakeup_handler
>[  3791.898] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.898] sna_block_handler (tv=0.007000)
>[  3791.898] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.898] sna_accel_do_flush -- no pending write to scanout
>[  3791.898] sna_accel_do_throttle -- no pending activity
>[  3791.900] sna_wakeup_handler
>[  3791.900] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.900] sna_copy_area: src=(860, 106)x(15, 560)+(0, 0) -> dst=(860, 106)+(137, 20); alu=3, pm=ffffffffffffffff
>[  3791.900] sna_do_copy: src=(860, 106), dst=(860, 106), size=(15x560)
>[  3791.900] sna_do_copy: after drawable: src=(860, 106), dst=(997, 126), size=(15x560)
>[  3791.900] sna_do_copy: dst extents (997, 126), (1012, 686)
>[  3791.900] sna_do_copy: dst clipped out
>[  3791.900] sna_block_handler (tv=0.005000)
>[  3791.900] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.900] sna_accel_do_flush -- no pending write to scanout
>[  3791.900] sna_accel_do_throttle -- no pending activity
>[  3791.903] sna_wakeup_handler
>[  3791.904] sna_block_handler (tv=0.013000)
>[  3791.904] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.904] sna_accel_do_flush -- no pending write to scanout
>[  3791.904] sna_accel_do_throttle -- no pending activity
>[  3791.905] sna_wakeup_handler
>[  3791.905] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.905] sna_change_window_attributes
>[  3791.905] sna_block_handler (tv=0.012000)
>[  3791.905] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.905] sna_accel_do_flush -- no pending write to scanout
>[  3791.905] sna_accel_do_throttle -- no pending activity
>[  3791.916] sna_wakeup_handler
>[  3791.916] sna_block_handler (tv=0.014000)
>[  3791.916] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.917] sna_accel_do_flush -- no pending write to scanout
>[  3791.917] sna_accel_do_throttle -- no pending activity
>[  3791.918] sna_wakeup_handler
>[  3791.918] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.918] sna_block_handler (tv=0.012000)
>[  3791.918] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.918] sna_accel_do_flush -- no pending write to scanout
>[  3791.918] sna_accel_do_throttle -- no pending activity
>[  3791.918] sna_wakeup_handler
>[  3791.918] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.918] sna_block_handler (tv=0.012000)
>[  3791.918] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.918] sna_accel_do_flush -- no pending write to scanout
>[  3791.918] sna_accel_do_throttle -- no pending activity
>[  3791.919] sna_wakeup_handler
>[  3791.919] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.919] sna_block_handler (tv=0.011000)
>[  3791.919] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  3791.919] sna_accel_do_flush -- no pending write to scanout
>[  3791.919] sna_accel_do_throttle -- no pending activity
>[  3791.920] sna_wakeup_handler
>[  3791.920] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  3791.920] sna_create_pixmap(32, 64, 1, usage=0)
>[  3791.920] kgem_can_create_2d: 32x64 @ 1
>[  3791.920] kgem_can_create_2d: unhandled depth 1
>[  3791.920] sna_create_pixmap: can not use GPU, just creating shadow
>[  3791.920] create_pixmap: allocating pixmap 32x64, depth=1, size=264
>[  3791.920] create_pixmap: serial=13312, usage=0, 32x64
>[  3791.920] sna_validate_gc changes=7fffff
>[  3791.920] sna_validate_gc: recomputing clip
>[  3791.920] sna_validate_gc: composite clip=1x[(0, 0), (32, 64)]
>[  3791.920] sna_put_image((0, 0)x(32, 64), depth=1, format=2)
>[  3791.920] sna_put_image: fallback -- unattached(0, 0, 32, 64)
>[  3791.921] sna_put_image: fallback
>[  3791.921] sna_gc_move_to_cpu, changes=7fffff
>[  3791.921] sna_gc_move_to_cpu: flushing stipple pixmap
>[  3791.921] sna_validate_pixmap: target bpp=1, source bpp=1
>[  3791.921] sna_validate_pixmap: flushing pixmap
>[  3791.921] sfbValidateGC changes=77ffff
>[  3791.921] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3791.921] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  3791.921] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  3791.921] sna_drawable_move_region_to_cpu(pixmap=13313 (32x64), [(0, 0), (32, 64)], flags=5)
>[  3791.921] sna_drawable_move_region_to_cpu: not attached to 0x30f5e80
>[  3791.921] sna_put_image: fbPutImage(0, 0, 32, 64)
>[  3791.921] sfbPutImage (0, 0)x(32, 64)
>[  3791.921] fbClipBoxes: box=(0, 0),(32, 64); region=(0, 0),(32, 64) x 1
>[  3791.921] fbDrawableRun: box=(0, 0), (32, 64), clip=(0, 0), (32, 64)
>[  3791.921] sfbBlt 32x64, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3791.921] sfbBlt fast blt, src_stride=4, dst_stride=4, width=4 (offset=459432)
>[  3791.921] sna_create_pixmap(32, 32, 1, usage=0)
>[  3791.921] kgem_can_create_2d: 32x32 @ 1
>[  3791.921] kgem_can_create_2d: unhandled depth 1
>[  3791.921] sna_create_pixmap: can not use GPU, just creating shadow
>[  3791.921] create_pixmap: allocating pixmap 32x32, depth=1, size=136
>[  3791.921] create_pixmap: serial=13314, usage=0, 32x32
>[  3791.921] sna_create_pixmap(32, 32, 1, usage=0)
>[  3791.921] kgem_can_create_2d: 32x32 @ 1
>[  3791.921] kgem_can_create_2d: unhandled depth 1
>[  3791.921] sna_create_pixmap: can not use GPU, just creating shadow
>[  3791.921] create_pixmap: allocating pixmap 32x32, depth=1, size=136
>[  3791.921] create_pixmap: serial=13316, usage=0, 32x32
>[  3791.921] sna_validate_gc changes=7fffff
>[  3791.921] sna_validate_gc: recomputing clip
>[  3791.921] sna_validate_gc: composite clip=1x[(0, 0), (32, 32)]
>[  3791.922] sna_copy_area: src=(0, 0)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=3, pm=ffffffffffffffff
>[  3791.922] sna_do_copy: src=(0, 0), dst=(0, 0), size=(32x32)
>[  3791.922] sna_do_copy: after drawable: src=(0, 0), dst=(0, 0), size=(32x32)
>[  3791.922] sna_do_copy: dst extents (0, 0), (32, 32)
>[  3791.922] sna_do_copy: pixmap -- no source clipping
>[  3791.922] sna_do_copy: src extents (0, 0), (32, 32) x 1
>[  3791.922] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  3791.922] sna_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 0), alu=3, src.size=32x64, dst.size=32x32)
>[  3791.922] sna_copy_boxes: dst=(priv=(nil), gpu_bo=0, cpu_bo=0), src=(priv=(nil), gpu_bo=0, cpu_bo=0), replaces=1
>[  3791.922] sna_copy_boxes: unattached dst failed, fallback
>[  3791.922] sna_copy_boxes__inplace - no, dst == src
>[  3791.922] sna_copy_boxes: fallback -- src=(0, 0), dst=(0, 0)
>[  3791.922] sna_copy_boxes: fallback -- miCopyRegion
>[  3791.922] sna_gc_move_to_cpu, changes=7fffff
>[  3791.922] sna_gc_move_to_cpu: flushing stipple pixmap
>[  3791.922] sna_validate_pixmap: target bpp=1, source bpp=1
>[  3791.922] sna_validate_pixmap: flushing pixmap
>[  3791.922] sfbValidateGC changes=77ffff
>[  3791.922] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3791.922] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  3791.922] sfbBlt 32x32, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3791.922] sfbBlt fast blt, src_stride=4, dst_stride=4, width=4 (offset=-400)
>[  3791.922] sna_validate_gc changes=0
>[  3791.922] sna_validate_gc: recomputing clip
>[  3791.922] sna_validate_gc: composite clip=1x[(0, 0), (32, 32)]
>[  3791.922] sna_copy_area: src=(0, 0)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=3, pm=ffffffffffffffff
>[  3791.922] sna_do_copy: src=(0, 0), dst=(0, 0), size=(32x32)
>[  3791.922] sna_do_copy: after drawable: src=(0, 0), dst=(0, 0), size=(32x32)
>[  3791.922] sna_do_copy: dst extents (0, 0), (32, 32)
>[  3791.922] sna_do_copy: pixmap -- no source clipping
>[  3791.922] sna_do_copy: src extents (0, 0), (32, 32) x 1
>[  3791.922] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  3791.922] sna_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 0), alu=3, src.size=32x64, dst.size=32x32)
>[  3791.922] sna_copy_boxes: dst=(priv=(nil), gpu_bo=0, cpu_bo=0), src=(priv=(nil), gpu_bo=0, cpu_bo=0), replaces=1
>[  3791.922] sna_copy_boxes: unattached dst failed, fallback
>[  3791.922] sna_copy_boxes__inplace - no, dst == src
>[  3791.922] sna_copy_boxes: fallback -- src=(0, 0), dst=(0, 0)
>[  3791.922] sna_copy_boxes: fallback -- miCopyRegion
>[  3791.922] sna_gc_move_to_cpu, changes=0
>[  3791.922] sfbValidateGC changes=0
>[  3791.922] sfbBlt 32x32, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3791.922] sfbBlt fast blt, src_stride=4, dst_stride=4, width=4 (offset=4393440)
>[  3791.922] sna_validate_gc changes=1
>[  3791.922] sna_copy_area: src=(0, 32)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=2, pm=ffffffffffffffff
>[  3791.922] sna_do_copy: src=(0, 32), dst=(0, 0), size=(32x32)
>[  3791.922] sna_do_copy: after drawable: src=(0, 32), dst=(0, 0), size=(32x32)
>[  3791.923] sna_do_copy: dst extents (0, 0), (32, 32)
>[  3791.923] sna_do_copy: pixmap -- no source clipping
>[  3791.923] sna_do_copy: src extents (0, 32), (32, 64) x 1
>[  3791.923] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  3791.923] sna_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 32), alu=2, src.size=32x64, dst.size=32x32)
>[  3791.923] sna_copy_boxes: dst=(priv=(nil), gpu_bo=0, cpu_bo=0), src=(priv=(nil), gpu_bo=0, cpu_bo=0), replaces=0
>[  3791.923] sna_copy_boxes: unattached dst failed, fallback
>[  3791.923] sna_copy_boxes__inplace - no, complex alu [2]
>[  3791.923] sna_copy_boxes: fallback -- src=(0, 32), dst=(0, 0)
>[  3791.923] sna_copy_boxes: fallback -- miCopyRegion
>[  3791.923] sna_gc_move_to_cpu, changes=1
>[  3791.923] sfbValidateGC changes=1
>[  3791.923] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3791.923] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=ffffffff, bgxor=ffffffff
>[  3791.923] sfbBlt 32x32, alu=2, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3791.923] sna_validate_gc changes=1
>[  3791.923] sna_copy_area: src=(0, 32)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=11, pm=ffffffffffffffff
>[  3791.923] sna_do_copy: src=(0, 32), dst=(0, 0), size=(32x32)
>[  3791.923] sna_do_copy: after drawable: src=(0, 32), dst=(0, 0), size=(32x32)
>[  3791.923] sna_do_copy: dst extents (0, 0), (32, 32)
>[  3791.923] sna_do_copy: pixmap -- no source clipping
>[  3791.923] sna_do_copy: src extents (0, 32), (32, 64) x 1
>[  3791.923] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  3791.923] sna_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 32), alu=11, src.size=32x64, dst.size=32x32)
>[  3791.923] sna_copy_boxes: dst=(priv=(nil), gpu_bo=0, cpu_bo=0), src=(priv=(nil), gpu_bo=0, cpu_bo=0), replaces=0
>[  3791.923] sna_copy_boxes: unattached dst failed, fallback
>[  3791.923] sna_copy_boxes__inplace - no, complex alu [11]
>[  3791.923] sna_copy_boxes: fallback -- src=(0, 32), dst=(0, 0)
>[  3791.923] sna_copy_boxes: fallback -- miCopyRegion
>[  3791.923] sna_gc_move_to_cpu, changes=1
>[  3791.923] sfbValidateGC changes=1
>[  3791.923] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3791.923] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=ffffffff, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  3791.923] sfbBlt 32x32, alu=11, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3791.923] sna_validate_gc changes=1
>[  3791.923] sna_validate_gc: recomputing clip
>[  3791.923] sna_validate_gc: composite clip=1x[(0, 0), (32, 64)]
>[  3791.923] sna_copy_area: src=(0, 32)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=1, pm=ffffffffffffffff
>[  3791.923] sna_do_copy: src=(0, 32), dst=(0, 0), size=(32x32)
>[  3791.923] sna_do_copy: after drawable: src=(0, 32), dst=(0, 0), size=(32x32)
>[  3791.923] sna_do_copy: dst extents (0, 0), (32, 32)
>[  3791.923] sna_do_copy: pixmap -- using gc clip
>[  3791.923] sna_do_copy: src extents (0, 32), (32, 64) x 1
>[  3791.923] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  3791.923] sna_self_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 32), alu=1, pix.size=32x64)
>[  3791.923] sna_self_copy_boxes: fallback_sna_pixmap_move_to_cpu(pixmap=13313, 32x64, flags=3)
>[  3791.923] _sna_pixmap_move_to_cpu: not attached
>[  3791.923] sna_gc_move_to_cpu, changes=1
>[  3791.924] sfbValidateGC changes=1
>[  3791.924] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  3791.924] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=ffffffff, bgxor=00000000
>[  3791.924] sfbBlt 32x32, alu=1, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  3791.924] sna_validate_gc changes=1
>[  3791.924] sna_copy_area: src=(0, 0)x(32, 32)+(0, 0) -> dst=(1, 1)+(0, 0); alu=7, pm=ffffffffffffffff
>[  3791.924] sna_do_copy: src=(0, 0), dst=(1, 1), size=(32x32)
>[  3791.924] sna_do_copy: after drawable: src=(0, 0), dst=(1, 1), size=(32x32)
>[  3791.924] sna_do_copy: dst extents (1, 1), (33, 33)
>[  3791.924] sna_do_copy: pixmap -- no source clipping
>[  3791.924] sna_do_copy: src extents (0, 0), (31, 32) x 1
>[  3791.924] sna_do_copy: copy region (1, 1), (32, 33) x 1
>[  3791.924] sna_copy_boxes (boxes=1x[(1, 1), (32, 33)...], src=+(-1, -1), alu=7, src.size=32x64, dst.size=32x32)
>[  3791.924] (EE) 
>Fatal server error:
>[  3791.924] (EE) sna_copy_boxes: damage box is beyond the pixmap: box=(1, 1), (32, 33), pixmap=(32, 32)
>[  3791.924] (EE) 
>[  3791.924] (EE) 
>Please consult the The X.Org Foundation support 
>	 at http://wiki.x.org
> for help. 
>[  3791.924] (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information.
>[  3791.924] (EE) 
>[  3791.924] (II) AIGLX: Suspending AIGLX clients for VT switch
>[  3791.924] sna_leave_vt
>[  3791.946] (EE) Server terminated with error (1). Closing log file.

Actions: View

Attachments on bug 70905: 88168 | 88930 | 89295 | 89297 | 89313 | 92365 | 92450 | 92469

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.