Attachment 92516 Details for Bug 73856 – Xorg.0.log-2.99.907-44-g720d131

Xorg.0.log-2.99.907-44-g720d131

Xorg.0.log-2.99.907-44-g720d131 (text/plain), 131.62 KB, created by Janusz on 2014-01-21 12:59:43 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Janusz

Created: 2014-01-21 12:59:43 UTC

Size: 131.62 KB

patch

obsolete

>[  2397.365] 
>X.Org X Server 1.15.0
>Release Date: 2013-12-27
>[  2397.365] X Protocol Version 11, Revision 0
>[  2397.365] Build Operating System: Linux 3.12.0-gentoo-gnu x86_64 Gentoo
>[  2397.365] Current Operating System: Linux asus 3.12.7-gentoo-gnu #1 SMP PREEMPT Fri Jan 17 15:51:11 CET 2014 x86_64
>[  2397.365] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.12.7-gentoo-gnu root=/dev/sda2 ro quiet nosplash rootfstype=ext4
>[  2397.365] Build Date: 06 January 2014  07:55:40PM
>[  2397.365]  
>[  2397.365] Current version of pixman: 0.32.4
>[  2397.365] 	Before reporting problems, check http://wiki.x.org
>	to make sure that you have the latest version.
>[  2397.365] Markers: (--) probed, (**) from config file, (==) default setting,
>	(++) from command line, (!!) notice, (II) informational,
>	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
>[  2397.365] (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jan 21 13:25:02 2014
>[  2397.366] (==) Using config directory: "/etc/X11/xorg.conf.d"
>[  2397.366] (==) Using system config directory "/usr/share/X11/xorg.conf.d"
>[  2397.366] (==) No Layout section.  Using the first Screen section.
>[  2397.366] (==) No screen section available. Using defaults.
>[  2397.366] (**) |-->Screen "Default Screen Section" (0)
>[  2397.366] (**) |   |-->Monitor "<default monitor>"
>[  2397.366] (==) No monitor specified for screen "Default Screen Section".
>	Using a default monitor configuration.
>[  2397.367] (==) Automatically adding devices
>[  2397.367] (==) Automatically enabling devices
>[  2397.367] (==) Automatically adding GPU devices
>[  2397.367] (==) 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/
>[  2397.367] (==) ModulePath set to "/usr/lib64/xorg/modules"
>[  2397.367] (II) The server relies on udev to provide the list of input devices.
>	If no devices become available, reconfigure udev or disable AutoAddDevices.
>[  2397.367] (II) Loader magic: 0x807c40
>[  2397.367] (II) Module ABI versions:
>[  2397.367] 	X.Org ANSI C Emulation: 0.4
>[  2397.367] 	X.Org Video Driver: 15.0
>[  2397.367] 	X.Org XInput driver : 20.0
>[  2397.367] 	X.Org Server Extension : 8.0
>[  2397.367] (II) xfree86: Adding drm device (/dev/dri/card0)
>[  2397.370] (--) PCI:*(0:0:2:0) 8086:2a42:1043:1862 rev 7, Mem @ 0xfe400000/4194304, 0xd0000000/268435456, I/O @ 0x0000dc00/8
>[  2397.371] (--) PCI: (0:0:2:1) 8086:2a43:1043:1862 rev 7, Mem @ 0xfe800000/1048576
>[  2397.371] Initializing built-in extension Generic Event Extension
>[  2397.371] Initializing built-in extension SHAPE
>[  2397.371] Initializing built-in extension MIT-SHM
>[  2397.371] Initializing built-in extension XInputExtension
>[  2397.371] Initializing built-in extension XTEST
>[  2397.371] Initializing built-in extension BIG-REQUESTS
>[  2397.371] Initializing built-in extension SYNC
>[  2397.371] Initializing built-in extension XKEYBOARD
>[  2397.371] Initializing built-in extension XC-MISC
>[  2397.371] Initializing built-in extension XINERAMA
>[  2397.371] Initializing built-in extension XFIXES
>[  2397.371] Initializing built-in extension RENDER
>[  2397.371] Initializing built-in extension RANDR
>[  2397.371] Initializing built-in extension COMPOSITE
>[  2397.371] Initializing built-in extension DAMAGE
>[  2397.371] Initializing built-in extension MIT-SCREEN-SAVER
>[  2397.371] Initializing built-in extension DOUBLE-BUFFER
>[  2397.371] Initializing built-in extension RECORD
>[  2397.371] Initializing built-in extension DPMS
>[  2397.371] Initializing built-in extension Present
>[  2397.371] Initializing built-in extension DRI3
>[  2397.371] Initializing built-in extension X-Resource
>[  2397.371] Initializing built-in extension XVideo
>[  2397.371] Initializing built-in extension XVideo-MotionCompensation
>[  2397.371] Initializing built-in extension XFree86-VidModeExtension
>[  2397.371] Initializing built-in extension XFree86-DGA
>[  2397.371] Initializing built-in extension XFree86-DRI
>[  2397.371] Initializing built-in extension DRI2
>[  2397.371] (II) LoadModule: "glx"
>[  2397.372] (II) Loading /usr/lib64/xorg/modules/extensions/libglx.so
>[  2397.374] (II) Module glx: vendor="X.Org Foundation"
>[  2397.374] 	compiled for 1.15.0, module version = 1.0.0
>[  2397.374] 	ABI class: X.Org Server Extension, version 8.0
>[  2397.374] (==) AIGLX enabled
>[  2397.374] Loading extension GLX
>[  2397.374] (==) Matched intel as autoconfigured driver 0
>[  2397.374] (==) Matched intel as autoconfigured driver 1
>[  2397.374] (==) Matched modesetting as autoconfigured driver 2
>[  2397.374] (==) Matched fbdev as autoconfigured driver 3
>[  2397.374] (==) Matched vesa as autoconfigured driver 4
>[  2397.374] (==) Assigned the driver to the xf86ConfigLayout
>[  2397.374] (II) LoadModule: "intel"
>[  2397.374] (II) Loading /usr/lib64/xorg/modules/drivers/intel_drv.so
>[  2397.375] (II) Module intel: vendor="X.Org Foundation"
>[  2397.375] 	compiled for 1.15.0, module version = 2.99.907
>[  2397.375] 	Module class: X.Org Video Driver
>[  2397.375] 	ABI class: X.Org Video Driver, version 15.0
>[  2397.375] (II) LoadModule: "modesetting"
>[  2397.376] (WW) Warning, couldn't open module modesetting
>[  2397.376] (II) UnloadModule: "modesetting"
>[  2397.376] (II) Unloading modesetting
>[  2397.376] (EE) Failed to load module "modesetting" (module does not exist, 0)
>[  2397.376] (II) LoadModule: "fbdev"
>[  2397.376] (WW) Warning, couldn't open module fbdev
>[  2397.376] (II) UnloadModule: "fbdev"
>[  2397.376] (II) Unloading fbdev
>[  2397.376] (EE) Failed to load module "fbdev" (module does not exist, 0)
>[  2397.376] (II) LoadModule: "vesa"
>[  2397.377] (WW) Warning, couldn't open module vesa
>[  2397.377] (II) UnloadModule: "vesa"
>[  2397.377] (II) Unloading vesa
>[  2397.377] (EE) Failed to load module "vesa" (module does not exist, 0)
>[  2397.377] (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
>[  2397.378] (II) intel: Driver for Intel(R) HD Graphics: 2000-5000
>[  2397.378] (II) intel: Driver for Intel(R) Iris(TM) Graphics: 5100
>[  2397.378] (II) intel: Driver for Intel(R) Iris(TM) Pro Graphics: 5200
>[  2397.378] (++) using VT number 7
>
>[  2397.386] sna_init_scrn: entity_num=0
>[  2397.386] (II) intel(0): SNA compiled from 2.99.907-44-g720d131
>[  2397.386] (II) intel(0): SNA compiled with assertions enabled
>[  2397.386] (II) intel(0): SNA compiled with memory allocation reporting enabled
>[  2397.386] (II) intel(0): SNA compiled with extra pixmap/damage validation
>[  2397.386] pixman version: 0.32.4
>[  2397.386] num_cores: processors=0x00000001, cores=0x00000003
>[  2397.386] sna_threads_init: creating a thread pool of 2 threads
>[  2397.387] sna_pre_init flags=0, numEntities=1
>[  2397.387] sna_acpi_open
>[  2397.388] (--) intel(0): Integrated Graphics Chipset: Intel(R) GM45
>[  2397.388] (--) intel(0): CPU: x86-64, sse2, sse3, ssse3
>[  2397.388] (II) intel(0): Creating default Display subsection in Screen section
>	"Default Screen Section" for depth/fbbpp 24/32
>[  2397.388] (==) intel(0): Depth 24, (--) framebuffer bpp 32
>[  2397.388] (==) intel(0): RGB weight 888
>[  2397.388] (==) intel(0): Default visual is TrueColor
>[  2397.388] kgem_init: fd=9, gen=37
>[  2397.388] kgem_init: has BLT ring? 0
>[  2397.388] kgem_init: has relaxed delta? 1
>[  2397.388] kgem_init: has relaxed fencing? 1
>[  2397.388] kgem_init: has shared last-level-cache? 0
>[  2397.388] kgem_init: has write-through caching for scanouts? 0
>[  2397.388] kgem_init: has set-cache-level? 1
>[  2397.388] kgem_init: has userptr? 0
>[  2397.388] kgem_init: has create2? 0
>[  2397.388] kgem_init: has no-reloc? 1
>[  2397.388] kgem_init: has handle-lut? 1
>[  2397.388] kgem_init: semaphores enabled? 0
>[  2397.388] kgem_init: can blt to cpu? 1
>[  2397.388] kgem_init: can render to Y-tiled surfaces? 0
>[  2397.388] kgem_init: can use privileged batchbuffers? 1
>[  2397.388] kgem_init: can use pinned batchbuffers (to avoid CS w/a)? 1
>[  2397.388] kgem_init_pinned_batches: new handle=1, num_pages=1
>[  2397.388] kgem_init_pinned_batches: new handle=2, num_pages=1
>[  2397.388] kgem_init_pinned_batches: new handle=3, num_pages=1
>[  2397.388] kgem_init_pinned_batches: new handle=4, num_pages=1
>[  2397.388] kgem_init_pinned_batches: new handle=5, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=6, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=7, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=8, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=9, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=10, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=11, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=12, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=13, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=14, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=15, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=16, num_pages=1
>[  2397.389] kgem_init_pinned_batches: new handle=17, num_pages=4
>[  2397.389] kgem_init_pinned_batches: new handle=18, num_pages=4
>[  2397.389] kgem_init_pinned_batches: new handle=19, num_pages=4
>[  2397.389] kgem_init_pinned_batches: new handle=20, num_pages=4
>[  2397.389] kgem_init: maximum batch size? 65528
>[  2397.389] kgem_init: last-level cache size: 1048576 bytes, threshold in pages: 128
>[  2397.389] kgem_init: cpu bo enabled 1: llc? 0, set-cache-level? 1, userptr? 0
>[  2397.389] kgem_init: aperture size 2147483648, available now 2142855168
>[  2397.389] kgem_init: aperture low=715827882 [682], high=1610612736 [1536]
>[  2397.389] kgem_init: aperture mappable=268435456 [256 MiB]
>[  2397.389] kgem_init: buffer size=262144 [256 KiB]
>[  2397.389] kgem_init: total ram=4081745920
>[  2397.389] kgem_init: maximum object size=1207959552
>[  2397.389] kgem_init: large object thresold=134217728
>[  2397.389] kgem_init: max object sizes (gpu=268435456, cpu=1207959552, tile upload=67108864, copy=134217728)
>[  2397.389] kgem_init: max fences=14
>[  2397.389] sna_pre_init: swapbuffer wait? enabled
>[  2397.389] sna_pre_init: vsync? enabled
>[  2397.389] sna_pre_init: page flips? enabled
>[  2397.389] sna_pre_init: triple buffer? enabled
>[  2397.389] (**) intel(0): Framebuffer tiled
>[  2397.390] (**) intel(0): Pixmaps tiled
>[  2397.390] (**) intel(0): "Tear free" disabled
>[  2397.390] (**) intel(0): Forcing per-crtc-pixmaps? no
>[  2397.390] sna_crtc_init
>[  2397.390] sna_crtc_init: created handle=21 for cursor on CRTC:3
>[  2397.390] sna_crtc_init: attached crtc[0] id=3, pipe=0
>[  2397.390] sna_crtc_init
>[  2397.390] sna_crtc_init: created handle=22 for cursor on CRTC:4
>[  2397.390] sna_crtc_init: attached crtc[1] id=4, pipe=1
>[  2397.390] sna_output_init(num=0)
>[  2397.390] (II) intel(0): Output LVDS1 has no monitor section
>[  2397.390] has_device_backlight: scanning /sys/bus/pci/devices/0000:00:02.0/backlight
>[  2397.390] has_device_backlight: acpi_video0
>[  2397.390] sna_output_backlight_get: level=1 (max=15)
>[  2397.390] (--) intel(0): found backlight control interface acpi_video0 (type 'firmware')
>[  2397.390] sna_output_init: created output 'LVDS1' 0 [5]  (possible crtc:3, possible clones:1), edid=0, dpms=2, crtc=3
>[  2397.390] sna_output_init(num=1)
>[  2397.390] (II) intel(0): Output VGA1 has no monitor section
>[  2397.390] sna_output_init: created output 'VGA1' 1 [11]  (possible crtc:7, possible clones:2), edid=0, dpms=2, crtc=0
>[  2397.390] sna_output_init(num=2)
>[  2397.390] (II) intel(0): Output HDMI1 has no monitor section
>[  2397.390] sna_output_init: created output 'HDMI1' 2 [14]  (possible crtc:7, possible clones:4), edid=0, dpms=2, crtc=0
>[  2397.390] sna_output_init(num=3)
>[  2397.390] (II) intel(0): Output DP1 has no monitor section
>[  2397.390] sna_output_init: created output 'DP1' 3 [18]  (possible crtc:7, possible clones:8), edid=0, dpms=2, crtc=0
>[  2397.391] sna_output_init(num=4)
>[  2397.391] (II) intel(0): Output DP2 has no monitor section
>[  2397.391] sna_output_init: created output 'DP2' 4 [20]  (possible crtc:7, possible clones:10), edid=0, dpms=2, crtc=0
>[  2397.391] sna_mode_compute_possible_outputs: updated output 'LVDS1' 0 [5] (possible crtc:3, possible clones:1)
>[  2397.391] sna_mode_compute_possible_outputs: updated output 'VGA1' 1 [11] (possible crtc:3, possible clones:2)
>[  2397.391] sna_mode_compute_possible_outputs: updated output 'HDMI1' 2 [14] (possible crtc:3, possible clones:4)
>[  2397.391] sna_mode_compute_possible_outputs: updated output 'DP1' 3 [18] (possible crtc:3, possible clones:8)
>[  2397.391] sna_mode_compute_possible_outputs: updated output 'DP2' 4 [20] (possible crtc:3, possible clones:10)
>[  2397.391] add_fake_output(late=0, num_fake=1)
>[  2397.391] (II) intel(0): Output VIRTUAL1 has no monitor section
>[  2397.391] crtc_init_gamma: CRTC:3, pipe=0: gamma set?=1
>[  2397.391] sna_probe_initial_configuration: CRTC:3, pipe=0: has mode?=1
>[  2397.391] kmode: 1366x768, clock=72000, 1366 1414 1446 1494 0, 768 771 777 803 0, flags=a, type=48
>[  2397.391] crtc_init_gamma: CRTC:4, pipe=1: gamma set?=0
>[  2397.391] sna_probe_initial_configuration: CRTC:4, pipe=1: has mode?=0
>[  2397.391] (--) intel(0): Output LVDS1 using initial mode 1366x768 on pipe 0
>[  2397.391] sna_probe_initial_configuration: SetScrnInfoModes = 0x213b140
>[  2397.391] (==) intel(0): DPI set to (96, 96)
>[  2397.391] (II) Loading sub module "dri2"
>[  2397.391] (II) LoadModule: "dri2"
>[  2397.391] (II) Module "dri2" already built-in
>[  2397.391] (==) Depth 24 pixmap format is 32 bpp
>[  2397.391] sna_screen_init
>[  2397.391] sna_accel_init
>[  2397.391] sna_picture_init
>[  2397.392] uploaded 85504 bytes of static state
>[  2397.392] kgem_create_linear(85504)
>[  2397.392] search_linear_cache: num_pages=21, flags=2, use_active? 0, use_large=0 [max=65536]
>[  2397.392] search_linear_cache: inactive and cache bucket empty
>[  2397.392] search_linear_cache: active cache bucket empty
>[  2397.392] kgem_create_linear: new handle=23, num_pages=21
>[  2397.392] gem_write(handle=23, offset=0, len=85504)
>[  2397.392] kgem_bo_write: flush=0, domain=1
>[  2397.392] __kgem_busy: handle=23, busy=0, wedged=0
>[  2397.392] kgem_bo_retire: retiring bo handle=23 (needed flush? 0), rq? 0 [busy?=0]
>[  2397.392] sna_accel_init(backend=Eaglelake (gen4.5), prefer_gpu=3)
>[  2397.392] (II) intel(0): SNA initialized with Eaglelake (gen4.5) backend
>[  2397.392] (==) intel(0): Backing store enabled
>[  2397.392] (==) intel(0): Silken mouse enabled
>[  2397.392] (II) intel(0): HW Cursor enabled
>[  2397.392] (II) intel(0): RandR 1.2 enabled, ignore the following RandR disabled message.
>[  2397.393] (==) intel(0): DPMS enabled
>[  2397.393] sna_video_sprite_setup: 0 sprite planes
>[  2397.393] (II) intel(0): [XvMC] xvmc_vld driver initialized.
>[  2397.393] sna_dri_open()
>[  2397.393] sna_dri_open: loading dri driver 'i965' [gen=37] for device '/dev/dri/card0'
>[  2397.393] (II) intel(0): [DRI2] Setup complete
>[  2397.393] (II) intel(0): [DRI2]   DRI driver: i965
>[  2397.393] (II) intel(0): [DRI2]   VDPAU driver: i965
>[  2397.393] (II) intel(0): direct rendering: DRI2 Enabled
>[  2397.393] sna_uevent_init
>[  2397.393] (==) intel(0): hotplug detection: "enabled"
>[  2397.393] sna_uevent_init: installed uvent handler
>[  2397.393] (--) RandR disabled
>[  2397.414] (II) AIGLX: enabled GLX_MESA_copy_sub_buffer
>[  2397.414] (II) AIGLX: enabled GLX_ARB_create_context
>[  2397.414] (II) AIGLX: enabled GLX_ARB_create_context_profile
>[  2397.414] (II) AIGLX: enabled GLX_EXT_create_context_es2_profile
>[  2397.414] (II) AIGLX: enabled GLX_INTEL_swap_event
>[  2397.414] (II) AIGLX: enabled GLX_SGI_swap_control and GLX_MESA_swap_control
>[  2397.414] (II) AIGLX: enabled GLX_EXT_framebuffer_sRGB
>[  2397.415] (II) AIGLX: enabled GLX_ARB_fbconfig_float
>[  2397.415] (II) AIGLX: GLX_EXT_texture_from_pixmap backed by buffer objects
>[  2397.415] (II) AIGLX: Loaded and initialized i965
>[  2397.415] (II) GLX: Initialized DRI2 GL provider for screen 0
>[  2397.415] sna_create_screen_resources(1366x768@24)
>[  2397.415] sna_accel_create
>[  2397.415] sna_glyphs_create
>[  2397.416] sna_create_pixmap(1024, 1024, 8, usage=11)
>[  2397.416] kgem_can_create_2d: 1024x1024 @ 8
>[  2397.416] kgem_can_create_2d: untiled size=1048576
>[  2397.416] kgem_choose_tiling: 1024x1024 -> 1
>[  2397.416] kgem_can_create_2d: tiled[1] size=1048576
>[  2397.416] sna_create_pixmap: usage=17, flags=b
>[  2397.416] sna_pixmap_create_scratch(1024, 1024, 8, tiling=2)
>[  2397.416] kgem_choose_tiling: 1024x1024 -> 1
>[  2397.416] create_pixmap: allocating pixmap 0x0, depth=8, size=0
>[  2397.416] create_pixmap: serial=1, usage=1, 0x0
>[  2397.416] kgem_create_2d(1024x1024, bpp=8, tiling=1, exact=0, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
>[  2397.416]   new pitch=1024, tiling=1, handle=24, id=1, num_pages=256 [256], bucket=8
>[  2397.416] __sna_damage_all(1024, 1024)
>[  2397.416] sna_create_pixmap(1024, 1024, 32, usage=11)
>[  2397.416] kgem_can_create_2d: 1024x1024 @ 32
>[  2397.416] kgem_can_create_2d: untiled size=4194304
>[  2397.416] kgem_choose_tiling: TLB miss between lines 1024x1024 (pitch=4096), forcing tiling 1
>[  2397.416] kgem_can_create_2d: tiled[-1] size=4194304
>[  2397.416] sna_create_pixmap: usage=17, flags=b
>[  2397.416] sna_pixmap_create_scratch(1024, 1024, 32, tiling=2)
>[  2397.416] kgem_choose_tiling: TLB miss between lines 1024x1024 (pitch=4096), forcing tiling 1
>[  2397.416] create_pixmap: allocating pixmap 0x0, depth=32, size=0
>[  2397.416] create_pixmap: serial=2, usage=1, 0x0
>[  2397.416] kgem_create_2d(1024x1024, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=0, prime?=0, temp?=1)
>[  2397.416]   new pitch=4096, tiling=1, handle=25, id=2, num_pages=1024 [1024], bucket=10
>[  2397.416] __sna_damage_all(1024, 1024)
>[  2397.416] sna_gradients_create
>[  2397.416] sna_alpha_cache_init
>[  2397.416] kgem_create_linear(1052)
>[  2397.416] search_linear_cache: num_pages=1, flags=2, use_active? 0, use_large=0 [max=65536]
>[  2397.416] search_linear_cache: inactive and cache bucket empty
>[  2397.417] search_linear_cache: active cache bucket empty
>[  2397.417] kgem_create_linear: new handle=26, num_pages=1
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=0, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=4, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=8, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=12, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=16, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=20, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=24, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=28, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=32, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=36, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=40, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=44, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=48, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=52, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=56, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=60, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=64, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=68, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=72, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=76, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=80, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=84, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=88, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=92, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=96, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=100, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=104, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=108, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=112, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=116, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=120, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=124, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=128, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=132, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=136, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=140, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=144, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=148, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=152, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=156, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=160, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=164, length=4, io=0
>[  2397.417] kgem_create_proxy: target handle=26 [proxy? -1], offset=168, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=172, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=176, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=180, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=184, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=188, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=192, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=196, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=200, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=204, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=208, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=212, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=216, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=220, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=224, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=228, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=232, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=236, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=240, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=244, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=248, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=252, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=256, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=260, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=264, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=268, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=272, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=276, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=280, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=284, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=288, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=292, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=296, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=300, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=304, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=308, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=312, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=316, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=320, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=324, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=328, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=332, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=336, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=340, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=344, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=348, length=4, io=0
>[  2397.418] kgem_create_proxy: target handle=26 [proxy? -1], offset=352, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=356, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=360, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=364, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=368, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=372, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=376, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=380, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=384, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=388, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=392, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=396, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=400, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=404, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=408, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=412, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=416, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=420, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=424, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=428, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=432, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=436, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=440, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=444, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=448, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=452, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=456, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=460, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=464, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=468, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=472, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=476, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=480, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=484, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=488, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=492, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=496, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=500, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=504, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=508, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=512, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=516, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=520, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=524, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=528, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=532, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=536, length=4, io=0
>[  2397.419] kgem_create_proxy: target handle=26 [proxy? -1], offset=540, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=544, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=548, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=552, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=556, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=560, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=564, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=568, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=572, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=576, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=580, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=584, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=588, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=592, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=596, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=600, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=604, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=608, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=612, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=616, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=620, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=624, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=628, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=632, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=636, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=640, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=644, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=648, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=652, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=656, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=660, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=664, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=668, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=672, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=676, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=680, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=684, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=688, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=692, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=696, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=700, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=704, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=708, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=712, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=716, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=720, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=724, length=4, io=0
>[  2397.420] kgem_create_proxy: target handle=26 [proxy? -1], offset=728, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=732, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=736, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=740, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=744, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=748, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=752, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=756, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=760, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=764, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=768, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=772, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=776, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=780, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=784, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=788, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=792, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=796, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=800, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=804, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=808, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=812, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=816, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=820, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=824, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=828, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=832, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=836, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=840, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=844, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=848, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=852, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=856, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=860, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=864, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=868, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=872, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=876, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=880, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=884, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=888, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=892, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=896, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=900, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=904, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=908, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=912, length=4, io=0
>[  2397.421] kgem_create_proxy: target handle=26 [proxy? -1], offset=916, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=920, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=924, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=928, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=932, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=936, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=940, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=944, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=948, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=952, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=956, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=960, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=964, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=968, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=972, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=976, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=980, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=984, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=988, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=992, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=996, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1000, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1004, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1008, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1012, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1016, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1020, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1024, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1028, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1032, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1036, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1040, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1044, length=4, io=0
>[  2397.422] kgem_create_proxy: target handle=26 [proxy? -1], offset=1048, length=4, io=0
>[  2397.422] gem_write(handle=26, offset=0, len=1052)
>[  2397.422] kgem_bo_write: flush=0, domain=1
>[  2397.422] __kgem_busy: handle=26, busy=0, wedged=0
>[  2397.422] kgem_bo_retire: retiring bo handle=26 (needed flush? 0), rq? 0 [busy?=0]
>[  2397.422] sna_solid_cache_init
>[  2397.422] kgem_create_linear(4096)
>[  2397.422] search_linear_cache: num_pages=1, flags=2, use_active? 0, use_large=0 [max=65536]
>[  2397.422] search_linear_cache: inactive and cache bucket empty
>[  2397.422] search_linear_cache: active cache bucket empty
>[  2397.422] kgem_create_linear: new handle=27, num_pages=1
>[  2397.422] sna_create_pixmap(1366, 768, 24, usage=10)
>[  2397.422] kgem_can_create_2d: 1366x768 @ 24
>[  2397.423] kgem_can_create_2d: untiled size=4198400
>[  2397.423] kgem_choose_tiling: TLB miss between lines 1366x768 (pitch=5464), forcing tiling 1
>[  2397.423] kgem_can_create_2d: tiled[-1] size=4198400
>[  2397.423] sna_create_pixmap: usage=16, flags=b
>[  2397.423] sna_create_pixmap: creating GPU pixmap 1366x768, stride=5464, flags=b
>[  2397.423] create_pixmap: allocating pixmap 0x0, depth=24, size=0
>[  2397.423] create_pixmap: serial=3, usage=16, 0x0
>[  2397.423] sna_pixmap_force_to_gpu(pixmap=0x216da30, flags=1)
>[  2397.423] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=41
>[  2397.423] sna_pixmap_move_to_gpu: CPU damage? 0
>[  2397.423] sna_pixmap_move_to_gpu: creating GPU bo (1366x768@32), create=b
>[  2397.423] kgem_create_2d(1366x768, bpp=32, tiling=1, exact=1, inactive=0, cpu-mapping=0, gtt-mapping=0, scanout?=1, prime?=0, temp?=0)
>[  2397.423]   new pitch=5632, tiling=1, handle=28, id=266, num_pages=1056 [1056], bucket=10
>[  2397.423] __sna_damage_all(1366, 768)
>[  2397.423] sna_pixmap_move_to_gpu: marking as all-damaged for GPU
>[  2397.423] sna_pixmap_mark_active: pixmap=3, handle=28
>[  2397.423] sna_set_screen_pixmap: changing from pixmap=0 to pixmap=3, (sna->front=0)
>[  2397.423] sna_copy_fbcon
>[  2397.423] sna_copy_fbcon: found fbcon, size=1366x768, depth=24, bpp=32
>[  2397.423] kgem_create_for_name(name=1)
>[  2397.423] kgem_create_for_name: new handle=30
>[  2397.423] sna_copy_fbcon: fbcon handle=30
>[  2397.423] gen4_render_copy_boxes x 1
>[  2397.423] sna_blt_copy_boxes src=(0, 0) -> (0, 0) x 1, tiling=(0, 1), pitch=(5504, 5632)
>[  2397.423]   sna_blt_copy_boxes: box=(0, 0)x(1366, 768)
>[  2397.423] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
>[  2397.423] kgem_add_handle: handle=28, index=0
>[  2397.423] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
>[  2397.423] kgem_add_reloc: handle=30, pos=7, delta=0, domains=00028000
>[  2397.423] kgem_add_handle: handle=30, index=1
>[  2397.423] batch[3/0, flags=1c00]: 10 10 65528 65528, nreloc=2, nexec=2, nfence=0, aperture=2088 [fenced=0]
>[  2397.423] gem_write__cachealigned(handle=16, offset=0, len=40)
>[  2397.431] kgem_commit: release handle=16 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=45d000
>[  2397.431] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=46e000
>[  2397.431] kgem_commit: release handle=30 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=46000
>[  2397.431] _kgem_bo_destroy: handle=30, proxy? 0
>[  2397.431] __kgem_bo_destroy: handle=30
>[  2397.431] __kgem_bo_destroy: handle=30, not reusable
>[  2397.431] kgem_bo_free: handle=30
>[  2397.431] kgem_bo_free: releasing (nil):(nil) vma for handle=30, count=0
>[  2397.431] sna_set_desired_mode
>[  2397.431] (II) intel(0): switch to mode 1366x768@60.0 on LVDS1 using pipe 0, position (0, 0), rotation normal, reflection none
>[  2397.431] sna_pixmap_force_to_gpu(pixmap=0x216da30, flags=3)
>[  2397.431] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
>[  2397.431] sna_pixmap_move_to_gpu: already all-damaged
>[  2397.431] sna_pixmap_mark_active: pixmap=3, handle=28
>[  2397.431] use_shadow? bounds (0, 0), (1366, 768), framebufer 1366x768
>[  2397.431] sna_crtc_attach: attaching to framebuffer
>[  2397.431] sna_pixmap_force_to_gpu(pixmap=0x216da30, flags=3)
>[  2397.431] sna_pixmap_move_to_gpu(pixmap=3, usage=16), flags=43
>[  2397.431] sna_pixmap_move_to_gpu: already all-damaged
>[  2397.431] sna_pixmap_mark_active: pixmap=3, handle=28
>[  2397.431] get_fb: create fb 1366x768@24/32
>[  2397.432] sna_crtc_apply
>[  2397.432] sna_crtc_apply: attaching output 'LVDS1' 0 [5] to crtc:3 (pipe 0) (possible crtc:3, possible clones:1)
>[  2397.432] sna_crtc_apply: applying crtc [3, pipe=0] mode=1366x768+0+0@72000, fb=23 update to 1 outputs [5...]
>[  2397.446] sna_crtc_force_outputs_on(pipe=0), currently? 3
>[  2397.446] sna_output_dpms(LVDS1): dpms=0 (current: 3), active? 1
>[  2397.446] sna_output_dpms_backlight(LVDS1) -- 3 -> 0
>[  2397.446] sna_output_backlight_set: level=1, max=15
>[  2397.449] sna_crtc_randr: transform? 0, bounds (0, 0), (1366, 768)
>[  2397.449] sna_output_dpms(VGA1): dpms=3 (current: 3), active? 0
>[  2397.449] sna_output_dpms(HDMI1): dpms=3 (current: 3), active? 0
>[  2397.449] sna_output_dpms(DP1): dpms=3 (current: 3), active? 0
>[  2397.449] sna_output_dpms(DP2): dpms=3 (current: 3), active? 0
>[  2397.449] sna_crtc_dpms(pipe 1, dpms mode -> 3):= active=0
>[  2397.449] sna_crtc_config_notify
>[  2397.449] sna_mode_update: crtc=3, valid?=1, fb attached?=23, expected=23
>[  2397.449] sna_mode_update: crtc=4, valid?=0, fb attached?=0, expected=0
>[  2397.449] update_flush_interval: CRTC:0 (pipe 0) vrefresh=60.015904
>[  2397.449] update_flush_interval: CRTC:1 (pipe 1) disabled
>[  2397.450] max_vrefresh=60, vblank_interval=16 ms
>[  2397.450] sna_mode_update: crtc=3, valid?=1, fb attached?=23, expected=23
>[  2397.450] sna_mode_update: crtc=4, valid?=0, fb attached?=0, expected=0
>[  2397.450] update_flush_interval: CRTC:0 (pipe 0) vrefresh=60.015904
>[  2397.450] update_flush_interval: CRTC:1 (pipe 1) disabled
>[  2397.450] max_vrefresh=60, vblank_interval=16 ms
>[  2397.450] (II) intel(0): Setting screen physical size to 361 x 203
>[  2397.450] sna_mode_resize (1366, 768) -> (1366, 768)
>[  2397.450] sna_create_pixmap(16, 16, 1, usage=0)
>[  2397.450] kgem_can_create_2d: 16x16 @ 1
>[  2397.450] kgem_can_create_2d: unhandled depth 1
>[  2397.450] sna_create_pixmap: can not use GPU, just creating shadow
>[  2397.450] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  2397.450] create_pixmap: serial=4, usage=0, 16x16
>[  2397.450] sna_validate_gc(0x216dd00) changes=7fffff, previous serial=80000000, drawable=4
>[  2397.450] sna_validate_gc: recomputing clip
>[  2397.450] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x2160500]
>[  2397.450] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  2397.450] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  2397.450] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  2397.450] sna_poly_fill_rect: fallback -- unattached
>[  2397.450] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  2397.450] sna_gc_move_to_cpu(0x216dd00) changes=7fffff
>[  2397.450] sfbValidateGC changes=77ffff
>[  2397.450] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  2397.450] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  2397.450] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  2397.450] sna_drawable_move_region_to_cpu(pixmap=4 (16x16), [(0, 0), (16, 16)], flags=5)
>[  2397.450] sna_drawable_move_region_to_cpu: not attached to pixmap 4 (depth 1)
>[  2397.450] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  2397.450] sfbPolyFillRect x 1
>[  2397.450] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  2397.450] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  2397.450] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  2397.450] fbFillBox box=(0, 0), (16, 16)
>[  2397.450] sfbFill (0, 0)x(16, 16), style=0
>[  2397.450] sna_gc_move_to_gpu(0x216dd00)
>[  2397.577] sna_realize_font (key=0)
>[  2397.578] sna_realize_font (key=0)
>[  2397.578] sna_create_pixmap(16, 16, 1, usage=1)
>[  2397.578] kgem_can_create_2d: 16x16 @ 1
>[  2397.578] kgem_can_create_2d: unhandled depth 1
>[  2397.578] sna_create_pixmap: can not use GPU, just creating shadow
>[  2397.578] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  2397.578] create_pixmap: serial=6, usage=1, 16x16
>[  2397.578] sna_validate_gc(0x216dd00) changes=7fffff, previous serial=80000000, drawable=6
>[  2397.578] sna_validate_gc: recomputing clip
>[  2397.578] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x2160500]
>[  2397.578] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  2397.578] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  2397.578] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  2397.578] sna_poly_fill_rect: fallback -- unattached
>[  2397.578] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  2397.578] sna_gc_move_to_cpu(0x216dd00) changes=7fffff
>[  2397.578] sfbValidateGC changes=77ffff
>[  2397.578] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2397.578] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  2397.578] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  2397.578] sna_drawable_move_region_to_cpu(pixmap=6 (16x16), [(0, 0), (16, 16)], flags=5)
>[  2397.578] sna_drawable_move_region_to_cpu: not attached to pixmap 6 (depth 1)
>[  2397.578] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  2397.578] sfbPolyFillRect x 1
>[  2397.578] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  2397.578] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  2397.578] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  2397.578] fbFillBox box=(0, 0), (16, 16)
>[  2397.578] sfbFill (0, 0)x(16, 16), style=0
>[  2397.578] sna_gc_move_to_gpu(0x216dd00)
>[  2397.578] sna_validate_gc(0x216dd00) changes=4, previous serial=80000004, drawable=6
>[  2397.578] sna_validate_gc: recomputing clip
>[  2397.578] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x2160500]
>[  2397.578] sna_font_too_large? (16 + 17) x 17: 99 > 124
>[  2397.578] sna_glyph_blt (7, 7) x 1, fg=00000001, bg=ffffffff alu=cc
>[  2397.578] sna_drawable_use_bo pixmap=6, box=((0, 0), (16, 16)), flags=1...
>[  2397.578] sna_drawable_use_bo: not attached
>[  2397.578] sna_poly_text16: fallback
>[  2397.578] sna_gc_move_to_cpu(0x216dd00) changes=4
>[  2397.578] sfbValidateGC changes=4
>[  2397.578] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  2397.578] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  2397.578] sna_drawable_move_region_to_cpu(pixmap=6 (16x16), [(0, 0), (16, 16)], flags=3)
>[  2397.579] sna_drawable_move_region_to_cpu: not attached to pixmap 6 (depth 1)
>[  2397.579] sna_poly_text16: fallback -- fbPolyGlyphBlt
>[  2397.579] sfbPolyGlyphBlt x 1
>[  2397.579] sfbPushImage (0, 0)x(16, 16)
>[  2397.579] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  2397.579] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  2397.579] sfbBlt 16x16, alu=7, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2397.579] sna_gc_move_to_gpu(0x216dd00)
>[  2397.579] sna_get_image: pixmap=6 (0, 0)x(16, 16), format=1, mask=1, depth=1
>[  2397.579] sna_drawable_move_region_to_cpu(pixmap=6 (16x16), [(0, 0), (16, 16)], flags=34)
>[  2397.579] sna_drawable_move_region_to_cpu: not attached to pixmap 6 (depth 1)
>[  2397.579] sfbGetImage (0, 0)x(16, 16)
>[  2397.579] sfbBlt 16x16, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2397.579] sfbBlt fast blt, src_stride=4, dst_stride=4, width=2 (offset=2075184)
>[  2397.579] sna_destroy_pixmap: pixmap=6, attached?=0
>[  2397.579] sna_create_pixmap(16, 16, 1, usage=1)
>[  2397.579] kgem_can_create_2d: 16x16 @ 1
>[  2397.579] kgem_can_create_2d: unhandled depth 1
>[  2397.579] sna_create_pixmap: can not use GPU, just creating shadow
>[  2397.579] create_pixmap: allocating pixmap 16x16, depth=1, size=72
>[  2397.579] create_pixmap: serial=7, usage=1, 16x16
>[  2397.579] sna_validate_gc(0x216dd00) changes=7fffff, previous serial=80000000, drawable=7
>[  2397.579] sna_validate_gc: recomputing clip
>[  2397.579] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x2160500]
>[  2397.579] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  2397.579] sna_poly_fill_rect_extents: [0] = (0, 0)x(16, 16)
>[  2397.579] sna_poly_fill_rect: extents(0, 0), (16, 16), flags=1
>[  2397.579] sna_poly_fill_rect: fallback -- unattached
>[  2397.579] sna_poly_fill_rect: fallback (0, 0), (16, 16)
>[  2397.579] sna_gc_move_to_cpu(0x216dd00) changes=7fffff
>[  2397.579] sfbValidateGC changes=77ffff
>[  2397.579] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2397.579] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  2397.579] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  2397.579] sna_drawable_move_region_to_cpu(pixmap=7 (16x16), [(0, 0), (16, 16)], flags=5)
>[  2397.579] sna_drawable_move_region_to_cpu: not attached to pixmap 7 (depth 1)
>[  2397.579] sna_poly_fill_rect: fallback - fbPolyFillRect
>[  2397.579] sfbPolyFillRect x 1
>[  2397.579] sfbPolyFillRect: rectangle (0, 0), (16, 16)
>[  2397.579] fbClipBoxes: box=(0, 0),(16, 16); region=(0, 0),(16, 16) x 1
>[  2397.579] fbDrawableRun: box=(0, 0), (16, 16), clip=(0, 0), (16, 16)
>[  2397.579] fbFillBox box=(0, 0), (16, 16)
>[  2397.579] sfbFill (0, 0)x(16, 16), style=0
>[  2397.579] sna_gc_move_to_gpu(0x216dd00)
>[  2397.579] sna_validate_gc(0x216dd00) changes=4, previous serial=80000006, drawable=7
>[  2397.579] sna_validate_gc: recomputing clip
>[  2397.579] sna_validate_gc: composite clip=1x[(0, 0), (16, 16)] [0x2160500]
>[  2397.580] sna_font_too_large? (16 + 17) x 17: 99 > 124
>[  2397.580] sna_glyph_blt (7, 7) x 1, fg=00000001, bg=ffffffff alu=cc
>[  2397.580] sna_drawable_use_bo pixmap=7, box=((1, 1), (15, 15)), flags=1...
>[  2397.580] sna_drawable_use_bo: not attached
>[  2397.580] sna_poly_text16: fallback
>[  2397.580] sna_gc_move_to_cpu(0x216dd00) changes=4
>[  2397.580] sfbValidateGC changes=4
>[  2397.580] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000001, bg=00000001, planemask=ffffffff
>[  2397.580] sfbValidateGC: rrop fg=ffffffff, bg=ffffffff, pm=ffffffff, and=00000000, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  2397.580] sna_drawable_move_region_to_cpu(pixmap=7 (16x16), [(1, 1), (15, 15)], flags=3)
>[  2397.580] sna_drawable_move_region_to_cpu: not attached to pixmap 7 (depth 1)
>[  2397.580] sna_poly_text16: fallback -- fbPolyGlyphBlt
>[  2397.580] sfbPolyGlyphBlt x 1
>[  2397.580] sfbPushImage (1, 1)x(14, 14)
>[  2397.580] fbClipBoxes: box=(1, 1),(15, 15); region=(1, 1),(15, 15) x 1
>[  2397.580] fbDrawableRun: box=(1, 1), (15, 15), clip=(1, 1), (15, 15)
>[  2397.580] sfbBlt 14x14, alu=7, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2397.580] sna_gc_move_to_gpu(0x216dd00)
>[  2397.580] sna_get_image: pixmap=7 (0, 0)x(16, 16), format=1, mask=1, depth=1
>[  2397.580] sna_drawable_move_region_to_cpu(pixmap=7 (16x16), [(0, 0), (16, 16)], flags=34)
>[  2397.580] sna_drawable_move_region_to_cpu: not attached to pixmap 7 (depth 1)
>[  2397.580] sfbGetImage (0, 0)x(16, 16)
>[  2397.580] sfbBlt 16x16, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2397.580] sfbBlt fast blt, src_stride=4, dst_stride=4, width=2 (offset=752)
>[  2397.580] sna_destroy_pixmap: pixmap=7, attached?=0
>[  2397.580] sna_change_window_attributes
>[  2397.580] sna_validate_gc(0x216de00) changes=7fffff, previous serial=80000000, drawable=5
>[  2397.580] sna_validate_gc: recomputing clip
>[  2397.580] sna_validate_gc: composite clip=1x[(0, 0), (1366, 768)] [0x216e620]
>[  2397.580] sna_poly_fill_rect(n=1, PlaneMask: ffffffffffffffff (solid 1), solid fill: 1 [style=0, tileIsPixel=1], alu=3)
>[  2397.580] sna_poly_fill_rect_extents: [0] = (0, 0)x(1366, 768)
>[  2397.580] sna_poly_fill_rect: extents(0, 0), (1366, 768), flags=1
>[  2397.580] discard_cpu_damage: discarding existing CPU damage
>[  2397.580] sna_poly_fill_rect: promoting to full GPU
>[  2397.580] sna_poly_fill_rect: dropping last-cpu hint
>[  2397.580] sna_drawable_use_bo pixmap=3, box=((0, 0), (1366, 768)), flags=19...
>[  2397.580] sna_drawable_use_bo: flush=0, shm=0, cpu=0 => flags=9
>[  2397.580] sna_drawable_use_bo: use GPU fast path (all-damaged)
>[  2397.580] sna_drawable_use_bo: using whole GPU bo
>[  2397.580] sna_poly_fill_rect: solid fill [00000000], testing for blt
>[  2397.580] sna_poly_fill_rect_blt x 1 [(0, 0)x(1366, 768)...]+(0,0), clipped?=0
>[  2397.580] gen4_render_fill_one: color=00000000
>[  2397.580] sna_blt_fill_boxes (32, 00000000, 3) x 1
>[  2397.580] sna_blt_fill_box: box=((0, 0), (1366, 768))
>[  2397.580] kgem_add_reloc: handle=28, pos=4, delta=0, domains=00028002
>[  2397.580] kgem_add_handle: handle=28, index=0
>[  2397.581] __kgem_bo_mark_dirty: handle=28 (proxy? 0)
>[  2397.581] sna_poly_fill_rect_blt: pixmap=3, marking clear [00000000]
>[  2397.635] (II) config/udev: Adding input device Power Button (/dev/input/event2)
>[  2397.635] (**) Power Button: Applying InputClass "evdev keyboard catchall"
>[  2397.635] (II) LoadModule: "evdev"
>[  2397.635] (II) Loading /usr/lib64/xorg/modules/input/evdev_drv.so
>[  2397.636] (II) Module evdev: vendor="X.Org Foundation"
>[  2397.636] 	compiled for 1.15.0, module version = 2.8.1
>[  2397.636] 	Module class: X.Org XInput Driver
>[  2397.636] 	ABI class: X.Org XInput driver, version 20.0
>[  2397.636] (II) Using input driver 'evdev' for 'Power Button'
>[  2397.636] (**) Power Button: always reports core events
>[  2397.636] (**) evdev: Power Button: Device: "/dev/input/event2"
>[  2397.636] (--) evdev: Power Button: Vendor 0 Product 0x1
>[  2397.636] (--) evdev: Power Button: Found keys
>[  2397.636] (II) evdev: Power Button: Configuring as keyboard
>[  2397.636] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/LNXPWRBN:00/input/input2/event2"
>[  2397.636] (II) XINPUT: Adding extended input device "Power Button" (type: KEYBOARD, id 6)
>[  2397.636] (**) Option "xkb_rules" "evdev"
>[  2397.636] (**) Option "xkb_model" "pc104"
>[  2397.636] (**) Option "xkb_layout" "us"
>[  2397.688] (II) config/udev: Adding input device Video Bus (/dev/input/event3)
>[  2397.688] (**) Video Bus: Applying InputClass "evdev keyboard catchall"
>[  2397.688] (II) Using input driver 'evdev' for 'Video Bus'
>[  2397.688] (**) Video Bus: always reports core events
>[  2397.688] (**) evdev: Video Bus: Device: "/dev/input/event3"
>[  2397.688] (--) evdev: Video Bus: Vendor 0 Product 0x6
>[  2397.688] (--) evdev: Video Bus: Found keys
>[  2397.688] (II) evdev: Video Bus: Configuring as keyboard
>[  2397.688] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/device:00/PNP0A08:00/LNXVIDEO:00/input/input3/event3"
>[  2397.688] (II) XINPUT: Adding extended input device "Video Bus" (type: KEYBOARD, id 7)
>[  2397.688] (**) Option "xkb_rules" "evdev"
>[  2397.688] (**) Option "xkb_model" "pc104"
>[  2397.688] (**) Option "xkb_layout" "us"
>[  2397.689] (II) config/udev: Adding input device Lid Switch (/dev/input/event1)
>[  2397.689] (II) No input driver specified, ignoring this device.
>[  2397.689] (II) This device may have been added with another device file.
>[  2397.690] (II) config/udev: Adding input device Sleep Button (/dev/input/event0)
>[  2397.690] (**) Sleep Button: Applying InputClass "evdev keyboard catchall"
>[  2397.690] (II) Using input driver 'evdev' for 'Sleep Button'
>[  2397.690] (**) Sleep Button: always reports core events
>[  2397.690] (**) evdev: Sleep Button: Device: "/dev/input/event0"
>[  2397.690] (--) evdev: Sleep Button: Vendor 0 Product 0x3
>[  2397.690] (--) evdev: Sleep Button: Found keys
>[  2397.690] (II) evdev: Sleep Button: Configuring as keyboard
>[  2397.690] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/device:00/PNP0C0E:00/input/input0/event0"
>[  2397.690] (II) XINPUT: Adding extended input device "Sleep Button" (type: KEYBOARD, id 8)
>[  2397.690] (**) Option "xkb_rules" "evdev"
>[  2397.690] (**) Option "xkb_model" "pc104"
>[  2397.690] (**) Option "xkb_layout" "us"
>[  2397.691] (II) config/udev: Adding drm device (/dev/dri/card0)
>[  2397.692] (II) config/udev: Adding input device Asus Laptop extra buttons (/dev/input/event4)
>[  2397.692] (**) Asus Laptop extra buttons: Applying InputClass "evdev keyboard catchall"
>[  2397.692] (II) Using input driver 'evdev' for 'Asus Laptop extra buttons'
>[  2397.692] (**) Asus Laptop extra buttons: always reports core events
>[  2397.692] (**) evdev: Asus Laptop extra buttons: Device: "/dev/input/event4"
>[  2397.692] (--) evdev: Asus Laptop extra buttons: Vendor 0 Product 0
>[  2397.692] (--) evdev: Asus Laptop extra buttons: Found keys
>[  2397.692] (II) evdev: Asus Laptop extra buttons: Configuring as keyboard
>[  2397.692] (**) Option "config_info" "udev:/sys/devices/platform/asus_laptop/input/input5/event4"
>[  2397.692] (II) XINPUT: Adding extended input device "Asus Laptop extra buttons" (type: KEYBOARD, id 9)
>[  2397.692] (**) Option "xkb_rules" "evdev"
>[  2397.692] (**) Option "xkb_model" "pc104"
>[  2397.692] (**) Option "xkb_layout" "us"
>[  2397.693] (II) config/udev: Adding input device AT Translated Set 2 keyboard (/dev/input/event5)
>[  2397.693] (**) AT Translated Set 2 keyboard: Applying InputClass "evdev keyboard catchall"
>[  2397.693] (II) Using input driver 'evdev' for 'AT Translated Set 2 keyboard'
>[  2397.693] (**) AT Translated Set 2 keyboard: always reports core events
>[  2397.694] (**) evdev: AT Translated Set 2 keyboard: Device: "/dev/input/event5"
>[  2397.694] (--) evdev: AT Translated Set 2 keyboard: Vendor 0x1 Product 0x1
>[  2397.694] (--) evdev: AT Translated Set 2 keyboard: Found keys
>[  2397.694] (II) evdev: AT Translated Set 2 keyboard: Configuring as keyboard
>[  2397.694] (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio0/input/input4/event5"
>[  2397.694] (II) XINPUT: Adding extended input device "AT Translated Set 2 keyboard" (type: KEYBOARD, id 10)
>[  2397.694] (**) Option "xkb_rules" "evdev"
>[  2397.694] (**) Option "xkb_model" "pc104"
>[  2397.694] (**) Option "xkb_layout" "us"
>[  2397.694] (II) config/udev: Adding input device ETPS/2 Elantech Touchpad (/dev/input/event6)
>[  2397.694] (**) ETPS/2 Elantech Touchpad: Applying InputClass "evdev touchpad catchall"
>[  2397.694] (**) ETPS/2 Elantech Touchpad: Applying InputClass "touchpad catchall"
>[  2397.694] (**) ETPS/2 Elantech Touchpad: Applying InputClass "Default clickpad buttons"
>[  2397.694] (II) LoadModule: "synaptics"
>[  2397.694] (II) Loading /usr/lib64/xorg/modules/input/synaptics_drv.so
>[  2397.694] (II) Module synaptics: vendor="X.Org Foundation"
>[  2397.694] 	compiled for 1.15.0, module version = 1.7.1
>[  2397.694] 	Module class: X.Org XInput Driver
>[  2397.694] 	ABI class: X.Org XInput driver, version 20.0
>[  2397.694] (II) Using input driver 'synaptics' for 'ETPS/2 Elantech Touchpad'
>[  2397.694] (**) ETPS/2 Elantech Touchpad: always reports core events
>[  2397.694] (**) Option "Device" "/dev/input/event6"
>[  2397.721] (II) synaptics: ETPS/2 Elantech Touchpad: ignoring touch events for semi-multitouch device
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: x-axis range 0 - 1088 (res 0)
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: y-axis range 0 - 704 (res 0)
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: pressure range 0 - 255
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: finger width range 0 - 15
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: buttons: left right double triple
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: Vendor 0x2 Product 0xe
>[  2397.721] (--) synaptics: ETPS/2 Elantech Touchpad: touchpad found
>[  2397.721] (**) ETPS/2 Elantech Touchpad: always reports core events
>[  2397.730] (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio4/input/input14/event6"
>[  2397.730] (II) XINPUT: Adding extended input device "ETPS/2 Elantech Touchpad" (type: TOUCHPAD, id 11)
>[  2397.730] (**) synaptics: ETPS/2 Elantech Touchpad: (accel) MinSpeed is now constant deceleration 2.5
>[  2397.730] (**) synaptics: ETPS/2 Elantech Touchpad: (accel) MaxSpeed is now 1.75
>[  2397.730] (**) synaptics: ETPS/2 Elantech Touchpad: (accel) AccelFactor is now 0.154
>[  2397.730] (**) ETPS/2 Elantech Touchpad: (accel) keeping acceleration scheme 1
>[  2397.730] (**) ETPS/2 Elantech Touchpad: (accel) acceleration profile 1
>[  2397.730] (**) ETPS/2 Elantech Touchpad: (accel) acceleration factor: 2.000
>[  2397.730] (**) ETPS/2 Elantech Touchpad: (accel) acceleration threshold: 4
>[  2397.730] (--) synaptics: ETPS/2 Elantech Touchpad: touchpad found
>[  2397.731] (II) config/udev: Adding input device ETPS/2 Elantech Touchpad (/dev/input/mouse0)
>[  2397.731] (**) ETPS/2 Elantech Touchpad: Ignoring device from InputClass "touchpad ignore duplicates"
>[  2397.739] sna_crtc_dpms(pipe 0, dpms mode -> 0):= active=1
>[  2397.739] sna_output_dpms(LVDS1): dpms=0 (current: 0), active? 1
>[  2397.739] sna_block_handler (tv=600.000000)
>[  2397.739] kgem_retire, need_retire?=1
>[  2397.739] kgem_retire__flushing: 0 bo on flushing list
>[  2397.739] __kgem_busy: handle=16, busy=0, wedged=0
>[  2397.739] __kgem_retire_rq: request 16 complete
>[  2397.739] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
>[  2397.739] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  2397.739] kgem_retire -- retired=0, need_retire=0
>[  2397.739] sna_accel_block_handler: GPU idle, flushing
>[  2397.739] batch[3/0, flags=1c00]: 8 8 65528 65528, nreloc=1, nexec=1, nfence=0, aperture=1056 [fenced=0]
>[  2397.739] gem_write__cachealigned(handle=15, offset=0, len=32)
>[  2397.739] kgem_commit: release handle=15 (proxy? 0), dirty? 0 flush? 0, snoop? 0 -> offset=45c000
>[  2397.739] kgem_commit: release handle=28 (proxy? 0), dirty? 1 flush? 1, snoop? 0 -> offset=46e000
>[  2397.739] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.739] sna_accel_do_flush -- no pending write to scanout
>[  2397.739] __kgem_busy: handle=28, busy=65537, wedged=0
>[  2397.739] __kgem_flush: handle=28, busy?=1
>[  2397.739] timer_enable (time=2397739), starting timer 1
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  2397.741] sna_block_handler (tv=599.998000)
>[  2397.741] kgem_retire, need_retire?=1
>[  2397.741] kgem_retire__flushing: 0 bo on flushing list
>[  2397.741] __kgem_busy: handle=15, busy=65537, wedged=0
>[  2397.741] kgem_retire__requests_ring: ring=0, 1 outstanding requests, oldest=15
>[  2397.741] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  2397.741] kgem_retire -- retired=0, need_retire=1
>[  2397.741] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.741] sna_accel_do_flush -- no pending write to scanout
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  2397.741] sna_block_handler (tv=0.000000)
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=1, need_purge=0
>[  2397.741] sna_block_handler (tv=599.998000)
>[  2397.741] kgem_retire, need_retire?=1
>[  2397.741] kgem_retire__flushing: 0 bo on flushing list
>[  2397.741] __kgem_busy: handle=15, busy=0, wedged=0
>[  2397.741] __kgem_retire_rq: request 15 complete
>[  2397.741] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
>[  2397.741] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  2397.741] kgem_retire -- retired=0, need_retire=0
>[  2397.741] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.741] sna_accel_do_flush -- no pending write to scanout
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2397.741] sna_block_handler (tv=599.998000)
>[  2397.741] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.741] sna_accel_do_flush -- no pending write to scanout
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2397.741] sna_block_handler (tv=599.998000)
>[  2397.741] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.741] sna_accel_do_flush -- no pending write to scanout
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2397.741] sna_block_handler (tv=599.998000)
>[  2397.741] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.741] sna_accel_do_flush -- no pending write to scanout
>[  2397.741] sna_wakeup_handler
>[  2397.741] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2397.742] sna_block_handler (tv=599.997000)
>[  2397.742] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.742] sna_accel_do_flush -- no pending write to scanout
>[  2397.742] sna_wakeup_handler
>[  2397.742] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2397.742] sna_block_handler (tv=599.997000)
>[  2397.742] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.742] sna_accel_do_flush -- no pending write to scanout
>[  2397.742] sna_wakeup_handler
>[  2397.742] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2397.742] sna_block_handler (tv=599.997000)
>[  2397.743] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2397.743] sna_accel_do_flush -- no pending write to scanout
>
>=============================================================================
>
>[  2519.270] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.270] sna_accel_do_flush -- no pending write to scanout
>[  2519.270] sna_accel_do_throttle -- no pending activity
>[  2519.270] sna_wakeup_handler
>[  2519.270] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.270] sna_block_handler (tv=0.004000)
>[  2519.270] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.270] sna_accel_do_flush -- no pending write to scanout
>[  2519.270] sna_accel_do_throttle -- no pending activity
>[  2519.271] sna_wakeup_handler
>[  2519.271] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.271] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.271] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.271] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.271] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.271] sna_do_copy: dst clipped out
>[  2519.271] sna_block_handler (tv=0.003000)
>[  2519.271] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.271] sna_accel_do_flush -- no pending write to scanout
>[  2519.271] sna_accel_do_throttle -- no pending activity
>[  2519.271] sna_wakeup_handler
>[  2519.271] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.271] sna_block_handler (tv=0.003000)
>[  2519.271] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.271] sna_accel_do_flush -- no pending write to scanout
>[  2519.271] sna_accel_do_throttle -- no pending activity
>[  2519.271] sna_wakeup_handler
>[  2519.271] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.271] sna_block_handler (tv=0.003000)
>[  2519.271] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.271] sna_accel_do_flush -- no pending write to scanout
>[  2519.271] sna_accel_do_throttle -- no pending activity
>[  2519.272] sna_wakeup_handler
>[  2519.272] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.272] sna_block_handler (tv=0.002000)
>[  2519.272] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.272] sna_accel_do_flush -- no pending write to scanout
>[  2519.272] sna_accel_do_throttle -- no pending activity
>[  2519.273] sna_wakeup_handler
>[  2519.273] sna_block_handler (tv=0.014000)
>[  2519.273] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.273] sna_accel_do_flush -- no pending write to scanout
>[  2519.273] sna_accel_do_throttle -- no pending activity
>[  2519.283] sna_wakeup_handler
>[  2519.283] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.283] sna_block_handler (tv=0.004000)
>[  2519.283] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.283] sna_accel_do_flush -- no pending write to scanout
>[  2519.283] sna_accel_do_throttle -- no pending activity
>[  2519.283] sna_wakeup_handler
>[  2519.283] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.283] sna_block_handler (tv=0.004000)
>[  2519.283] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.284] sna_accel_do_flush -- no pending write to scanout
>[  2519.284] sna_accel_do_throttle -- no pending activity
>[  2519.284] sna_wakeup_handler
>[  2519.284] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.284] sna_block_handler (tv=0.003000)
>[  2519.284] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.284] sna_accel_do_flush -- no pending write to scanout
>[  2519.284] sna_accel_do_throttle -- no pending activity
>[  2519.284] sna_wakeup_handler
>[  2519.284] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.284] sna_block_handler (tv=0.003000)
>[  2519.284] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.284] sna_accel_do_flush -- no pending write to scanout
>[  2519.284] sna_accel_do_throttle -- no pending activity
>[  2519.284] sna_wakeup_handler
>[  2519.284] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.284] sna_block_handler (tv=0.003000)
>[  2519.284] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.284] sna_accel_do_flush -- no pending write to scanout
>[  2519.284] sna_accel_do_throttle -- no pending activity
>[  2519.285] sna_wakeup_handler
>[  2519.285] sna_block_handler (tv=0.014000)
>[  2519.285] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.285] sna_accel_do_flush -- no pending write to scanout
>[  2519.285] sna_accel_do_throttle -- no pending activity
>[  2519.289] sna_wakeup_handler
>[  2519.289] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.289] sna_block_handler (tv=0.010000)
>[  2519.289] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.289] sna_accel_do_flush -- no pending write to scanout
>[  2519.289] sna_accel_do_throttle -- no pending activity
>[  2519.289] sna_wakeup_handler
>[  2519.289] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.289] sna_block_handler (tv=0.010000)
>[  2519.289] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.289] sna_accel_do_flush -- no pending write to scanout
>[  2519.289] sna_accel_do_throttle -- no pending activity
>[  2519.291] sna_wakeup_handler
>[  2519.291] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.291] sna_create_pixmap(1, 1, 1, usage=0)
>[  2519.291] kgem_can_create_2d: 1x1 @ 1
>[  2519.291] kgem_can_create_2d: unhandled depth 1
>[  2519.291] sna_create_pixmap: can not use GPU, just creating shadow
>[  2519.291] create_pixmap: allocating pixmap 1x1, depth=1, size=12
>[  2519.291] create_pixmap: serial=10223, usage=0, 1x1
>[  2519.291] sna_block_handler (tv=0.008000)
>[  2519.291] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.291] sna_accel_do_flush -- no pending write to scanout
>[  2519.291] sna_accel_do_throttle -- no pending activity
>[  2519.291] sna_wakeup_handler
>[  2519.291] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.291] sna_block_handler (tv=0.008000)
>[  2519.291] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.291] sna_accel_do_flush -- no pending write to scanout
>[  2519.291] sna_accel_do_throttle -- no pending activity
>[  2519.292] sna_wakeup_handler
>[  2519.292] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.292] sna_block_handler (tv=0.007000)
>[  2519.292] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.292] sna_accel_do_flush -- no pending write to scanout
>[  2519.292] sna_accel_do_throttle -- no pending activity
>[  2519.292] sna_wakeup_handler
>[  2519.292] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.292] sna_block_handler (tv=0.007000)
>[  2519.292] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.292] sna_accel_do_flush -- no pending write to scanout
>[  2519.292] sna_accel_do_throttle -- no pending activity
>[  2519.297] sna_wakeup_handler
>[  2519.298] sna_block_handler (tv=0.013000)
>[  2519.298] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.298] sna_accel_do_flush -- no pending write to scanout
>[  2519.298] sna_accel_do_throttle -- no pending activity
>[  2519.298] sna_wakeup_handler
>[  2519.298] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.298] sna_block_handler (tv=0.013000)
>[  2519.298] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.298] sna_accel_do_flush -- no pending write to scanout
>[  2519.298] sna_accel_do_throttle -- no pending activity
>[  2519.300] sna_wakeup_handler
>[  2519.300] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.300] sna_change_window_attributes
>[  2519.300] sna_block_handler (tv=0.011000)
>[  2519.300] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.300] sna_accel_do_flush -- no pending write to scanout
>[  2519.300] sna_accel_do_throttle -- no pending activity
>[  2519.300] sna_wakeup_handler
>[  2519.300] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.300] sna_block_handler (tv=0.011000)
>[  2519.300] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.300] sna_accel_do_flush -- no pending write to scanout
>[  2519.300] sna_accel_do_throttle -- no pending activity
>[  2519.300] sna_wakeup_handler
>[  2519.300] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.300] sna_block_handler (tv=0.011000)
>[  2519.300] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.300] sna_accel_do_flush -- no pending write to scanout
>[  2519.301] sna_accel_do_throttle -- no pending activity
>[  2519.301] sna_wakeup_handler
>[  2519.301] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.301] sna_block_handler (tv=0.010000)
>[  2519.301] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.301] sna_accel_do_flush -- no pending write to scanout
>[  2519.301] sna_accel_do_throttle -- no pending activity
>[  2519.301] sna_wakeup_handler
>[  2519.301] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.301] sna_block_handler (tv=0.010000)
>[  2519.301] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.301] sna_accel_do_flush -- no pending write to scanout
>[  2519.301] sna_accel_do_throttle -- no pending activity
>[  2519.302] sna_wakeup_handler
>[  2519.302] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.302] sna_block_handler (tv=0.009000)
>[  2519.302] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.302] sna_accel_do_flush -- no pending write to scanout
>[  2519.302] sna_accel_do_throttle -- no pending activity
>[  2519.302] sna_wakeup_handler
>[  2519.302] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.302] sna_block_handler (tv=0.009000)
>[  2519.302] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.302] sna_accel_do_flush -- no pending write to scanout
>[  2519.302] sna_accel_do_throttle -- no pending activity
>[  2519.302] sna_wakeup_handler
>[  2519.302] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.302] sna_block_handler (tv=0.009000)
>[  2519.302] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.302] sna_accel_do_flush -- no pending write to scanout
>[  2519.302] sna_accel_do_throttle -- no pending activity
>[  2519.303] sna_wakeup_handler
>[  2519.303] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.303] sna_block_handler (tv=0.008000)
>[  2519.303] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.303] sna_accel_do_flush -- no pending write to scanout
>[  2519.303] sna_accel_do_throttle -- no pending activity
>[  2519.303] sna_wakeup_handler
>[  2519.303] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.303] sna_block_handler (tv=0.008000)
>[  2519.303] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.303] sna_accel_do_flush -- no pending write to scanout
>[  2519.303] sna_accel_do_throttle -- no pending activity
>[  2519.303] sna_wakeup_handler
>[  2519.303] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.303] sna_block_handler (tv=0.008000)
>[  2519.303] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.303] sna_accel_do_flush -- no pending write to scanout
>[  2519.303] sna_accel_do_throttle -- no pending activity
>[  2519.303] sna_wakeup_handler
>[  2519.303] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.303] sna_block_handler (tv=0.008000)
>[  2519.303] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.304] sna_accel_do_flush -- no pending write to scanout
>[  2519.304] sna_accel_do_throttle -- no pending activity
>[  2519.304] sna_wakeup_handler
>[  2519.304] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.304] sna_block_handler (tv=0.007000)
>[  2519.304] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.304] sna_accel_do_flush -- no pending write to scanout
>[  2519.304] sna_accel_do_throttle -- no pending activity
>[  2519.304] sna_wakeup_handler
>[  2519.304] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.304] sna_block_handler (tv=0.007000)
>[  2519.304] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.304] sna_accel_do_flush -- no pending write to scanout
>[  2519.304] sna_accel_do_throttle -- no pending activity
>[  2519.304] sna_wakeup_handler
>[  2519.304] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.304] sna_block_handler (tv=0.007000)
>[  2519.304] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.305] sna_accel_do_flush -- no pending write to scanout
>[  2519.305] sna_accel_do_throttle -- no pending activity
>[  2519.305] sna_wakeup_handler
>[  2519.305] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.305] sna_block_handler (tv=0.006000)
>[  2519.305] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.305] sna_accel_do_flush -- no pending write to scanout
>[  2519.305] sna_accel_do_throttle -- no pending activity
>[  2519.305] sna_wakeup_handler
>[  2519.305] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.305] sna_block_handler (tv=0.006000)
>[  2519.305] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.305] sna_accel_do_flush -- no pending write to scanout
>[  2519.305] sna_accel_do_throttle -- no pending activity
>[  2519.305] sna_wakeup_handler
>[  2519.305] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.305] sna_block_handler (tv=0.006000)
>[  2519.305] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.305] sna_accel_do_flush -- no pending write to scanout
>[  2519.305] sna_accel_do_throttle -- no pending activity
>[  2519.305] sna_wakeup_handler
>[  2519.305] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.305] sna_block_handler (tv=0.006000)
>[  2519.305] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.306] sna_accel_do_flush -- no pending write to scanout
>[  2519.306] sna_accel_do_throttle -- no pending activity
>[  2519.306] sna_wakeup_handler
>[  2519.306] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.306] sna_block_handler (tv=0.005000)
>[  2519.306] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.306] sna_accel_do_flush -- no pending write to scanout
>[  2519.306] sna_accel_do_throttle -- no pending activity
>[  2519.308] sna_wakeup_handler
>[  2519.309] sna_block_handler (tv=0.013000)
>[  2519.309] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.309] sna_accel_do_flush -- no pending write to scanout
>[  2519.309] sna_accel_do_throttle -- no pending activity
>[  2519.319] sna_wakeup_handler
>[  2519.319] sna_block_handler (tv=0.014000)
>[  2519.319] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.320] sna_accel_do_flush -- no pending write to scanout
>[  2519.320] sna_accel_do_throttle -- no pending activity
>[  2519.333] sna_wakeup_handler
>[  2519.333] sna_block_handler (tv=0.014000)
>[  2519.334] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.334] sna_accel_do_flush -- no pending write to scanout
>[  2519.334] sna_accel_do_throttle -- no pending activity
>[  2519.334] sna_accel_do_expire (time=2519334), triggered
>[  2519.334] sna_accel_expire (time=2519334)
>[  2519.334] kgem_expire_cache: still allocated 1 bo, 569344 bytes, in snoop cache
>[  2519.334] kgem_retire, need_retire?=0
>[  2519.334] kgem_retire__flushing: 0 bo on flushing list
>[  2519.334] kgem_retire__requests_ring: ring=0, 0 outstanding requests, oldest=0
>[  2519.334] kgem_retire__requests_ring: ring=1, 0 outstanding requests, oldest=0
>[  2519.334] kgem_retire -- retired=0, need_retire=0
>[  2519.334] kgem_bo_free: handle=32
>[  2519.334] kgem_bo_free: releasing 0x7f8b343d3000:(nil) vma for handle=32, count=-507
>[  2519.336] kgem_expire_cache: expiring 32
>[  2519.336] kgem_bo_free: handle=168
>[  2519.336] kgem_bo_free: releasing 0x7f8b33a5a000:(nil) vma for handle=168, count=-508
>[  2519.340] kgem_expire_cache: expiring 168
>[  2519.341] kgem_expire_cache: still allocated 7 bo, 8769536 bytes, in inactive cache
>[  2519.341] kgem_expire_cache: expired 2 objects, 4161536 bytes, idle? 0
>[  2519.342] sna_wakeup_handler
>[  2519.342] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.342] sna_block_handler (tv=0.005000)
>[  2519.342] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.342] sna_accel_do_flush -- no pending write to scanout
>[  2519.342] sna_accel_do_throttle -- no pending activity
>[  2519.353] sna_wakeup_handler
>[  2519.353] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.353] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.353] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.353] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.353] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.353] sna_do_copy: dst clipped out
>[  2519.353] sna_block_handler (tv=0.014000)
>[  2519.354] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.354] sna_accel_do_flush -- no pending write to scanout
>[  2519.354] sna_accel_do_throttle -- no pending activity
>[  2519.358] sna_wakeup_handler
>[  2519.358] sna_block_handler (tv=0.014000)
>[  2519.358] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.358] sna_accel_do_flush -- no pending write to scanout
>[  2519.358] sna_accel_do_throttle -- no pending activity
>[  2519.368] sna_wakeup_handler
>[  2519.368] sna_block_handler (tv=0.014000)
>[  2519.368] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.368] sna_accel_do_flush -- no pending write to scanout
>[  2519.368] sna_accel_do_throttle -- no pending activity
>[  2519.381] sna_wakeup_handler
>[  2519.381] sna_block_handler (tv=0.014000)
>[  2519.381] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.381] sna_accel_do_flush -- no pending write to scanout
>[  2519.381] sna_accel_do_throttle -- no pending activity
>[  2519.393] sna_wakeup_handler
>[  2519.394] sna_block_handler (tv=0.013000)
>[  2519.394] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.394] sna_accel_do_flush -- no pending write to scanout
>[  2519.394] sna_accel_do_throttle -- no pending activity
>[  2519.405] sna_wakeup_handler
>[  2519.405] sna_block_handler (tv=0.014000)
>[  2519.405] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.405] sna_accel_do_flush -- no pending write to scanout
>[  2519.406] sna_accel_do_throttle -- no pending activity
>[  2519.416] sna_wakeup_handler
>[  2519.416] sna_block_handler (tv=0.014000)
>[  2519.416] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.416] sna_accel_do_flush -- no pending write to scanout
>[  2519.416] sna_accel_do_throttle -- no pending activity
>[  2519.431] sna_wakeup_handler
>[  2519.431] sna_block_handler (tv=0.014000)
>[  2519.431] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.431] sna_accel_do_flush -- no pending write to scanout
>[  2519.432] sna_accel_do_throttle -- no pending activity
>[  2519.432] sna_wakeup_handler
>[  2519.433] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.433] sna_block_handler (tv=0.012000)
>[  2519.433] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.433] sna_accel_do_flush -- no pending write to scanout
>[  2519.433] sna_accel_do_throttle -- no pending activity
>[  2519.434] sna_wakeup_handler
>[  2519.434] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.434] sna_block_handler (tv=0.011000)
>[  2519.434] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.434] sna_accel_do_flush -- no pending write to scanout
>[  2519.434] sna_accel_do_throttle -- no pending activity
>[  2519.442] sna_wakeup_handler
>[  2519.442] sna_block_handler (tv=0.014000)
>[  2519.442] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.442] sna_accel_do_flush -- no pending write to scanout
>[  2519.442] sna_accel_do_throttle -- no pending activity
>[  2519.442] sna_wakeup_handler
>[  2519.442] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.442] sna_block_handler (tv=0.014000)
>[  2519.442] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.442] sna_accel_do_flush -- no pending write to scanout
>[  2519.442] sna_accel_do_throttle -- no pending activity
>[  2519.447] sna_wakeup_handler
>[  2519.447] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.447] sna_copy_area: src=(0, 340)x(200, 20)+(0, 0) -> dst=(0, 340)+(137, 20); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.447] sna_do_copy: src=(0, 340), dst=(0, 340), size=(200x20)
>[  2519.447] sna_do_copy: after drawable: src=(0, 340), dst=(137, 360), size=(200x20)
>[  2519.447] sna_do_copy: dst extents (137, 360), (337, 380), dst clip extents (137, 706), (1229, 748), dst size=1092x728
>[  2519.447] sna_do_copy: dst clipped out
>[  2519.447] sna_change_window_attributes
>[  2519.447] sna_block_handler (tv=0.009000)
>[  2519.447] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.447] sna_accel_do_flush -- no pending write to scanout
>[  2519.447] sna_accel_do_throttle -- no pending activity
>[  2519.448] sna_wakeup_handler
>[  2519.448] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.448] sna_block_handler (tv=0.008000)
>[  2519.448] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.448] sna_accel_do_flush -- no pending write to scanout
>[  2519.448] sna_accel_do_throttle -- no pending activity
>[  2519.449] sna_wakeup_handler
>[  2519.449] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.449] sna_block_handler (tv=0.007000)
>[  2519.449] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.449] sna_accel_do_flush -- no pending write to scanout
>[  2519.449] sna_accel_do_throttle -- no pending activity
>[  2519.450] sna_wakeup_handler
>[  2519.450] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.451] sna_block_handler (tv=0.005000)
>[  2519.451] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.451] sna_accel_do_flush -- no pending write to scanout
>[  2519.451] sna_accel_do_throttle -- no pending activity
>[  2519.451] sna_wakeup_handler
>[  2519.451] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.451] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.451] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.451] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.451] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.451] sna_do_copy: dst clipped out
>[  2519.451] sna_block_handler (tv=0.005000)
>[  2519.451] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.451] sna_accel_do_flush -- no pending write to scanout
>[  2519.451] sna_accel_do_throttle -- no pending activity
>[  2519.454] sna_wakeup_handler
>[  2519.454] sna_block_handler (tv=0.014000)
>[  2519.454] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.454] sna_accel_do_flush -- no pending write to scanout
>[  2519.454] sna_accel_do_throttle -- no pending activity
>[  2519.464] sna_wakeup_handler
>[  2519.464] sna_block_handler (tv=0.014000)
>[  2519.464] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.464] sna_accel_do_flush -- no pending write to scanout
>[  2519.464] sna_accel_do_throttle -- no pending activity
>[  2519.479] sna_wakeup_handler
>[  2519.479] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.479] sna_block_handler (tv=0.014000)
>[  2519.479] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.479] sna_accel_do_flush -- no pending write to scanout
>[  2519.479] sna_accel_do_throttle -- no pending activity
>[  2519.491] sna_wakeup_handler
>[  2519.492] sna_block_handler (tv=0.013000)
>[  2519.492] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.492] sna_accel_do_flush -- no pending write to scanout
>[  2519.492] sna_accel_do_throttle -- no pending activity
>[  2519.501] sna_wakeup_handler
>[  2519.501] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.501] sna_block_handler (tv=0.004000)
>[  2519.501] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.501] sna_accel_do_flush -- no pending write to scanout
>[  2519.501] sna_accel_do_throttle -- no pending activity
>[  2519.502] sna_wakeup_handler
>[  2519.502] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.502] sna_block_handler (tv=0.003000)
>[  2519.502] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.502] sna_accel_do_flush -- no pending write to scanout
>[  2519.502] sna_accel_do_throttle -- no pending activity
>[  2519.503] sna_wakeup_handler
>[  2519.503] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.503] sna_block_handler (tv=0.002000)
>[  2519.503] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.503] sna_accel_do_flush -- no pending write to scanout
>[  2519.503] sna_accel_do_throttle -- no pending activity
>[  2519.503] sna_wakeup_handler
>[  2519.503] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.503] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.503] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.503] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.503] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.504] sna_do_copy: dst clipped out
>[  2519.504] sna_block_handler (tv=0.001000)
>[  2519.504] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.504] sna_accel_do_flush -- no pending write to scanout
>[  2519.504] sna_accel_do_throttle -- no pending activity
>[  2519.505] sna_wakeup_handler
>[  2519.505] sna_block_handler (tv=0.014000)
>[  2519.505] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.505] sna_accel_do_flush -- no pending write to scanout
>[  2519.505] sna_accel_do_throttle -- no pending activity
>[  2519.517] sna_wakeup_handler
>[  2519.517] sna_block_handler (tv=0.014000)
>[  2519.517] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.517] sna_accel_do_flush -- no pending write to scanout
>[  2519.517] sna_accel_do_throttle -- no pending activity
>[  2519.528] sna_wakeup_handler
>[  2519.528] sna_block_handler (tv=0.014000)
>[  2519.528] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.528] sna_accel_do_flush -- no pending write to scanout
>[  2519.528] sna_accel_do_throttle -- no pending activity
>[  2519.543] sna_wakeup_handler
>[  2519.543] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.543] sna_block_handler (tv=0.014000)
>[  2519.543] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.543] sna_accel_do_flush -- no pending write to scanout
>[  2519.543] sna_accel_do_throttle -- no pending activity
>[  2519.544] sna_wakeup_handler
>[  2519.544] sna_block_handler (tv=0.014000)
>[  2519.544] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.544] sna_accel_do_flush -- no pending write to scanout
>[  2519.544] sna_accel_do_throttle -- no pending activity
>[  2519.556] sna_wakeup_handler
>[  2519.556] sna_block_handler (tv=0.014000)
>[  2519.556] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.556] sna_accel_do_flush -- no pending write to scanout
>[  2519.556] sna_accel_do_throttle -- no pending activity
>[  2519.567] sna_wakeup_handler
>[  2519.567] sna_block_handler (tv=0.013000)
>[  2519.567] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.567] sna_accel_do_flush -- no pending write to scanout
>[  2519.567] sna_accel_do_throttle -- no pending activity
>[  2519.567] sna_wakeup_handler
>[  2519.567] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.567] sna_change_window_attributes
>[  2519.567] sna_block_handler (tv=0.013000)
>[  2519.567] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.567] sna_accel_do_flush -- no pending write to scanout
>[  2519.567] sna_accel_do_throttle -- no pending activity
>[  2519.578] sna_wakeup_handler
>[  2519.578] sna_block_handler (tv=0.013000)
>[  2519.578] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.578] sna_accel_do_flush -- no pending write to scanout
>[  2519.578] sna_accel_do_throttle -- no pending activity
>[  2519.581] sna_wakeup_handler
>[  2519.581] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.581] sna_block_handler (tv=0.010000)
>[  2519.581] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.581] sna_accel_do_flush -- no pending write to scanout
>[  2519.581] sna_accel_do_throttle -- no pending activity
>[  2519.582] sna_wakeup_handler
>[  2519.582] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.582] sna_block_handler (tv=0.009000)
>[  2519.582] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.582] sna_accel_do_flush -- no pending write to scanout
>[  2519.582] sna_accel_do_throttle -- no pending activity
>[  2519.583] sna_wakeup_handler
>[  2519.583] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.583] sna_block_handler (tv=0.008000)
>[  2519.583] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.583] sna_accel_do_flush -- no pending write to scanout
>[  2519.583] sna_accel_do_throttle -- no pending activity
>[  2519.583] sna_wakeup_handler
>[  2519.584] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.584] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.584] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.584] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.584] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.584] sna_do_copy: dst clipped out
>[  2519.584] sna_block_handler (tv=0.007000)
>[  2519.584] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.584] sna_accel_do_flush -- no pending write to scanout
>[  2519.584] sna_accel_do_throttle -- no pending activity
>[  2519.589] sna_wakeup_handler
>[  2519.589] sna_block_handler (tv=3552.440000)
>[  2519.589] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.589] sna_accel_do_flush -- no pending write to scanout
>[  2519.589] sna_accel_do_throttle -- no pending activity
>[  2519.596] sna_wakeup_handler
>[  2519.596] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.596] sna_block_handler (tv=3552.433000)
>[  2519.596] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.596] sna_accel_do_flush -- no pending write to scanout
>[  2519.596] sna_accel_do_throttle -- no pending activity
>[  2519.597] sna_wakeup_handler
>[  2519.597] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.597] sna_block_handler (tv=3552.432000)
>[  2519.597] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.597] sna_accel_do_flush -- no pending write to scanout
>[  2519.597] sna_accel_do_throttle -- no pending activity
>[  2519.607] sna_wakeup_handler
>[  2519.607] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.607] sna_block_handler (tv=3552.422000)
>[  2519.607] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.607] sna_accel_do_flush -- no pending write to scanout
>[  2519.607] sna_accel_do_throttle -- no pending activity
>[  2519.608] sna_wakeup_handler
>[  2519.608] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.608] sna_copy_area: src=(0, 340)x(200, 20)+(0, 0) -> dst=(0, 340)+(137, 20); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.608] sna_do_copy: src=(0, 340), dst=(0, 340), size=(200x20)
>[  2519.608] sna_do_copy: after drawable: src=(0, 340), dst=(137, 360), size=(200x20)
>[  2519.608] sna_do_copy: dst extents (137, 360), (337, 380), dst clip extents (137, 706), (1229, 748), dst size=1092x728
>[  2519.608] sna_do_copy: dst clipped out
>[  2519.608] sna_block_handler (tv=3552.421000)
>[  2519.608] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.608] sna_accel_do_flush -- no pending write to scanout
>[  2519.608] sna_accel_do_throttle -- no pending activity
>[  2519.611] sna_wakeup_handler
>[  2519.611] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.611] sna_block_handler (tv=3552.418000)
>[  2519.611] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.611] sna_accel_do_flush -- no pending write to scanout
>[  2519.611] sna_accel_do_throttle -- no pending activity
>[  2519.612] sna_wakeup_handler
>[  2519.612] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.613] sna_block_handler (tv=3552.416000)
>[  2519.613] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.613] sna_accel_do_flush -- no pending write to scanout
>[  2519.613] sna_accel_do_throttle -- no pending activity
>[  2519.673] sna_wakeup_handler
>[  2519.673] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.673] sna_block_handler (tv=3552.356000)
>[  2519.673] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.673] sna_accel_do_flush -- no pending write to scanout
>[  2519.673] sna_accel_do_throttle -- no pending activity
>[  2519.674] sna_wakeup_handler
>[  2519.674] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.674] sna_block_handler (tv=3552.355000)
>[  2519.674] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.674] sna_accel_do_flush -- no pending write to scanout
>[  2519.674] sna_accel_do_throttle -- no pending activity
>[  2519.675] sna_wakeup_handler
>[  2519.675] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.675] sna_block_handler (tv=3552.354000)
>[  2519.675] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.675] sna_accel_do_flush -- no pending write to scanout
>[  2519.675] sna_accel_do_throttle -- no pending activity
>[  2519.684] sna_wakeup_handler
>[  2519.684] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.684] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.684] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.684] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.684] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.684] sna_do_copy: dst clipped out
>[  2519.684] sna_block_handler (tv=3552.345000)
>[  2519.684] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.684] sna_accel_do_flush -- no pending write to scanout
>[  2519.684] sna_accel_do_throttle -- no pending activity
>[  2519.706] sna_wakeup_handler
>[  2519.706] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.706] sna_block_handler (tv=3552.323000)
>[  2519.706] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.706] sna_accel_do_flush -- no pending write to scanout
>[  2519.706] sna_accel_do_throttle -- no pending activity
>[  2519.741] sna_wakeup_handler
>[  2519.741] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.741] sna_block_handler (tv=3552.288000)
>[  2519.741] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.741] sna_accel_do_flush -- no pending write to scanout
>[  2519.741] sna_accel_do_throttle -- no pending activity
>[  2519.742] sna_wakeup_handler
>[  2519.742] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.742] sna_block_handler (tv=3552.287000)
>[  2519.742] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.742] sna_accel_do_flush -- no pending write to scanout
>[  2519.742] sna_accel_do_throttle -- no pending activity
>[  2519.743] sna_wakeup_handler
>[  2519.743] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.743] sna_block_handler (tv=3552.286000)
>[  2519.744] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.744] sna_accel_do_flush -- no pending write to scanout
>[  2519.744] sna_accel_do_throttle -- no pending activity
>[  2519.744] sna_wakeup_handler
>[  2519.744] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.744] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.744] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.744] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.744] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.744] sna_do_copy: dst clipped out
>[  2519.744] sna_block_handler (tv=3552.285000)
>[  2519.744] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.744] sna_accel_do_flush -- no pending write to scanout
>[  2519.744] sna_accel_do_throttle -- no pending activity
>[  2519.793] sna_wakeup_handler
>[  2519.793] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.793] sna_block_handler (tv=3552.236000)
>[  2519.793] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.793] sna_accel_do_flush -- no pending write to scanout
>[  2519.793] sna_accel_do_throttle -- no pending activity
>[  2519.794] sna_wakeup_handler
>[  2519.794] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.794] sna_block_handler (tv=3552.235000)
>[  2519.794] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.794] sna_accel_do_flush -- no pending write to scanout
>[  2519.794] sna_accel_do_throttle -- no pending activity
>[  2519.805] sna_wakeup_handler
>[  2519.805] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.805] sna_block_handler (tv=3552.224000)
>[  2519.805] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.805] sna_accel_do_flush -- no pending write to scanout
>[  2519.805] sna_accel_do_throttle -- no pending activity
>[  2519.805] sna_wakeup_handler
>[  2519.805] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.805] sna_copy_area: src=(0, 340)x(200, 20)+(0, 0) -> dst=(0, 340)+(137, 20); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.805] sna_do_copy: src=(0, 340), dst=(0, 340), size=(200x20)
>[  2519.805] sna_do_copy: after drawable: src=(0, 340), dst=(137, 360), size=(200x20)
>[  2519.805] sna_do_copy: dst extents (137, 360), (337, 380), dst clip extents (137, 706), (1229, 748), dst size=1092x728
>[  2519.805] sna_do_copy: dst clipped out
>[  2519.806] sna_block_handler (tv=3552.224000)
>[  2519.806] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.806] sna_accel_do_flush -- no pending write to scanout
>[  2519.806] sna_accel_do_throttle -- no pending activity
>[  2519.822] sna_wakeup_handler
>[  2519.822] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.822] sna_block_handler (tv=3552.207000)
>[  2519.822] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.822] sna_accel_do_flush -- no pending write to scanout
>[  2519.822] sna_accel_do_throttle -- no pending activity
>[  2519.822] sna_wakeup_handler
>[  2519.823] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.823] sna_block_handler (tv=3552.206000)
>[  2519.823] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.823] sna_accel_do_flush -- no pending write to scanout
>[  2519.823] sna_accel_do_throttle -- no pending activity
>[  2519.824] sna_wakeup_handler
>[  2519.824] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.824] sna_block_handler (tv=3552.205000)
>[  2519.824] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.824] sna_accel_do_flush -- no pending write to scanout
>[  2519.824] sna_accel_do_throttle -- no pending activity
>[  2519.824] sna_wakeup_handler
>[  2519.824] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.824] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.824] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.824] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.824] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.824] sna_do_copy: dst clipped out
>[  2519.824] sna_block_handler (tv=3552.205000)
>[  2519.824] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.824] sna_accel_do_flush -- no pending write to scanout
>[  2519.824] sna_accel_do_throttle -- no pending activity
>[  2519.838] sna_wakeup_handler
>[  2519.838] sna_block_handler (tv=0.001000)
>[  2519.838] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.838] sna_accel_do_flush -- no pending write to scanout
>[  2519.838] sna_accel_do_throttle -- no pending activity
>[  2519.839] sna_wakeup_handler
>[  2519.839] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.839] sna_block_handler (tv=0.014000)
>[  2519.839] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.839] sna_accel_do_flush -- no pending write to scanout
>[  2519.839] sna_accel_do_throttle -- no pending activity
>[  2519.850] sna_wakeup_handler
>[  2519.851] sna_block_handler (tv=0.013000)
>[  2519.851] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.851] sna_accel_do_flush -- no pending write to scanout
>[  2519.851] sna_accel_do_throttle -- no pending activity
>[  2519.864] sna_wakeup_handler
>[  2519.864] sna_block_handler (tv=0.014000)
>[  2519.864] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.864] sna_accel_do_flush -- no pending write to scanout
>[  2519.864] sna_accel_do_throttle -- no pending activity
>[  2519.864] sna_wakeup_handler
>[  2519.864] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.864] sna_change_window_attributes
>[  2519.864] sna_block_handler (tv=0.014000)
>[  2519.865] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.865] sna_accel_do_flush -- no pending write to scanout
>[  2519.865] sna_accel_do_throttle -- no pending activity
>[  2519.876] sna_wakeup_handler
>[  2519.876] sna_block_handler (tv=0.014000)
>[  2519.877] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.877] sna_accel_do_flush -- no pending write to scanout
>[  2519.877] sna_accel_do_throttle -- no pending activity
>[  2519.888] sna_wakeup_handler
>[  2519.888] sna_block_handler (tv=0.014000)
>[  2519.888] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.888] sna_accel_do_flush -- no pending write to scanout
>[  2519.888] sna_accel_do_throttle -- no pending activity
>[  2519.899] sna_wakeup_handler
>[  2519.899] sna_block_handler (tv=0.014000)
>[  2519.899] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.899] sna_accel_do_flush -- no pending write to scanout
>[  2519.899] sna_accel_do_throttle -- no pending activity
>[  2519.912] sna_wakeup_handler
>[  2519.912] sna_block_handler (tv=0.014000)
>[  2519.912] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.912] sna_accel_do_flush -- no pending write to scanout
>[  2519.912] sna_accel_do_throttle -- no pending activity
>[  2519.923] sna_wakeup_handler
>[  2519.923] sna_block_handler (tv=0.014000)
>[  2519.923] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.923] sna_accel_do_flush -- no pending write to scanout
>[  2519.923] sna_accel_do_throttle -- no pending activity
>[  2519.936] sna_wakeup_handler
>[  2519.936] sna_block_handler (tv=0.014000)
>[  2519.936] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.936] sna_accel_do_flush -- no pending write to scanout
>[  2519.936] sna_accel_do_throttle -- no pending activity
>[  2519.948] sna_wakeup_handler
>[  2519.948] sna_block_handler (tv=0.014000)
>[  2519.948] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.948] sna_accel_do_flush -- no pending write to scanout
>[  2519.948] sna_accel_do_throttle -- no pending activity
>[  2519.949] sna_wakeup_handler
>[  2519.949] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.949] sna_block_handler (tv=0.013000)
>[  2519.949] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.949] sna_accel_do_flush -- no pending write to scanout
>[  2519.949] sna_accel_do_throttle -- no pending activity
>[  2519.950] sna_wakeup_handler
>[  2519.950] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.950] sna_block_handler (tv=0.012000)
>[  2519.950] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.950] sna_accel_do_flush -- no pending write to scanout
>[  2519.950] sna_accel_do_throttle -- no pending activity
>[  2519.951] sna_wakeup_handler
>[  2519.951] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.951] sna_block_handler (tv=0.011000)
>[  2519.951] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.951] sna_accel_do_flush -- no pending write to scanout
>[  2519.951] sna_accel_do_throttle -- no pending activity
>[  2519.951] sna_wakeup_handler
>[  2519.951] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.952] sna_copy_area: src=(328, 244)x(34, 34)+(0, 0) -> dst=(328, 244)+(338, 122); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.952] sna_do_copy: src=(328, 244), dst=(328, 244), size=(34x34)
>[  2519.952] sna_do_copy: after drawable: src=(328, 244), dst=(666, 366), size=(34x34)
>[  2519.952] sna_do_copy: dst extents (666, 366), (700, 400), dst clip extents (0, 0), (0, 0), dst size=690x523
>[  2519.952] sna_do_copy: dst clipped out
>[  2519.952] sna_block_handler (tv=0.010000)
>[  2519.952] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.952] sna_accel_do_flush -- no pending write to scanout
>[  2519.952] sna_accel_do_throttle -- no pending activity
>[  2519.953] sna_wakeup_handler
>[  2519.953] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.953] sna_block_handler (tv=0.009000)
>[  2519.953] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.953] sna_accel_do_flush -- no pending write to scanout
>[  2519.953] sna_accel_do_throttle -- no pending activity
>[  2519.960] sna_wakeup_handler
>[  2519.960] sna_block_handler (tv=0.014000)
>[  2519.960] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.960] sna_accel_do_flush -- no pending write to scanout
>[  2519.960] sna_accel_do_throttle -- no pending activity
>[  2519.964] sna_wakeup_handler
>[  2519.964] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.964] sna_block_handler (tv=0.010000)
>[  2519.964] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.964] sna_accel_do_flush -- no pending write to scanout
>[  2519.964] sna_accel_do_throttle -- no pending activity
>[  2519.965] sna_wakeup_handler
>[  2519.965] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.965] sna_copy_area: src=(0, 340)x(200, 20)+(0, 0) -> dst=(0, 340)+(137, 20); alu=3, pm=ffffffffffffffff, depth=24
>[  2519.965] sna_do_copy: src=(0, 340), dst=(0, 340), size=(200x20)
>[  2519.965] sna_do_copy: after drawable: src=(0, 340), dst=(137, 360), size=(200x20)
>[  2519.965] sna_do_copy: dst extents (137, 360), (337, 380), dst clip extents (137, 706), (1229, 748), dst size=1092x728
>[  2519.965] sna_do_copy: dst clipped out
>[  2519.965] sna_block_handler (tv=0.009000)
>[  2519.965] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.965] sna_accel_do_flush -- no pending write to scanout
>[  2519.965] sna_accel_do_throttle -- no pending activity
>[  2519.965] sna_wakeup_handler
>[  2519.966] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.966] sna_change_window_attributes
>[  2519.966] sna_block_handler (tv=0.008000)
>[  2519.966] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.966] sna_accel_do_flush -- no pending write to scanout
>[  2519.966] sna_accel_do_throttle -- no pending activity
>[  2519.966] sna_wakeup_handler
>[  2519.966] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.966] sna_block_handler (tv=0.008000)
>[  2519.966] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.966] sna_accel_do_flush -- no pending write to scanout
>[  2519.966] sna_accel_do_throttle -- no pending activity
>[  2519.967] sna_wakeup_handler
>[  2519.967] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.967] sna_block_handler (tv=0.007000)
>[  2519.967] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.967] sna_accel_do_flush -- no pending write to scanout
>[  2519.967] sna_accel_do_throttle -- no pending activity
>[  2519.967] sna_wakeup_handler
>[  2519.967] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.967] sna_block_handler (tv=0.007000)
>[  2519.967] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.967] sna_accel_do_flush -- no pending write to scanout
>[  2519.967] sna_accel_do_throttle -- no pending activity
>[  2519.968] sna_wakeup_handler
>[  2519.968] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.968] sna_block_handler (tv=0.006000)
>[  2519.968] start_flush: scanout=28 shadow?=0, slaves?=0, (cpu?=0 || gpu?=0))
>[  2519.968] sna_accel_do_flush -- no pending write to scanout
>[  2519.968] sna_accel_do_throttle -- no pending activity
>[  2519.972] sna_wakeup_handler
>[  2519.972] sna_accel_wakeup_handler: nbatch=0, need_retire=0, need_purge=0
>[  2519.972] sna_create_pixmap(32, 64, 1, usage=0)
>[  2519.972] kgem_can_create_2d: 32x64 @ 1
>[  2519.972] kgem_can_create_2d: unhandled depth 1
>[  2519.972] sna_create_pixmap: can not use GPU, just creating shadow
>[  2519.972] create_pixmap: allocating pixmap 32x64, depth=1, size=264
>[  2519.972] create_pixmap: serial=10237, usage=0, 32x64
>[  2519.972] sna_validate_gc(0x2c751b0) changes=7fffff, previous serial=80000000, drawable=27fe
>[  2519.972] sna_validate_gc: recomputing clip
>[  2519.972] sna_validate_gc: composite clip=1x[(0, 0), (32, 64)] [0x2c752d0]
>[  2519.972] sna_put_image((0, 0)x(32, 64), depth=1, format=2)
>[  2519.972] sna_put_image: fallback -- unattached(0, 0, 32, 64)
>[  2519.972] sna_put_image: fallback
>[  2519.972] sna_gc_move_to_cpu(0x2c751b0) changes=7fffff
>[  2519.972] sna_gc_move_to_cpu: flushing stipple pixmap
>[  2519.972] sna_validate_pixmap: target bpp=1, source bpp=1
>[  2519.972] sna_validate_pixmap: flushing pixmap
>[  2519.972] sfbValidateGC changes=77ffff
>[  2519.973] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2519.973] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  2519.973] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  2519.973] sna_drawable_move_region_to_cpu(pixmap=10238 (32x64), [(0, 0), (32, 64)], flags=5)
>[  2519.973] sna_drawable_move_region_to_cpu: not attached to pixmap 10238 (depth 1)
>[  2519.973] sna_put_image: fbPutImage(0, 0, 32, 64)
>[  2519.973] sfbPutImage (0, 0)x(32, 64)
>[  2519.973] fbClipBoxes: box=(0, 0),(32, 64); region=(0, 0),(32, 64) x 1
>[  2519.973] fbDrawableRun: box=(0, 0), (32, 64), clip=(0, 0), (32, 64)
>[  2519.973] sfbBlt 32x64, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2519.973] sfbBlt fast blt, src_stride=4, dst_stride=4, width=4 (offset=-216408)
>[  2519.973] sna_gc_move_to_gpu(0x2c751b0)
>[  2519.973] sna_create_pixmap(32, 32, 1, usage=0)
>[  2519.973] kgem_can_create_2d: 32x32 @ 1
>[  2519.973] kgem_can_create_2d: unhandled depth 1
>[  2519.973] sna_create_pixmap: can not use GPU, just creating shadow
>[  2519.973] create_pixmap: allocating pixmap 32x32, depth=1, size=136
>[  2519.973] create_pixmap: serial=10239, usage=0, 32x32
>[  2519.973] sna_create_pixmap(32, 32, 1, usage=0)
>[  2519.973] kgem_can_create_2d: 32x32 @ 1
>[  2519.973] kgem_can_create_2d: unhandled depth 1
>[  2519.973] sna_create_pixmap: can not use GPU, just creating shadow
>[  2519.973] create_pixmap: allocating pixmap 32x32, depth=1, size=136
>[  2519.973] create_pixmap: serial=10241, usage=0, 32x32
>[  2519.973] sna_validate_gc(0x2c751b0) changes=7fffff, previous serial=80000000, drawable=2800
>[  2519.973] sna_validate_gc: recomputing clip
>[  2519.973] sna_validate_gc: composite clip=1x[(0, 0), (32, 32)] [0x2c75530]
>[  2519.973] sna_copy_area: src=(0, 0)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=3, pm=ffffffffffffffff, depth=1
>[  2519.973] sna_do_copy: src=(0, 0), dst=(0, 0), size=(32x32)
>[  2519.973] sna_do_copy: after drawable: src=(0, 0), dst=(0, 0), size=(32x32)
>[  2519.973] sna_do_copy: dst extents (0, 0), (32, 32), dst clip extents (0, 0), (32, 32), dst size=32x32
>[  2519.973] sna_do_copy: clipped dst extents (0, 0), (32, 32)
>[  2519.973] sna_do_copy: unclipped src extents (0, 0), (32, 32)
>[  2519.973] sna_do_copy: pixmap -- no source clipping
>[  2519.973] sna_do_copy: src extents (0, 0), (32, 32) x 1
>[  2519.973] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  2519.973] sna_fallback_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 0), alu=3
>[  2519.973] sna_gc_move_to_cpu(0x2c751b0) changes=7fffff
>[  2519.973] sna_gc_move_to_cpu: flushing stipple pixmap
>[  2519.973] sna_validate_pixmap: target bpp=1, source bpp=1
>[  2519.973] sna_validate_pixmap: flushing pixmap
>[  2519.973] sfbValidateGC changes=77ffff
>[  2519.973] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2519.973] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=00000000, bgxor=ffffffff
>[  2519.973] sna_drawable_move_region_to_cpu(pixmap=10238 (32x64), [(0, 0), (32, 32)], flags=2)
>[  2519.973] sna_drawable_move_region_to_cpu: not attached to pixmap 10238 (depth 1)
>[  2519.973] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  2519.974] sna_drawable_move_region_to_cpu(pixmap=10240 (32x32), [(0, 0), (32, 32)], flags=5)
>[  2519.974] sna_drawable_move_region_to_cpu: not attached to pixmap 10240 (depth 1)
>[  2519.974] sfbBlt 32x32, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2519.974] sfbBlt fast blt, src_stride=4, dst_stride=4, width=4 (offset=-720)
>[  2519.974] sna_gc_move_to_gpu(0x2c751b0)
>[  2519.974] sna_validate_gc(0x2c751b0) changes=0, previous serial=0, drawable=2802
>[  2519.974] sna_validate_gc: recomputing clip
>[  2519.974] sna_validate_gc: composite clip=1x[(0, 0), (32, 32)] [0x2c75530]
>[  2519.974] sna_copy_area: src=(0, 0)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=3, pm=ffffffffffffffff, depth=1
>[  2519.974] sna_do_copy: src=(0, 0), dst=(0, 0), size=(32x32)
>[  2519.974] sna_do_copy: after drawable: src=(0, 0), dst=(0, 0), size=(32x32)
>[  2519.974] sna_do_copy: dst extents (0, 0), (32, 32), dst clip extents (0, 0), (32, 32), dst size=32x32
>[  2519.974] sna_do_copy: clipped dst extents (0, 0), (32, 32)
>[  2519.974] sna_do_copy: unclipped src extents (0, 0), (32, 32)
>[  2519.974] sna_do_copy: pixmap -- no source clipping
>[  2519.974] sna_do_copy: src extents (0, 0), (32, 32) x 1
>[  2519.974] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  2519.974] sna_fallback_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 0), alu=3
>[  2519.974] sna_gc_move_to_cpu(0x2c751b0) changes=0
>[  2519.974] sfbValidateGC changes=0
>[  2519.974] sna_drawable_move_region_to_cpu(pixmap=10238 (32x64), [(0, 0), (32, 32)], flags=2)
>[  2519.974] sna_drawable_move_region_to_cpu: not attached to pixmap 10238 (depth 1)
>[  2519.974] drawable_gc_flags: try operating on drawable inplace [hint? 1]
>[  2519.974] sna_drawable_move_region_to_cpu(pixmap=10242 (32x32), [(0, 0), (32, 32)], flags=5)
>[  2519.974] sna_drawable_move_region_to_cpu: not attached to pixmap 10242 (depth 1)
>[  2519.974] sfbBlt 32x32, alu=3, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2519.974] sfbBlt fast blt, src_stride=4, dst_stride=4, width=4 (offset=-992)
>[  2519.974] sna_gc_move_to_gpu(0x2c751b0)
>[  2519.974] sna_validate_gc(0x2c751b0) changes=1, previous serial=80002800, drawable=2800
>[  2519.974] sna_copy_area: src=(0, 32)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=2, pm=ffffffffffffffff, depth=1
>[  2519.974] sna_do_copy: src=(0, 32), dst=(0, 0), size=(32x32)
>[  2519.974] sna_do_copy: after drawable: src=(0, 32), dst=(0, 0), size=(32x32)
>[  2519.974] sna_do_copy: dst extents (0, 0), (32, 32), dst clip extents (0, 0), (32, 32), dst size=32x32
>[  2519.974] sna_do_copy: clipped dst extents (0, 0), (32, 32)
>[  2519.974] sna_do_copy: unclipped src extents (0, 32), (32, 64)
>[  2519.974] sna_do_copy: pixmap -- no source clipping
>[  2519.974] sna_do_copy: src extents (0, 32), (32, 64) x 1
>[  2519.974] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  2519.974] sna_fallback_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 32), alu=2
>[  2519.974] sna_gc_move_to_cpu(0x2c751b0) changes=1
>[  2519.974] sfbValidateGC changes=1
>[  2519.974] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2519.974] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=ffffffff, bgxor=ffffffff
>[  2519.974] sna_drawable_move_region_to_cpu(pixmap=10238 (32x64), [(0, 32), (32, 64)], flags=2)
>[  2519.974] sna_drawable_move_region_to_cpu: not attached to pixmap 10238 (depth 1)
>[  2519.974] drawable_gc_flags: read due to rrop 2:0
>[  2519.975] sna_drawable_move_region_to_cpu(pixmap=10240 (32x32), [(0, 0), (32, 32)], flags=3)
>[  2519.975] sna_drawable_move_region_to_cpu: not attached to pixmap 10240 (depth 1)
>[  2519.975] sfbBlt 32x32, alu=2, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2519.975] sna_gc_move_to_gpu(0x2c751b0)
>[  2519.975] sna_validate_gc(0x2c751b0) changes=1, previous serial=80002802, drawable=2802
>[  2519.975] sna_copy_area: src=(0, 32)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=11, pm=ffffffffffffffff, depth=1
>[  2519.975] sna_do_copy: src=(0, 32), dst=(0, 0), size=(32x32)
>[  2519.975] sna_do_copy: after drawable: src=(0, 32), dst=(0, 0), size=(32x32)
>[  2519.975] sna_do_copy: dst extents (0, 0), (32, 32), dst clip extents (0, 0), (32, 32), dst size=32x32
>[  2519.975] sna_do_copy: clipped dst extents (0, 0), (32, 32)
>[  2519.975] sna_do_copy: unclipped src extents (0, 32), (32, 64)
>[  2519.975] sna_do_copy: pixmap -- no source clipping
>[  2519.975] sna_do_copy: src extents (0, 32), (32, 64) x 1
>[  2519.975] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  2519.975] sna_fallback_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 32), alu=11
>[  2519.975] sna_gc_move_to_cpu(0x2c751b0) changes=1
>[  2519.975] sfbValidateGC changes=1
>[  2519.975] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2519.975] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=ffffffff, xor=ffffffff, bgand=00000000, bgxor=ffffffff
>[  2519.975] sna_drawable_move_region_to_cpu(pixmap=10238 (32x64), [(0, 32), (32, 64)], flags=2)
>[  2519.975] sna_drawable_move_region_to_cpu: not attached to pixmap 10238 (depth 1)
>[  2519.975] drawable_gc_flags: read due to rrop 11:ffffffff
>[  2519.975] sna_drawable_move_region_to_cpu(pixmap=10242 (32x32), [(0, 0), (32, 32)], flags=3)
>[  2519.975] sna_drawable_move_region_to_cpu: not attached to pixmap 10242 (depth 1)
>[  2519.975] sfbBlt 32x32, alu=11, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2519.975] sna_gc_move_to_gpu(0x2c751b0)
>[  2519.975] sna_validate_gc(0x2c751b0) changes=1, previous serial=80002800, drawable=27fe
>[  2519.975] sna_validate_gc: recomputing clip
>[  2519.975] sna_validate_gc: composite clip=1x[(0, 0), (32, 64)] [0x2c75530]
>[  2519.975] sna_copy_area: src=(0, 32)x(32, 32)+(0, 0) -> dst=(0, 0)+(0, 0); alu=1, pm=ffffffffffffffff, depth=1
>[  2519.975] sna_do_copy: src=(0, 32), dst=(0, 0), size=(32x32)
>[  2519.975] sna_do_copy: after drawable: src=(0, 32), dst=(0, 0), size=(32x32)
>[  2519.975] sna_do_copy: dst extents (0, 0), (32, 32), dst clip extents (0, 0), (32, 64), dst size=32x64
>[  2519.975] sna_do_copy: clipped dst extents (0, 0), (32, 32)
>[  2519.975] sna_do_copy: unclipped src extents (0, 32), (32, 64)
>[  2519.975] sna_do_copy: pixmap -- using gc clip
>[  2519.975] sna_do_copy: src extents (0, 32), (32, 64) x 1
>[  2519.975] sna_do_copy: copy region (0, 0), (32, 32) x 1
>[  2519.975] sna_fallback_copy_boxes (boxes=1x[(0, 0), (32, 32)...], src=+(0, 32), alu=1
>[  2519.975] sna_gc_move_to_cpu(0x2c751b0) changes=1
>[  2519.975] sfbValidateGC changes=1
>[  2519.975] sfbValidateGC: computing rrop mask=00000001, depthMask=00000001, fg=00000000, bg=00000001, planemask=ffffffff
>[  2519.975] sfbValidateGC: rrop fg=00000000, bg=ffffffff, pm=ffffffff, and=00000000, xor=00000000, bgand=ffffffff, bgxor=00000000
>[  2519.975] sna_drawable_move_region_to_cpu(pixmap=10238 (32x64), [(0, 32), (32, 64)], flags=2)
>[  2519.975] sna_drawable_move_region_to_cpu: not attached to pixmap 10238 (depth 1)
>[  2519.975] sna_fallback_copy_boxes: self-copy
>[  2519.975] _sna_pixmap_move_to_cpu(pixmap=10238, 32x64, flags=3)
>[  2519.976] _sna_pixmap_move_to_cpu: not attached
>[  2519.976] sfbBlt 32x32, alu=1, pm=ffffffff, bpp=1 (reverse=0, upsidedown=0)
>[  2519.976] sna_gc_move_to_gpu(0x2c751b0)
>[  2519.976] sna_validate_gc(0x2c751b0) changes=1, previous serial=80002802, drawable=2802

Actions: View

Attachments on bug 73856: 92496 | 92516 | 92550

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.