[Spice-devel] screen freezed for 2-3 minutes on spice connect on xen windows 7 domU's with qxl after save/restore

Fabio Fantoni fabio.fantoni at m2r.biz
Tue Jul 8 03:34:58 PDT 2014 

Il 08/07/2014 12:06, Fabio Fantoni ha scritto:
> Il 08/07/2014 10:53, David Jaša ha scritto:
>> Hi,
>>
>> On Út, 2014-07-08 at 10:13 +0200, Fabio Fantoni wrote:
>>> On xen 4.5 (tried with qemu 2.0.0/2.1-rc0, spice 0.12.5 and client with
>>> spice-gtk 0.23/0.25) windows 7 domUs with qxl vga works good as kvm
>>> except for one problem after xl save/restore, when after restore on
>>> spice client connect  the domU's screen freezed for 2-3 minutes (and
>>> seems also windows), after this time seems that all return to works
>>> correctly.
>>> This problem happen also if spice client connect long time after 
>>> restore.
>>> With stdvga not have this problem but stdvga has many missed 
>>> resolutions
>>> and bad refresh performance.
>>>
>>> If you need more tests/informations tell me and I'll post them.
>> Client and server logs would certainly help. Please run:
>>    * virt-viewer with --spice-debug option
>>    * spice-server with SPICE_DEBUG_LEVEL environment variable set
>>      to 4 or 5 (if you use qemu+libvirt, use qemu:env element:
>>      http://libvirt.org/drvqemu.html#qemucommand )
>> and note the location in the logs where the freeze takes place.
>>
>> Regards,
>>
>> David
>
> Thanks for your reply, in attachments:
> - domU's xl cfg: W7.cfg
> - xl -vvv create/save/restore: xen logs.txt
> - remote-viewer with --spice-debug after domU's start until xl save: 
> spicelog-1.txt (zipped)
> - remote-viewer with --spice-debug after domU's xl restore: 
> spicelog-2.txt

Sorry for my forgetfulness, here also qemu's log:
- after domU's start until xl save: qemu-dm-W7.log.1
- after domU's xl restore: qemu-dm-W7.log

>
> If you need more tests/informations tell me and I'll post them.
>
>
>> Thanks for any reply and sorry for my bad english.
>>
>> _______________________________________________
>> Spice-devel mailing list
>> Spice-devel at lists.freedesktop.org
>> http://lists.freedesktop.org/mailman/listinfo/spice-devel

-------------- next part --------------
(/usr/sbin/xl:13985): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=0
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2355:reds_init_socket: bound to 0.0.0.0:6002
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7fbd7ab8a900
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7fbd7ab8a900
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
xc: error: linux_gnttab_set_max_grants: ioctl SET_MAX_GRANTS failed (22 = Invalid argument): Internal error
xen be: qdisk-768: xc_gnttab_set_max_grants failed: Invalid argument
xc: error: linux_gnttab_set_max_grants: ioctl SET_MAX_GRANTS failed (22 = Invalid argument): Internal error
xen be: qdisk-832: xc_gnttab_set_max_grants failed: Invalid argument
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7fbd7ab8a900
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7fbd7ab8a900
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7fbd7d049d08
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7fbd66a8e700
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7fbd66a8e700
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1551:reds_handle_main_link: 
(/usr/sbin/xl:13985): Spice-Info **: reds.c:522:reds_disconnect: 
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Non Secure link
main_channel_link: add main channel client
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7fbd7cf21020 mcc 0x7fbd7cf844e0 connect-id 1531646179
main_channel_handle_parsed: net test: latency 2.631000 ms, bitrate 652229299 bps (622.014331 Mbps)
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Non Secure link
(/usr/sbin/xl:13985): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Non Secure link
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7fbd7cf21020) dcc 0x7fbd7d2cabc0 stream 0x7fbd7d083d40
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg disabled
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz disabled
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Non Secure link
(/usr/sbin/xl:13985): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 1.24(ms)
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Non Secure link
red_dispatcher_set_cursor_peer: 
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:13985): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Non Secure link
inputs_connect: inputs channel client create
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.23(ms)
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd5459c230
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd5459c230: cookie 140451134924192
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd5442f3d0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd5442f3d0: cookie 140451134917584
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fbd7cf2f328 dev_state 0x7fbd54452fd0
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fbd54452fd0
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
main_channel_handle_parsed: agent start
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fbd54452fd0 client 0x7fbd7cf21020
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd5400e3a0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd5400e3a0: cookie 140451139249072
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544363b0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544363b0: cookie 140451134890912
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd5400e3a0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd5400e3a0: cookie 140451139249072
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544363b0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544363b0: cookie 140451134890912
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
(/usr/sbin/xl:13985): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fbd7cf2f328 dev_state 0x7fbd54452fd0
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fbd54452fd0
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
main_channel_handle_parsed: agent start
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fbd54452fd0 client 0x7fbd7cf21020
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.48(ms)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: reds.c:2383:reds_send_mm_time: 
(/usr/sbin/xl:13985): Spice-Debug **: reds.c:2383:reds_send_mm_time: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1302, 718) (1362, 733)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139384304
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544573f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1303, 736) (1362, 751)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544573f0: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1141, 752) (1362, 767)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139384304
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544573f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (397, 146) (523, 161)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544573f0: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 190) (523, 211)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139384304
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544573f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 230) (616, 247)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544573f0: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 559) (483, 576)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139384304
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (434, 146) (560, 161)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 190) (773, 211)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 230) (943, 247)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 247) (727, 264)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 273) (677, 290)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 296) (911, 313)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 313) (880, 330)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 330) (527, 347)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 355) (922, 372)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 372) (922, 389)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 389) (720, 406)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 415) (534, 432)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 436) (930, 453)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 453) (730, 470)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 483) (927, 500)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 500) (585, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (583, 500) (753, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (583, 500) (753, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (751, 500) (756, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 534) (923, 551)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (921, 534) (965, 551)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (921, 534) (965, 551)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 551) (434, 568)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd544579f0
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 551) (434, 568)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd544579f0: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (432, 551) (437, 568)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385840
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a50
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 483) (927, 500)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a50: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (412, 500) (585, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385936
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a50
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (583, 500) (753, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a50: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (583, 500) (753, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385936
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a50
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (751, 500) (756, 517)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a50: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1302, 718) (1362, 733)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385936
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a50
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1303, 736) (1362, 751)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a50: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a20
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1141, 752) (1362, 767)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a20: cookie 140451139385936
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54457a50
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 728) (54, 768)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54457a50: cookie 140451139385888
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: reds.c:2383:reds_send_mm_time: 
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54458d70
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 721) (54, 728)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54458d70: cookie 140451139390784
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54458d40
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (316, 255) (380, 319)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54458d40: cookie 140451139390832
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fbd54458d70
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:13985): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 721) (54, 728)
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fbd54458d70: cookie 140451139390784
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:13985): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:13985): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7fbd54452fd0
(/usr/sbin/xl:13985): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
(/usr/sbin/xl:13985): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
qemu: terminating on signal 1 from pid 14130
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
(/usr/sbin/xl:13985): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fbd7d17b740 rcc=0x7fbd7d220570 type=5
red_channel_client_disconnect_dummy: rcc=0x7fbd7d220570 (channel=0x7fbd7d007490 type=5 id=0)
(/usr/sbin/xl:13985): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 38
snd_channel_put: SndChannel=0x7fbd7d17b740 freed
(/usr/sbin/xl:13985): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
(/usr/sbin/xl:13985): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fbd7d2a2000 rcc=0x7fbd7d23ed30 type=6
red_channel_client_disconnect_dummy: rcc=0x7fbd7d23ed30 (channel=0x7fbd7cfcb010 type=6 id=0)
(/usr/sbin/xl:13985): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 39
snd_channel_put: SndChannel=0x7fbd7d2a2000 freed
-------------- next part --------------
(/usr/sbin/xl:14270): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=0
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2355:reds_init_socket: bound to 0.0.0.0:6002
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f1502e0e900
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f1502e0e900
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
xen_ram_alloc: do not alloc 78000000 bytes of ram at 0 when runstate is INMIGRATE
xc: error: linux_gnttab_set_max_grants: ioctl SET_MAX_GRANTS failed (22 = Invalid argument): Internal error
xen be: qdisk-768: xc_gnttab_set_max_grants failed: Invalid argument
xc: error: linux_gnttab_set_max_grants: ioctl SET_MAX_GRANTS failed (22 = Invalid argument): Internal error
xen be: qdisk-832: xc_gnttab_set_max_grants failed: Invalid argument
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f1502e0e900
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f1502e0e900
xen_ram_alloc: do not alloc 4000000 bytes of ram at 78000000 when runstate is INMIGRATE
xen_ram_alloc: do not alloc 2000 bytes of ram at 7c000000 when runstate is INMIGRATE
xen_ram_alloc: do not alloc 4000000 bytes of ram at 7c002000 when runstate is INMIGRATE
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f1505aa18d8
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f14ead13700
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f14ead13700
xen_ram_alloc: do not alloc 10000 bytes of ram at 80002000 when runstate is INMIGRATE
xen_ram_alloc: do not alloc 40000 bytes of ram at 80012000 when runstate is INMIGRATE
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
red_dispatcher_loadvm_commands: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f150599a328 dev_state 0x7f1505c95200
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f1505c95200
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1551:reds_handle_main_link: 
(/usr/sbin/xl:14270): Spice-Info **: reds.c:522:reds_disconnect: 
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Non Secure link
main_channel_link: add main channel client
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f15059a6450 mcc 0x7f15059d0400 connect-id 1019704103
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
main_channel_handle_parsed: agent start
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f1505c95200 client 0x7f15059a6450
main_channel_handle_parsed: net test: latency 3.177000 ms, bitrate 757676655 bps (722.576766 Mbps)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f1505a953d0
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f1505a953d0: cookie 139728265349072
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Non Secure link
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f15059a6450) dcc 0x7f14f42fe010 stream 0x7f1505a97070
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg disabled
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz disabled
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Non Secure link
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Non Secure link
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.51(ms)
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Non Secure link
inputs_connect: inputs channel client create
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Non Secure link
red_dispatcher_set_cursor_peer: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.53(ms)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.52(ms)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.50(ms)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f1505a992a0
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f1505a992a0: cookie 139728265828080
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (200, 200) (400, 400)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 0) (1, 1)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (316, 255) (380, 319)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 728) (54, 768)
(/usr/sbin/xl:14270): Spice-Debug **: reds.c:776:vdi_port_on_free_self_token: pending mouse event
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.46(ms)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.22(ms)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.43(ms)
red_channel_client_disconnect: rcc=0x7f15059d0400 (channel=0x7f150599ec40 type=1 id=0)
red_channel_client_disconnect: rcc=0x7f14f42fe010 (channel=0x7f1505aa25b0 type=2 id=0)
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 37
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 21
main_channel_client_on_disconnect: rcc=0x7f15059d0400
(/usr/sbin/xl:14270): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f15059a6450
red_channel_client_disconnect: rcc=0x7f1505cf3d40 (channel=0x7f15059aa6d0 type=3 id=0)
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 40
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f1505c6bae0 rcc=0x7f1505ca27b0 type=6
red_channel_client_disconnect_dummy: rcc=0x7f1505ca27b0 (channel=0x7f1505aa61c0 type=6 id=0)
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 39
snd_channel_put: SndChannel=0x7f1505c6bae0 freed
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f1505cbdbf0 rcc=0x7f1505c44360 type=5
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=17, #red_draw=17, #glz_draw=0
red_channel_client_disconnect_dummy: rcc=0x7f1505c44360 (channel=0x7f1505aa40e0 type=5 id=0)
red_channel_client_disconnect: rcc=0x7f1505cc7560 (channel=0x7f1505aa1b40 type=4 id=0)
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 41
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 38
snd_channel_put: SndChannel=0x7f1505cbdbf0 freed
(/usr/sbin/xl:14270): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f15059a6450
(/usr/sbin/xl:14270): Spice-Info **: reds.c:445:reds_client_disconnect: 
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7f1505c95200 client 0x7f15059a6450
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
red_client_destroy: destroy client 0x7f15059a6450 with #channels=6
red_dispatcher_disconnect_cursor_peer: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f1505cc7560
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f1505cf3d40
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f1505ca27b0
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f1505c44360
red_dispatcher_disconnect_display_peer: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f14f42fe010
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f15059d0400
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f15059a6450
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f150599a328 dev_state 0x7f15059d6f80
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f15059d6f80
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1551:reds_handle_main_link: 
(/usr/sbin/xl:14270): Spice-Info **: reds.c:522:reds_disconnect: 
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Non Secure link
main_channel_link: add main channel client
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f15059a6450 mcc 0x7f15059ce800 connect-id 14747444
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
main_channel_handle_parsed: agent start
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f15059d6f80 client 0x7f15059a6450
main_channel_handle_parsed: net test: latency 0.966000 ms, bitrate 572706935 bps (546.175895 Mbps)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Non Secure link
(/usr/sbin/xl:14270): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f15059a6450) dcc 0x7f1505e36ea0 stream 0x7f1505b30c90
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg disabled
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz disabled
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Non Secure link
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Non Secure link
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Non Secure link
inputs_connect: inputs channel client create
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
(/usr/sbin/xl:14270): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Non Secure link
red_dispatcher_set_cursor_peer: 
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 55.40(ms)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.57(ms)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 721) (54, 728)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (316, 255) (380, 319)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (268, 717) (656, 728)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (268, 728) (656, 737)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (656, 717) (661, 728)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (656, 728) (661, 737)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (268, 737) (661, 742)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1302, 678) (1362, 693)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1303, 696) (1362, 711)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1141, 712) (1362, 727)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1302, 678) (1362, 693)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1303, 696) (1362, 711)
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
(/usr/sbin/xl:14270): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1141, 712) (1362, 727)
(/usr/sbin/xl:14270): Spice-Debug **: reds.c:2383:reds_send_mm_time: 
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
(/usr/sbin/xl:14270): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 0.62(ms)
(/usr/sbin/xl:14270): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f150599a328 dev_state 0x7f1505b55780
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f1505b55780
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
main_channel_handle_parsed: agent start
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f1505b55780 client 0x7f15059a6450
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
(/usr/sbin/xl:14270): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
(/usr/sbin/xl:14270): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
(/usr/sbin/xl:14270): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
Issued domain 19 poweroff
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f1505c387d0 rcc=0x7f1505b5e8b0 type=5
red_channel_client_disconnect_dummy: rcc=0x7f1505b5e8b0 (channel=0x7f1505aa40e0 type=5 id=0)
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 38
snd_channel_put: SndChannel=0x7f1505c387d0 freed
(/usr/sbin/xl:14270): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
(/usr/sbin/xl:14270): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f1505ce3d20 rcc=0x7f1505cf4560 type=6
red_channel_client_disconnect_dummy: rcc=0x7f1505cf4560 (channel=0x7f1505aa61c0 type=6 id=0)
(/usr/sbin/xl:14270): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 39
snd_channel_put: SndChannel=0x7f1505ce3d20 freed

More information about the Spice-devel mailing list