Attachment 902192 Details for Bug 1104697 – qemu/spice log

qemu/spice log

colvm36.log (text/plain), 231.72 KB, created by Markus Stockhausen on 2014-06-04 14:07:36 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Markus Stockhausen

Created: 2014-06-04 14:07:36 UTC

Size: 231.72 KB

patch

obsolete

>2014-06-03 14:51:31.896+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 2048 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-03T16:51:31,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=25,id=hostnet0,vhost=on,vhostfd=26 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=4 is tainted: custom-argv
>((null):17898): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):17898): Spice-Warning **: reds.c:1863:reds_handle_ticket: Invalid password
>main_channel_link: add main channel client
>main_channel_handle_parsed: net test: latency 6.124000 ms, bitrate 209986670 bps (200.258894 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: agent start
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>((null):17898): Spice-Warning **: reds.c:879:reds_fill_channels: sent 3 out of 5
>main_channel_handle_parsed: net test: latency 5.452000 ms, bitrate 148137432 bps (141.274864 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7feae5048b70 (channel=0x7feae4cd95b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7fea306c6400 (channel=0x7fea3021f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7fea30ca0ec0 (channel=0x7fea3021f930 type=4 id=0)
>red_channel_client_disconnect: rcc=0x7feae4d959a0 (channel=0x7feae4ccf800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7feae4d959a0
>red_client_destroy: destroy client 0x7feae5047c40 with #channels=4
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: agent start
>red_channel_client_disconnect: rcc=0x7feae5008bd0 (channel=0x7feae4cd95b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7fea302b83b0 (channel=0x7fea3021f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7feae50276a0 (channel=0x7feae4da7590 type=6 id=0)
>snd_channel_put: SndChannel=0x7feae5065f40 freed
>red_channel_client_disconnect_dummy: rcc=0x7feae5019f30 (channel=0x7feae4da7730 type=5 id=0)
>snd_channel_put: SndChannel=0x7feae5076780 freed
>red_channel_client_disconnect: rcc=0x7feae5014bb0 (channel=0x7feae4ccf800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7feae5014bb0
>red_client_destroy: destroy client 0x7feae5008ac0 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: rcc=0x7fea3024b2b0 (channel=0x7fea3021f360 type=2 id=0)
>qemu: terminating on signal 15 from pid 1001
>2014-06-03 15:02:49.786+0000: shutting down
>2014-06-04 06:00:05.333+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D7-3000-D7483000C49E,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-03T17:04:15,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=5 is tainted: custom-argv
>((null):14714): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>red_dispatcher_loadvm_commands: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 6.834000 ms, bitrate 199066874 bps (189.844965 Mbps)
>red_dispatcher_set_cursor_peer: 
>inputs_connect: inputs channel client create
>red_channel_client_disconnect: rcc=0x7f365af1c480 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3658df5010 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564272af0 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365ae4b7a0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aea12e0 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365ae86ad0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365aecd7f0 freed
>red_channel_client_disconnect: rcc=0x7f365ae6c430 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365ae6c430
>red_client_destroy: destroy client 0x7f365af1a8f0 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: net test: latency 19.329000 ms, bitrate 52825711 bps (50.378524 Mbps)
>red_dispatcher_set_cursor_peer: 
>inputs_connect: inputs channel client create
>red_channel_client_disconnect_dummy: rcc=0x7f365ae86ad0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aea12e0 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365af1c480 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365aecd7f0 freed
>red_channel_client_disconnect: rcc=0x7f365ae4b7a0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f356424a1b0 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect: rcc=0x7f365ae6c430 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365ae6c430
>red_client_destroy: destroy client 0x7f365ae9d070 with #channels=6
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: rcc=0x7f35642bcd70 (channel=0x7f356421f360 type=2 id=0)
>red_dispatcher_disconnect_cursor_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 6.970000 ms, bitrate 261993091 bps (249.856082 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365ae4b7a0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f35642bcd70 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f356424ec80 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365af1c480 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aea12e0 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365ae86ad0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365aecd7f0 freed
>red_channel_client_disconnect: rcc=0x7f365ae6c430 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365ae6c430
>red_client_destroy: destroy client 0x7f365aef8040 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 27.002000 ms, bitrate 203436972 bps (194.012615 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: agent start
>red_channel_client_disconnect: rcc=0x7f365ae4b7a0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564262ad0 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365af1c480 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aea12e0 freed
>red_channel_client_disconnect: rcc=0x7f365ae6c430 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365ae6c430
>red_client_destroy: destroy client 0x7f365ae982b0 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_channel_client_disconnect_dummy: rcc=0x7f365ae86ad0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365aecd7f0 freed
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: rcc=0x7f35642b7180 (channel=0x7f356421f360 type=2 id=0)
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 6.584000 ms, bitrate 332036316 bps (316.654507 Mbps)
>red_dispatcher_set_cursor_peer: 
>inputs_connect: inputs channel client create
>red_channel_client_disconnect: rcc=0x7f365af25b30 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564c9c880 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3565453450 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365af1bad0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365ae71840 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 4.756000 ms, bitrate 406752730 bps (387.909632 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>((null):14714): Spice-Warning **: reds.c:879:reds_fill_channels: sent 3 out of 5
>main_channel_handle_parsed: net test: latency 7.157000 ms, bitrate 223434431 bps (213.083678 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365af25b30 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3565043350 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564276460 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365af1bad0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365ae03670 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: rcc=0x7f365aecd5f0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564f51900 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f365b42bb10 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365b42bb10
>red_client_destroy: destroy client 0x7f365af18d40 with #channels=4
>red_dispatcher_disconnect_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f356424c270 (channel=0x7f356421f930 type=4 id=0)
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 3.007000 ms, bitrate 533333333 bps (508.626302 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365aecd5f0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fc6aa0 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564273b30 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365b42bb10 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365ae03750 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 3.534000 ms, bitrate 382732199 bps (365.001868 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365af1bad0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fc6aa0 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fb9e30 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365b42bb10 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365adfc490 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 27.498000 ms, bitrate 202852614 bps (193.455328 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365aecd5f0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fc6aa0 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fb9e30 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365b42bb10 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365addb260 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 10.260000 ms, bitrate 391287734 bps (373.161062 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365af1bad0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fe02c0 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fd54c0 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365b42bb10 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365addb030 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 13.853000 ms, bitrate 261658362 bps (249.536860 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365af1bad0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3564fdbbe0 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3564269920 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365b42bb10 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365ae98940 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 5.636000 ms, bitrate 212162022 bps (202.333471 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7f365aecd5f0 (channel=0x7f365ad3a5b0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7f3565073260 (channel=0x7f356421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7f3565043350 (channel=0x7f356421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7f365b42bb10 (channel=0x7f365ae08590 type=6 id=0)
>snd_channel_put: SndChannel=0x7f365aeb9d30 freed
>red_channel_client_disconnect_dummy: rcc=0x7f365adfeac0 (channel=0x7f365ae08730 type=5 id=0)
>snd_channel_put: SndChannel=0x7f365ae72210 freed
>red_channel_client_disconnect: rcc=0x7f365adf69a0 (channel=0x7f365ad30800 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7f365adf69a0
>red_client_destroy: destroy client 0x7f365adfb800 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>2014-06-04 11:51:30.035+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>2014-06-04 11:52:15.428+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D7-3000-D7483000C49E,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T13:31:20,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=7 is tainted: custom-argv
>((null):28391): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>red_dispatcher_loadvm_commands: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 126.544000 ms, bitrate 11351543 bps (10.825675 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>((null):28391): Spice-Warning **: reds.c:879:reds_fill_channels: sent 3 out of 5
>main_channel_handle_parsed: net test: latency 8.029000 ms, bitrate 250305548 bps (238.709972 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_channel_client_disconnect: rcc=0x7fb9b6989710 (channel=0x7fb9b678e5d0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7fb8c42b59d0 (channel=0x7fb8c421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7fb8c429e360 (channel=0x7fb8c421f930 type=4 id=0)
>red_channel_client_disconnect: rcc=0x7fb9b696a770 (channel=0x7fb9b6784820 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7fb9b696a770
>red_client_destroy: destroy client 0x7fb9b68b3a70 with #channels=4
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: rcc=0x7fb9b695d000 (channel=0x7fb9b678e5d0 type=3 id=0)
>red_channel_client_disconnect: rcc=0x7fb9b5366010 (channel=0x7fb8c421f360 type=2 id=0)
>red_channel_client_disconnect: rcc=0x7fb8c426df80 (channel=0x7fb8c421f930 type=4 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7fb9b6942390 (channel=0x7fb9b6847800 type=6 id=0)
>snd_channel_put: SndChannel=0x7fb9b694c7c0 freed
>red_channel_client_disconnect_dummy: rcc=0x7fb9b68da7f0 (channel=0x7fb9b6836aa0 type=5 id=0)
>snd_channel_put: SndChannel=0x7fb9b6938a20 freed
>red_channel_client_disconnect: rcc=0x7fb9b6946ed0 (channel=0x7fb9b6784820 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7fb9b6946ed0
>red_client_destroy: destroy client 0x7fb9b692d6b0 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 50.984000 ms, bitrate 5892677 bps (5.619695 Mbps) LOW BANDWIDTH
>red_channel_client_disconnect: rcc=0x7fb8c42b48a0 (channel=0x7fb8c421f360 type=2 id=0)
>red_channel_client_disconnect_dummy: rcc=0x7fb9b6989710 (channel=0x7fb9b6847800 type=6 id=0)
>snd_channel_put: SndChannel=0x7fb9b694c7c0 freed
>red_channel_client_disconnect_dummy: rcc=0x7fb9b6942390 (channel=0x7fb9b6836aa0 type=5 id=0)
>snd_channel_put: SndChannel=0x7fb9b6938a20 freed
>red_peer_receive: Connection reset by peer
>red_channel_client_disconnect: rcc=0x7fb9b68da7f0 (channel=0x7fb9b6784820 type=1 id=0)
>main_channel_client_on_disconnect: rcc=0x7fb9b68da7f0
>red_client_destroy: destroy client 0x7fb9b68fbfa0 with #channels=4
>red_dispatcher_disconnect_display_peer: 
>main_channel_link: add main channel client
>main_channel_handle_parsed: agent start
>main_channel_handle_parsed: net test: latency 50.048000 ms, bitrate 4381067 bps (4.178111 Mbps) LOW BANDWIDTH
>qemu: terminating on signal 15 from pid 1001
>red_channel_client_disconnect_dummy: rcc=0x7fb9b6942390 (channel=0x7fb9b6836aa0 type=5 id=0)
>snd_channel_put: SndChannel=0x7fb9b6938a20 freed
>red_channel_client_disconnect_dummy: rcc=0x7fb9b6973d90 (channel=0x7fb9b6847800 type=6 id=0)
>snd_channel_put: SndChannel=0x7fb9b694c7c0 freed
>2014-06-04 13:39:26.125+0000: shutting down
>2014-06-04 13:39:56.128+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:39:55,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=25,id=hostnet0,vhost=on,vhostfd=26 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=9 is tainted: custom-argv
>((null):9840): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):9840): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):9840): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):9840): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):9840): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):9840): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):9840): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f05c07c4a80
>((null):9840): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f05c07c4a80
>((null):9840): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):9840): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):9840): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):9840): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):9840): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):9840): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f05c2c75f28
>((null):9840): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):9840): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):9840): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f05a1fff700
>((null):9840): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):9840): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f05a1fff700
>((null):9840): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):9840): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f05c07c4a80
>((null):9840): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):9840): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f05c07c4a80
>((null):9840): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4000b60
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4000b60: cookie 139662203029920
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4000d10
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4000d10: cookie 139662203029968
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):9840): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f05c2b9be78 dev_state 0x7f05c2c97ad0
>((null):9840): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f05c2c97ad0
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a40009d0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (511, 249) (928, 400)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a40009d0: cookie 139662203030800
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (511, 227) (928, 446)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068888224
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):9840): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):9840): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):9840): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f05c2c43120 mcc 0x7f05c2c869a0 connect-id 401200457
>((null):9840): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):9840): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f05c2c97ad0 client 0x7f05c2c43120
>main_channel_handle_parsed: net test: latency 71.348000 ms, bitrate 6324540 bps (6.031551 Mbps) LOW BANDWIDTH
>((null):9840): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):9840): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 45.12(ms)
>((null):9840): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):9840): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):9840): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):9840): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):9840): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):9840): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):9840): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):9840): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):9840): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):9840): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):9840): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):9840): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f05c2c43120) dcc 0x7f04cc24b450 stream 0x7f05c2c43670
>((null):9840): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):9840): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):9840): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):9840): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):9840): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):9840): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):9840): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):9840): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):9840): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 74.01(ms)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (511, 227) (943, 523)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (511, 249) (928, 400)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.97(ms)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4000d10
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4000d10: cookie 139662203029968
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a40009d0
>((null):9840): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a40009d0: cookie 139662203030800
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4000d10
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4000d10: cookie 139662203029968
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):9840): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):9840): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>((null):9840): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):9840): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):9840): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f05c2b9be78 dev_state 0x7f05c2b9b8a0
>((null):9840): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f05c2b9b8a0
>((null):9840): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):9840): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f05c2b9b8a0 client 0x7f05c2c43120
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (30, 33) (315, 147)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: reds.c:2383:reds_send_mm_time: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4027830
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 0) (200, 500)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4027830: cookie 139662203112944
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4014df0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 0) (1, 1)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4014df0: cookie 139662203189296
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4027830
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 0) (200, 500)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4027830: cookie 139662203112944
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 337) (475, 820)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (547, 276) (977, 337)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (547, 337) (977, 536)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4014df0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (334, 238) (1251, 276)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4014df0: cookie 139662203189296
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (334, 276) (547, 536)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4027830
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (977, 276) (1251, 536)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4027830: cookie 139662203112944
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f05a4014df0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (334, 536) (1251, 696)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f05a4014df0: cookie 139662203189296
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 337) (475, 536)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012960
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (0, 536) (475, 820)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012960: cookie 139662068887248
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):9840): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>red_channel_client_disconnect: rcc=0x7f05c2e59b90 (channel=0x7f05c2bca5b0 type=3 id=0)
>((null):9840): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>red_channel_client_disconnect: rcc=0x7f04cc24b450 (channel=0x7f04cc21f360 type=2 id=0)
>((null):9840): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):9840): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):9840): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=9, #red_draw=9, #glz_draw=0
>red_channel_client_disconnect: rcc=0x7f04cc2b82f0 (channel=0x7f04cc21f930 type=4 id=0)
>((null):9840): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):9840): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f05c2e3bbe0 rcc=0x7f05c2c6e2e0 type=6
>red_channel_client_disconnect_dummy: rcc=0x7f05c2c6e2e0 (channel=0x7f05c2c98590 type=6 id=0)
>((null):9840): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7f05c2e3bbe0 freed
>((null):9840): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f05c2e32270 rcc=0x7f05c2c581a0 type=5
>red_channel_client_disconnect_dummy: rcc=0x7f05c2c581a0 (channel=0x7f05c2c98730 type=5 id=0)
>((null):9840): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7f05c2e32270 freed
>red_channel_client_disconnect: rcc=0x7f05c2c869a0 (channel=0x7f05c2bc0800 type=1 id=0)
>((null):9840): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7f05c2c869a0
>((null):9840): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f05c2c43120
>((null):9840): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f05c2c43120
>((null):9840): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):9840): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7f05c2b9b8a0 client 0x7f05c2c43120
>((null):9840): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):9840): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7f05c2c43120 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):9840): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):9840): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f04cc2b82f0
>((null):9840): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f05c2e59b90
>red_dispatcher_disconnect_display_peer: 
>((null):9840): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):9840): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f04cc24b450
>((null):9840): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):9840): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f05c2c6e2e0
>((null):9840): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):9840): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f05c2c581a0
>((null):9840): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f05c2c869a0
>((null):9840): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f05c2c43120
>((null):9840): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):9840): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):9840): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):9840): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f05c2b9be78 dev_state 0x7f05c2c97ad0
>((null):9840): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f05c2c97ad0
>((null):9840): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f059c012ed0
>((null):9840): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):9840): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1175, 719) (1491, 835)
>((null):9840): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):9840): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f059c012ed0: cookie 139662068885856
>((null):9840): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):9840): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):9840): Spice-Info **: reds.c:3694:spice_server_migrate_connect: no client connected
>((null):9840): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):9840): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7f05c2c97ad0
>((null):9840): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):9840): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):9840): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):9840): Spice-Info **: reds.c:3760:spice_server_migrate_end: no peer connected
>2014-06-04 13:42:42.334+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):9840): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):9840): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):9840): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):9840): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:43:45.329+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:41:44,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=10 is tainted: custom-argv
>((null):10538): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):10538): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):10538): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):10538): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):10538): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):10538): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):10538): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f682c2a1a80
>((null):10538): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f682c2a1a80
>((null):10538): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):10538): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):10538): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):10538): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):10538): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):10538): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f682db9af28
>((null):10538): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):10538): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):10538): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f680dbff700
>((null):10538): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):10538): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f680dbff700
>((null):10538): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):10538): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f682c2a1a80
>((null):10538): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):10538): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f682c2a1a80
>((null):10538): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):10538): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):10538): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):10538): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):10538): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f682dac0e78 dev_state 0x7f682dbb8850
>((null):10538): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f682dbb8850
>((null):10538): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f682dbb8850
>((null):10538): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):10538): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10538): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):10538): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):10538): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f682dc4dc80 mcc 0x7f682db921e0 connect-id 538461340
>((null):10538): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):10538): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f682dbb8850 client 0x7f682dc4dc80
>main_channel_handle_parsed: net test: latency 48.227000 ms, bitrate 5711672 bps (5.447075 Mbps) LOW BANDWIDTH
>((null):10538): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):10538): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 30.05(ms)
>((null):10538): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10538): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):10538): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):10538): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10538): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):10538): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):10538): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):10538): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):10538): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f682dc4dc80) dcc 0x7f682c230010 stream 0x7f682dc805f0
>((null):10538): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):10538): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):10538): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10538): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):10538): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):10538): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):10538): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10538): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):10538): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10538): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):10538): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):10538): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>red_channel_client_disconnect: rcc=0x7f682dc5eb30 (channel=0x7f682daef5b0 type=3 id=0)
>((null):10538): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>red_channel_client_disconnect: rcc=0x7f682c230010 (channel=0x7f673821f360 type=2 id=0)
>((null):10538): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):10538): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):10538): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=38, #red_draw=38, #glz_draw=0
>red_channel_client_disconnect: rcc=0x7f67382828a0 (channel=0x7f673821f930 type=4 id=0)
>((null):10538): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):10538): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f682dc4e2f0 rcc=0x7f682dcd1380 type=6
>red_channel_client_disconnect_dummy: rcc=0x7f682dcd1380 (channel=0x7f682dbbd590 type=6 id=0)
>((null):10538): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7f682dc4e2f0 freed
>((null):10538): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f682dca0490 rcc=0x7f682dc3baa0 type=5
>red_channel_client_disconnect_dummy: rcc=0x7f682dc3baa0 (channel=0x7f682dbbd730 type=5 id=0)
>((null):10538): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7f682dca0490 freed
>red_channel_client_disconnect: rcc=0x7f682db921e0 (channel=0x7f682dae5800 type=1 id=0)
>((null):10538): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7f682db921e0
>((null):10538): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f682dc4dc80
>((null):10538): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f682dc4dc80
>((null):10538): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):10538): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7f682dbb8850 client 0x7f682dc4dc80
>((null):10538): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):10538): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7f682dc4dc80 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):10538): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):10538): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f67382828a0
>((null):10538): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f682dc5eb30
>((null):10538): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):10538): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f682dcd1380
>red_dispatcher_disconnect_display_peer: 
>((null):10538): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):10538): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f682c230010
>((null):10538): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):10538): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f682dc3baa0
>((null):10538): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f682db921e0
>((null):10538): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f682dc4dc80
>((null):10538): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):10538): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):10538): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):10538): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f682dac0e78 dev_state 0x7f682dac08a0
>((null):10538): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f682dac08a0
>((null):10538): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):10538): Spice-Info **: reds.c:3694:spice_server_migrate_connect: no client connected
>((null):10538): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):10538): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7f682dac08a0
>((null):10538): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):10538): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):10538): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):10538): Spice-Info **: reds.c:3760:spice_server_migrate_end: no peer connected
>2014-06-04 13:44:56.132+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):10538): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):10538): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):10538): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):10538): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:45:23.100+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:43:21,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=11 is tainted: custom-argv
>((null):10966): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):10966): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):10966): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):10966): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):10966): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):10966): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):10966): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7fb37f934a80
>((null):10966): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7fb37f934a80
>((null):10966): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):10966): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):10966): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):10966): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):10966): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):10966): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7fb380c63f28
>((null):10966): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):10966): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):10966): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7fb3653ff700
>((null):10966): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):10966): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7fb3653ff700
>((null):10966): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):10966): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7fb37f934a80
>((null):10966): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):10966): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7fb37f934a80
>((null):10966): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):10966): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):10966): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):10966): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):10966): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fb380b89e78 dev_state 0x7fb380c81850
>((null):10966): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fb380c81850
>((null):10966): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fb380c81850
>((null):10966): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):10966): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10966): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):10966): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):10966): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7fb380d48f80 mcc 0x7fb380c5b1e0 connect-id 806653416
>((null):10966): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):10966): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fb380c81850 client 0x7fb380d48f80
>main_channel_handle_parsed: net test: latency 61.651000 ms, bitrate 5503615 bps (5.248656 Mbps) LOW BANDWIDTH
>((null):10966): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):10966): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.26(ms)
>((null):10966): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10966): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):10966): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):10966): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10966): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):10966): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):10966): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10966): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):10966): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):10966): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):10966): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):10966): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7fb380d48f80) dcc 0x7fb37f8c3010 stream 0x7fb380d58ed0
>((null):10966): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):10966): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):10966): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):10966): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10966): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):10966): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):10966): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):10966): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):10966): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>red_channel_client_disconnect: rcc=0x7fb380d27b30 (channel=0x7fb380bb85b0 type=3 id=0)
>((null):10966): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>red_channel_client_disconnect: rcc=0x7fb37f8c3010 (channel=0x7fb28c21f360 type=2 id=0)
>((null):10966): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):10966): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):10966): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=38, #red_draw=38, #glz_draw=0
>red_channel_client_disconnect: rcc=0x7fb28c282690 (channel=0x7fb28c21f930 type=4 id=0)
>((null):10966): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):10966): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fb380d172f0 rcc=0x7fb380d9a380 type=6
>red_channel_client_disconnect_dummy: rcc=0x7fb380d9a380 (channel=0x7fb380c86590 type=6 id=0)
>((null):10966): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7fb380d172f0 freed
>((null):10966): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fb380d69490 rcc=0x7fb380d04aa0 type=5
>red_channel_client_disconnect_dummy: rcc=0x7fb380d04aa0 (channel=0x7fb380c86730 type=5 id=0)
>((null):10966): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7fb380d69490 freed
>red_channel_client_disconnect: rcc=0x7fb380c5b1e0 (channel=0x7fb380bae800 type=1 id=0)
>((null):10966): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7fb380c5b1e0
>((null):10966): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7fb380d48f80
>((null):10966): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7fb380d48f80
>((null):10966): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):10966): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7fb380c81850 client 0x7fb380d48f80
>((null):10966): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):10966): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7fb380d48f80 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):10966): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):10966): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fb28c282690
>((null):10966): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fb380d27b30
>red_dispatcher_disconnect_display_peer: 
>((null):10966): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):10966): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fb37f8c3010
>((null):10966): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):10966): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fb380d9a380
>((null):10966): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):10966): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fb380d04aa0
>((null):10966): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fb380c5b1e0
>((null):10966): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7fb380d48f80
>((null):10966): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):10966): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):10966): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):10966): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fb380b89e78 dev_state 0x7fb380b898a0
>((null):10966): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fb380b898a0
>((null):10966): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):10966): Spice-Info **: reds.c:3694:spice_server_migrate_connect: no client connected
>((null):10966): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):10966): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7fb380b898a0
>((null):10966): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):10966): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):10966): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):10966): Spice-Info **: reds.c:3760:spice_server_migrate_end: no peer connected
>2014-06-04 13:46:10.042+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):10966): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):10966): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):10966): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):10966): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:46:35.927+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:44:34,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=12 is tainted: custom-argv
>((null):11312): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):11312): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):11312): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):11312): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):11312): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):11312): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):11312): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f49e6eb6a80
>((null):11312): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f49e6eb6a80
>((null):11312): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):11312): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):11312): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):11312): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):11312): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):11312): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f49e7fccf28
>((null):11312): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):11312): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):11312): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f49cc9ff700
>((null):11312): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):11312): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f49cc9ff700
>((null):11312): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):11312): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f49e6eb6a80
>((null):11312): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):11312): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f49e6eb6a80
>((null):11312): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):11312): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):11312): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):11312): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):11312): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f49e7ef2e78 dev_state 0x7f49e7fea850
>((null):11312): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f49e7fea850
>((null):11312): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f49e7fea850
>((null):11312): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):11312): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11312): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):11312): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):11312): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f49e80c11a0 mcc 0x7f49e7fc41e0 connect-id 1151859661
>((null):11312): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):11312): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7f49e7fea850 client 0x7f49e80c11a0
>main_channel_handle_parsed: net test: latency 49.017000 ms, bitrate 7271668 bps (6.934803 Mbps) LOW BANDWIDTH
>((null):11312): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):11312): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 32.82(ms)
>((null):11312): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11312): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):11312): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):11312): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11312): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):11312): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):11312): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11312): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):11312): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):11312): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):11312): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):11312): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f49e80c11a0) dcc 0x7f49e6e45010 stream 0x7f49e7fca020
>((null):11312): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):11312): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):11312): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):11312): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11312): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):11312): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11312): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):11312): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):11312): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>red_channel_client_disconnect: rcc=0x7f49e8090b30 (channel=0x7f49e7f215b0 type=3 id=0)
>((null):11312): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>red_channel_client_disconnect: rcc=0x7f49e6e45010 (channel=0x7f48f421f360 type=2 id=0)
>((null):11312): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):11312): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=38, #red_draw=38, #glz_draw=0
>red_channel_client_disconnect: rcc=0x7f48f4282670 (channel=0x7f48f421f930 type=4 id=0)
>((null):11312): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):11312): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f49e80802f0 rcc=0x7f49e806daa0 type=6
>red_channel_client_disconnect_dummy: rcc=0x7f49e806daa0 (channel=0x7f49e7fef590 type=6 id=0)
>((null):11312): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7f49e80802f0 freed
>((null):11312): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f49e80d2490 rcc=0x7f49e8103380 type=5
>red_channel_client_disconnect_dummy: rcc=0x7f49e8103380 (channel=0x7f49e7fef730 type=5 id=0)
>((null):11312): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7f49e80d2490 freed
>red_channel_client_disconnect: rcc=0x7f49e7fc41e0 (channel=0x7f49e7f17800 type=1 id=0)
>((null):11312): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7f49e7fc41e0
>((null):11312): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f49e80c11a0
>((null):11312): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f49e80c11a0
>((null):11312): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):11312): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7f49e7fea850 client 0x7f49e80c11a0
>((null):11312): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):11312): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7f49e80c11a0 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):11312): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):11312): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f48f4282670
>((null):11312): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f49e8090b30
>red_dispatcher_disconnect_display_peer: 
>((null):11312): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):11312): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f49e6e45010
>((null):11312): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):11312): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f49e8103380
>((null):11312): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):11312): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f49e806daa0
>((null):11312): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f49e7fc41e0
>((null):11312): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f49e80c11a0
>((null):11312): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):11312): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):11312): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):11312): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f49e7ef2e78 dev_state 0x7f49e7ef28a0
>((null):11312): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f49e7ef28a0
>((null):11312): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f49c0000950
>((null):11312): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11312): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f49c0000950: cookie 139954730567968
>((null):11312): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11312): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f49c0000920
>((null):11312): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11312): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f49c0000920: cookie 139954730568016
>((null):11312): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11312): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f49c0000950
>((null):11312): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11312): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f49c0000950: cookie 139954730567968
>((null):11312): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11312): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f49c0000920
>((null):11312): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11312): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):11312): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11312): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f49c0000920: cookie 139954730568016
>((null):11312): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11312): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):11312): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7f49c8000950
>((null):11312): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11312): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (554, 205) (971, 434)
>((null):11312): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11312): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7f49c8000950: cookie 139954864785696
>((null):11312): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11312): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):11312): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7f49e7ef2e78 dev_state 0x7f49e7f5cf80
>((null):11312): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7f49e7f5cf80
>((null):11312): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):11312): Spice-Info **: reds.c:3694:spice_server_migrate_connect: no client connected
>((null):11312): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):11312): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7f49e7f5cf80
>((null):11312): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):11312): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):11312): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):11312): Spice-Info **: reds.c:3760:spice_server_migrate_end: no peer connected
>2014-06-04 13:47:47.957+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):11312): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):11312): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):11312): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):11312): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:48:10.084+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:46:08,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=13 is tainted: custom-argv
>((null):11734): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):11734): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):11734): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):11734): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):11734): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):11734): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):11734): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7fa1e3ea3a80
>((null):11734): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7fa1e3ea3a80
>((null):11734): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):11734): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):11734): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):11734): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):11734): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):11734): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7fa1e4f02f28
>((null):11734): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):11734): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):11734): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7fa1c57ff700
>((null):11734): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):11734): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7fa1c57ff700
>((null):11734): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):11734): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7fa1e3ea3a80
>((null):11734): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):11734): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7fa1e3ea3a80
>((null):11734): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):11734): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):11734): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):11734): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):11734): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fa1e4e28e78 dev_state 0x7fa1e4f20850
>((null):11734): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fa1e4f20850
>((null):11734): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fa1e4f20850
>((null):11734): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):11734): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11734): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):11734): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):11734): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7fa1e4fe8010 mcc 0x7fa1e4efa1e0 connect-id 473665068
>((null):11734): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):11734): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fa1e4f20850 client 0x7fa1e4fe8010
>main_channel_handle_parsed: net test: latency 51.453000 ms, bitrate 9413841 bps (8.977738 Mbps) LOW BANDWIDTH
>((null):11734): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):11734): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.34(ms)
>((null):11734): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11734): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):11734): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):11734): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11734): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):11734): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):11734): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11734): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):11734): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):11734): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):11734): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):11734): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7fa1e4fe8010) dcc 0x7fa1e3e32010 stream 0x7fa1e4f20190
>((null):11734): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):11734): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):11734): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):11734): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11734): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):11734): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):11734): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):11734): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):11734): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>((null):11734): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.29(ms)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c8000950
>((null):11734): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11734): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (554, 205) (986, 573)
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c8000950: cookie 140332821907744
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000f50
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000f50: cookie 140332687690016
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c8000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c8000920: cookie 140332821907792
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c8000950
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c8000950: cookie 140332821907744
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000920: cookie 140332687691600
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000f50
>((null):11734): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11734): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1175, 719) (1486, 830)
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000f50: cookie 140332687690016
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11734): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (308, 535) (700, 573)
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000920: cookie 140332687691600
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000f50
>((null):11734): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11734): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (1175, 719) (1486, 830)
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000f50: cookie 140332687690016
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11734): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (308, 535) (700, 573)
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000920: cookie 140332687691600
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):11734): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):11734): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>((null):11734): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):11734): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):11734): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fa1e4e28e78 dev_state 0x7fa1e4f95df0
>((null):11734): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fa1e4f95df0
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):11734): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fa1e4f95df0 client 0x7fa1e4fe8010
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c8000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c8000920: cookie 140332821907792
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000f50
>((null):11734): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000f50: cookie 140332687690016
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c8000950
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c8000950: cookie 140332821907744
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c8000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c8000920: cookie 140332821907792
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Info **: reds.c:3006:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent
>((null):11734): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):11734): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>((null):11734): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 1
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Debug **: red_dispatcher.c:353:async_command_alloc: 0x7fa1c0000920
>((null):11734): SpiceWorker-Debug **: red_worker.c:4718:red_update_area: surface 0: area ==>
>((null):11734): SpiceWorker-Debug **: ../spice-common/common/rect.h:91:rect_debug: (554, 205) (971, 434)
>((null):11734): SpiceWorker-Debug **: red_worker.c:11688:worker_handle_dispatcher_async_done: 
>((null):11734): Spice-Debug **: red_dispatcher.c:1019:red_dispatcher_async_complete: 0x7fa1c0000920: cookie 140332687691600
>((null):11734): Spice-Debug **: red_dispatcher.c:1021:red_dispatcher_async_complete: no more async commands
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):11734): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fa1e4e28e78 dev_state 0x7fa1e4f200c0
>((null):11734): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fa1e4f200c0
>((null):11734): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>main_channel_handle_parsed: agent start
>((null):11734): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fa1e4f200c0 client 0x7fa1e4fe8010
>((null):11734): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):11734): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.97(ms)
>((null):11734): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):11734): Spice-Info **: reds.c:2674:reds_mig_started: 
>main_channel_client_handle_migrate_connected: client 0x7fa1e4fe8010 connected: 1 seamless 0
>((null):11734): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):11734): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7fa1e4f200c0
>((null):11734): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):11734): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):11734): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):11734): Spice-Info **: reds.c:2746:reds_mig_finished: 
>main_channel_migrate_src_complete: 
>main_channel_migrate_src_complete: client 0x7fa1e4fe8010 MIGRATE_END
>red_channel_client_disconnect: rcc=0x7fa1e4efa1e0 (channel=0x7fa1e4e4d800 type=1 id=0)
>((null):11734): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7fa1e4efa1e0
>((null):11734): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7fa1e4fe8010
>((null):11734): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7fa1e4fe8010
>((null):11734): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):11734): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7fa1e4f200c0 client 0x7fa1e4fe8010
>((null):11734): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):11734): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7fa1e4fe8010 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):11734): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>red_channel_client_disconnect: rcc=0x7fa0f026df80 (channel=0x7fa0f021f930 type=4 id=0)
>((null):11734): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):11734): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fa0f026df80
>red_channel_client_disconnect: rcc=0x7fa1e4fc6b30 (channel=0x7fa1e4e575b0 type=3 id=0)
>((null):11734): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>((null):11734): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fa1e4fc6b30
>red_dispatcher_disconnect_display_peer: 
>((null):11734): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>red_channel_client_disconnect: rcc=0x7fa1e3e32010 (channel=0x7fa0f021f360 type=2 id=0)
>((null):11734): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):11734): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):11734): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=0, #red_draw=0, #glz_draw=0
>((null):11734): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fa1e3e32010
>((null):11734): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):11734): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fa1e5008490 rcc=0x7fa1e5039380 type=5
>red_channel_client_disconnect_dummy: rcc=0x7fa1e5039380 (channel=0x7fa1e4f25730 type=5 id=0)
>((null):11734): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7fa1e5008490 freed
>((null):11734): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fa1e5039380
>((null):11734): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):11734): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fa1e4fb62f0 rcc=0x7fa1e4fa3aa0 type=6
>red_channel_client_disconnect_dummy: rcc=0x7fa1e4fa3aa0 (channel=0x7fa1e4f25590 type=6 id=0)
>((null):11734): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7fa1e4fb62f0 freed
>((null):11734): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fa1e4fa3aa0
>((null):11734): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fa1e4efa1e0
>((null):11734): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7fa1e4fe8010
>2014-06-04 13:49:21.091+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):11734): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):11734): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):11734): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):11734): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:49:40.252+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:47:39,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=14 is tainted: custom-argv
>((null):12084): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):12084): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):12084): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):12084): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):12084): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):12084): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):12084): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7fd24b713a80
>((null):12084): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7fd24b713a80
>((null):12084): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):12084): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):12084): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):12084): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):12084): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):12084): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7fd24d718f28
>((null):12084): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):12084): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):12084): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7fd2311ff700
>((null):12084): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):12084): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7fd2311ff700
>((null):12084): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):12084): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7fd24b713a80
>((null):12084): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):12084): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7fd24b713a80
>((null):12084): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):12084): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7fd24d7a4c50 mcc 0x7fd24d79b220 connect-id 473665068
>((null):12084): Spice-Info **: reds.c:1448:reds_mig_target_client_add: 
>((null):12084): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>((null):12084): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):12084): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):12084): Spice-Info **: reds.c:2960:spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>((null):12084): Spice-Debug **: char_device.c:661:spice_char_device_state_create: sin 0x7fd24d63ee78 dev_state 0x7fd24d7c4660
>main_channel_send_item: Init msg for client 0x7fd24d7a4c50 was not sent yet (client is probably during semi-seamless migration). Ignoring msg type 103
>main_channel_send_item: Init msg for client 0x7fd24d7a4c50 was not sent yet (client is probably during semi-seamless migration). Ignoring msg type 116
>((null):12084): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fd24d7c4660
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 5616.04(ms)
>((null):12084): Spice-Debug **: char_device.c:795:spice_char_device_start: dev_state 0x7fd24d7c4660
>((null):12084): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):12084): Spice-Info **: reds.c:1743:reds_on_client_semi_seamless_migrate_complete: 0x7fd24d7a4c50
>((null):12084): Spice-Info **: reds.c:1683:reds_link_mig_target_channels: 0x7fd24d7a4c50
>red_dispatcher_set_cursor_peer: 
>((null):12084): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):12084): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):12084): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):12084): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>((null):12084): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):12084): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):12084): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):12084): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7fd24d7a4c50) dcc 0x7fd24b6a2010 stream 0x7fd24d716520
>((null):12084): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg disabled
>((null):12084): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz disabled
>inputs_connect: inputs channel client create
>main_channel_handle_parsed: agent start
>((null):12084): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fd24d7c4660 client 0x7fd24d7a4c50
>((null):12084): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):12084): SpiceWorker-Info **: red_worker.c:11610:handle_dev_set_mouse_mode: mouse mode 2
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 26.03(ms)
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.77(ms)
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.76(ms)
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):12084): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7fd24d844aa0 mcc 0x7fd24d81d180 connect-id 1077365182
>main_channel_handle_parsed: agent start
>((null):12084): Spice-Debug **: char_device.c:740:spice_char_device_client_add: dev_state 0x7fd24d7c4660 client 0x7fd24d844aa0
>((null):12084): Spice-Warning **: reds.c:879:reds_fill_channels: sent 3 out of 5
>main_channel_handle_parsed: net test: latency 51.030000 ms, bitrate 8024480 bps (7.652740 Mbps) LOW BANDWIDTH
>((null):12084): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 35.70(ms)
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):12084): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):12084): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):12084): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7fd24d844aa0) dcc 0x7fd158274c50 stream 0x7fd24d7151d0
>((null):12084): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):12084): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):12084): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.79(ms)
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):12084): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12084): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):12084): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>((null):12084): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>((null):12084): SpiceWorker-Debug **: red_worker.c:10485:display_channel_release_item: not pushed (101)
>red_channel_client_disconnect: rcc=0x7fd24d880f70 (channel=0x7fd24d66d5b0 type=3 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 105
>red_channel_client_disconnect: rcc=0x7fd158274c50 (channel=0x7fd15821f360 type=2 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 104
>((null):12084): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=23, #red_draw=23, #glz_draw=12
>red_channel_client_disconnect: rcc=0x7fd1582d1ad0 (channel=0x7fd15821f930 type=4 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 106
>red_channel_client_disconnect: rcc=0x7fd24d81d180 (channel=0x7fd24d663800 type=1 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 103
>main_channel_client_on_disconnect: rcc=0x7fd24d81d180
>((null):12084): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7fd24d844aa0
>((null):12084): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7fd24d844aa0
>((null):12084): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):12084): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7fd24d7c4660 client 0x7fd24d844aa0
>((null):12084): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):12084): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7fd24d844aa0 with #channels=4
>red_dispatcher_disconnect_cursor_peer: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd1582d1ad0
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24d880f70
>red_dispatcher_disconnect_display_peer: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd158274c50
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24d81d180
>((null):12084): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7fd24d844aa0
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.42(ms)
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.12(ms)
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.93(ms)
>((null):12084): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.82(ms)
>((null):12084): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):12084): Spice-Info **: reds.c:2674:reds_mig_started: 
>main_channel_client_handle_migrate_connected: client 0x7fd24d7a4c50 connected: 0 seamless 0
>((null):12084): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):12084): Spice-Debug **: char_device.c:805:spice_char_device_stop: dev_state 0x7fd24d7c4660
>((null):12084): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):12084): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):12084): Spice-Info **: reds.c:2746:reds_mig_finished: 
>main_channel_migrate_src_complete: 
>main_channel_migrate_src_complete: client 0x7fd24d7a4c50 SWITCH_HOST
>main_channel_marshall_migrate_switch: 
>red_channel_client_disconnect: rcc=0x7fd24d86a4d0 (channel=0x7fd24d66d5b0 type=3 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 33
>red_channel_client_disconnect: rcc=0x7fd24b6a2010 (channel=0x7fd15821f360 type=2 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 34
>((null):12084): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):12084): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=0, #red_draw=0, #glz_draw=0
>red_channel_client_disconnect: rcc=0x7fd1582452a0 (channel=0x7fd15821f930 type=4 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 35
>((null):12084): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fd24d831a40 rcc=0x7fd24d7299d0 type=6
>red_channel_client_disconnect_dummy: rcc=0x7fd24d7299d0 (channel=0x7fd24d73b590 type=6 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 36
>snd_channel_put: SndChannel=0x7fd24d831a40 freed
>((null):12084): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7fd24d7fa070 rcc=0x7fd24d7bbfa0 type=5
>red_channel_client_disconnect_dummy: rcc=0x7fd24d7bbfa0 (channel=0x7fd24d73b730 type=5 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 37
>snd_channel_put: SndChannel=0x7fd24d7fa070 freed
>red_channel_client_disconnect: rcc=0x7fd24d79b220 (channel=0x7fd24d663800 type=1 id=0)
>((null):12084): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 32
>main_channel_client_on_disconnect: rcc=0x7fd24d79b220
>((null):12084): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7fd24d7a4c50
>((null):12084): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7fd24d7a4c50
>((null):12084): Spice-Info **: reds.c:445:reds_client_disconnect: 
>((null):12084): Spice-Debug **: char_device.c:772:spice_char_device_client_remove: dev_state 0x7fd24d7c4660 client 0x7fd24d7a4c50
>((null):12084): Spice-Debug **: char_device.c:127:spice_char_device_client_send_queue_free: send_queue_empty 1
>((null):12084): Spice-Debug **: char_device.c:154:spice_char_device_client_free: write_queue_is_empty 1
>red_client_destroy: destroy client 0x7fd24d7a4c50 with #channels=6
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24d86a4d0
>red_dispatcher_disconnect_display_peer: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24b6a2010
>((null):12084): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24d7bbfa0
>red_dispatcher_disconnect_cursor_peer: 
>((null):12084): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd1582452a0
>((null):12084): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24d7299d0
>((null):12084): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7fd24d79b220
>((null):12084): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7fd24d7a4c50
>2014-06-04 13:51:25.215+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):12084): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):12084): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):12084): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):12084): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:52:39.696+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:50:38,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=15 is tainted: custom-argv
>((null):12689): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):12689): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):12689): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):12689): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):12689): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):12689): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):12689): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f9f8c389a80
>((null):12689): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f9f8c389a80
>((null):12689): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):12689): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):12689): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):12689): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):12689): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):12689): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f9f8ef33f28
>((null):12689): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):12689): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):12689): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f9f6dbff700
>((null):12689): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):12689): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f9f6dbff700
>((null):12689): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):12689): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f9f8c389a80
>((null):12689): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):12689): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f9f8c389a80
>((null):12689): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):12689): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):12689): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):12689): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):12689): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12689): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):12689): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):12689): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f9f8ef22b60 mcc 0x7f9f8f04f840 connect-id 1345023415
>main_channel_handle_parsed: net test: latency 54.873000 ms, bitrate 5465910 bps (5.212698 Mbps) LOW BANDWIDTH
>((null):12689): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):12689): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 23.46(ms)
>((null):12689): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12689): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):12689): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):12689): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12689): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):12689): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):12689): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f9f8ef22b60) dcc 0x7f9f8c318010 stream 0x7f9f8f036520
>((null):12689): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):12689): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):12689): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):12689): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):12689): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12689): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):12689): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):12689): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):12689): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12689): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):12689): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):12689): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):12689): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):12689): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>((null):12689): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.06(ms)
>red_channel_client_disconnect: rcc=0x7f9f8efc8590 (channel=0x7f9f8ee885b0 type=3 id=0)
>((null):12689): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>red_channel_client_disconnect: rcc=0x7f9f8c318010 (channel=0x7f9e9821f360 type=2 id=0)
>((null):12689): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):12689): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):12689): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=0, #red_draw=0, #glz_draw=0
>red_channel_client_disconnect: rcc=0x7f9e9826df80 (channel=0x7f9e9821f930 type=4 id=0)
>((null):12689): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):12689): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f9f8efe5f40 rcc=0x7f9f8f00d210 type=6
>red_channel_client_disconnect_dummy: rcc=0x7f9f8f00d210 (channel=0x7f9f8ef56590 type=6 id=0)
>((null):12689): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7f9f8efe5f40 freed
>((null):12689): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f9f8f03c930 rcc=0x7f9f8f01dc10 type=5
>red_channel_client_disconnect_dummy: rcc=0x7f9f8f01dc10 (channel=0x7f9f8ef56730 type=5 id=0)
>((null):12689): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7f9f8f03c930 freed
>red_channel_client_disconnect: rcc=0x7f9f8f04f840 (channel=0x7f9f8ee7e800 type=1 id=0)
>((null):12689): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7f9f8f04f840
>((null):12689): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f9f8ef22b60
>((null):12689): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f9f8ef22b60
>((null):12689): Spice-Info **: reds.c:445:reds_client_disconnect: 
>red_client_destroy: destroy client 0x7f9f8ef22b60 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):12689): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>((null):12689): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f9e9826df80
>((null):12689): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f9f8efc8590
>((null):12689): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):12689): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f9f8f00d210
>((null):12689): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):12689): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f9f8f01dc10
>red_dispatcher_disconnect_display_peer: 
>((null):12689): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):12689): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f9f8c318010
>((null):12689): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f9f8f04f840
>((null):12689): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f9f8ef22b60
>((null):12689): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):12689): Spice-Info **: reds.c:3694:spice_server_migrate_connect: no client connected
>((null):12689): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):12689): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):12689): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):12689): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):12689): Spice-Info **: reds.c:3760:spice_server_migrate_end: no peer connected
>2014-06-04 13:53:23.848+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):12689): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):12689): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):12689): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):12689): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:53:44.482+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:51:43,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=16 is tainted: custom-argv
>((null):13016): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):13016): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):13016): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):13016): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):13016): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):13016): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):13016): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f60128cfa80
>((null):13016): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f60128cfa80
>((null):13016): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):13016): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):13016): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):13016): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):13016): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):13016): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f60152c1f28
>((null):13016): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):13016): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):13016): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f5f25fff700
>((null):13016): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):13016): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f5f25fff700
>((null):13016): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):13016): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f60128cfa80
>((null):13016): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):13016): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f60128cfa80
>((null):13016): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):13016): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>red_dispatcher_loadvm_commands: 
>((null):13016): SpiceWorker-Info **: red_worker.c:11672:handle_dev_loadvm_commands: loadvm_commands
>((null):13016): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):13016): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13016): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):13016): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):13016): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f60153e3a90 mcc 0x7f60153dd840 connect-id 903369408
>main_channel_handle_parsed: net test: latency 53.694000 ms, bitrate 7240919 bps (6.905478 Mbps) LOW BANDWIDTH
>((null):13016): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):13016): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 26.32(ms)
>((null):13016): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13016): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13016): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):13016): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13016): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):13016): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):13016): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):13016): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):13016): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f60153e3a90) dcc 0x7f601285e010 stream 0x7f60153c4520
>((null):13016): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):13016): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):13016): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13016): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):13016): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13016): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):13016): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13016): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>((null):13016): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13016): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 4:0, connected successfully, over Secure link
>red_dispatcher_set_cursor_peer: 
>((null):13016): SpiceWorker-Info **: red_worker.c:11512:handle_dev_cursor_connect: cursor connect
>((null):13016): SpiceWorker-Info **: red_worker.c:10781:red_connect_cursor: add cursor channel client
>((null):13016): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 26.86(ms)
>((null):13016): Spice-Info **: reds.c:3658:spice_server_migrate_connect: 
>((null):13016): Spice-Info **: reds.c:2674:reds_mig_started: 
>main_channel_client_handle_migrate_connected: client 0x7f60153e3a90 connected: 1 seamless 0
>((null):13016): Spice-Info **: reds.c:3719:spice_server_migrate_start: 
>((null):13016): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.88(ms)
>((null):13016): Spice-Debug **: red_dispatcher.c:822:red_dispatcher_on_vm_stop: 
>((null):13016): SpiceWorker-Info **: red_worker.c:11259:handle_dev_stop: stop
>((null):13016): Spice-Info **: reds.c:3745:spice_server_migrate_end: 
>((null):13016): Spice-Info **: reds.c:2746:reds_mig_finished: 
>main_channel_migrate_src_complete: 
>main_channel_migrate_src_complete: client 0x7f60153e3a90 MIGRATE_END
>red_channel_client_disconnect: rcc=0x7f60153dd840 (channel=0x7f601520c800 type=1 id=0)
>((null):13016): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7f60153dd840
>((null):13016): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f60153e3a90
>((null):13016): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f60153e3a90
>((null):13016): Spice-Info **: reds.c:445:reds_client_disconnect: 
>red_client_destroy: destroy client 0x7f60153e3a90 with #channels=6
>red_dispatcher_disconnect_cursor_peer: 
>((null):13016): SpiceWorker-Info **: red_worker.c:11525:handle_dev_cursor_disconnect: disconnect cursor client
>red_channel_client_disconnect: rcc=0x7f5f2026df80 (channel=0x7f5f2021f930 type=4 id=0)
>((null):13016): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 102
>((null):13016): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f5f2026df80
>red_channel_client_disconnect: rcc=0x7f6015356590 (channel=0x7f60152165b0 type=3 id=0)
>((null):13016): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 101
>((null):13016): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f6015356590
>((null):13016): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):13016): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f6015373f40 rcc=0x7f601539b210 type=6
>red_channel_client_disconnect_dummy: rcc=0x7f601539b210 (channel=0x7f60152e4590 type=6 id=0)
>((null):13016): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7f6015373f40 freed
>((null):13016): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f601539b210
>red_dispatcher_disconnect_display_peer: 
>((null):13016): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>red_channel_client_disconnect: rcc=0x7f601285e010 (channel=0x7f5f2021f360 type=2 id=0)
>((null):13016): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):13016): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):13016): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=0, #red_draw=0, #glz_draw=0
>((null):13016): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f601285e010
>((null):13016): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):13016): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f60153ca930 rcc=0x7f60153abc10 type=5
>red_channel_client_disconnect_dummy: rcc=0x7f60153abc10 (channel=0x7f60152e4730 type=5 id=0)
>((null):13016): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7f60153ca930 freed
>((null):13016): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f60153abc10
>((null):13016): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f60153dd840
>((null):13016): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f60153e3a90
>2014-06-04 13:54:24.702+0000: shutting down
>qemu: terminating on signal 15 from pid 1001
>((null):13016): Spice-Info **: reds.c:3141:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>((null):13016): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13016): Spice-Info **: reds.c:3144:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD
>((null):13016): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>2014-06-04 13:55:10.305+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_ALLOW_MC=1 SPICE_DEBUG_LEVEL=4 /usr/bin/qemu-kvm -name colvm36 -S -machine pc-1.0,accel=kvm,usb=off -cpu Nehalem -m 3072 -realtime mlock=off -smp 2,maxcpus=160,sockets=80,cores=2,threads=1 -uuid 803870d2-e365-4ab4-bdcc-4c1d25a03944 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=20-3,serial=49434D53-0200-48D6-3000-D64830004AC9,uuid=803870d2-e365-4ab4-bdcc-4c1d25a03944 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/colvm36.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-04T15:53:09,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/10.10.30.252:_var_nas2_OVirtIB/272ec473-6041-42ee-bd1a-732789dd18d4/images/12843faf-ebbc-4732-b4e9-0bb2501c194f/04b45378-e7b8-4488-b48a-6b3fac1f18a4,if=none,id=drive-virtio-disk0,format=raw,serial=12843faf-ebbc-4732-b4e9-0bb2501c194f,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=26,id=hostnet0,vhost=on,vhostfd=27 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:0c:29:05:09:1e,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/803870d2-e365-4ab4-bdcc-4c1d25a03944.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice tls-port=5900,addr=192.168.11.42,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -device qxl-vga,id=video0,ram_size=67108864,vram_size=33554432,bus=pci.0,addr=0x2 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:[::]:49152 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x7
>Domain id=17 is tainted: custom-argv
>((null):13444): Spice-Debug **: reds.c:3820:spice_server_set_seamless_migration: seamless migration enabled=1
>((null):13444): Spice-Info **: reds.c:3182:do_spice_init: starting 0.12.5
>((null):13444): Spice-Info **: reds.c:2355:reds_init_socket: bound to 192.168.11.42:5900
>((null):13444): Spice-Info **: reds.c:2555:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>((null):13444): Spice-Info **: reds.c:2566:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>((null):13444): Spice-Info **: reds.c:2575:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>((null):13444): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 1 id 0 thread_id 0x7f35894a3a80
>((null):13444): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 3 id 0 thread_id 0x7f35894a3a80
>((null):13444): Spice-Warning **: reds.c:3263:do_spice_init: spice: allowing multiple client connections (crashy)
>((null):13444): Spice-Info **: reds.c:3114:spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>((null):13444): Spice-Info **: reds.c:3035:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>((null):13444): Spice-Info **: reds.c:3045:spice_server_add_interface: SPICE_INTERFACE_MOUSE
>((null):13444): Spice-Info **: reds.c:3057:spice_server_add_interface: SPICE_INTERFACE_QXL
>((null):13444): Spice-Debug **: red_dispatcher.c:1098:red_dispatcher_init: red_dispatcher->async_commands.next 0x7f358aeddf28
>((null):13444): SpiceWorker-Info **: red_worker.c:11967:red_worker_main: begin
>((null):13444): SpiceWorker-Info **: red_worker.c:10498:display_channel_create: create display channel
>((null):13444): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 2 id 0 thread_id 0x7f349cdff700
>((null):13444): SpiceWorker-Info **: red_worker.c:10753:cursor_channel_create: create cursor channel
>((null):13444): Spice-Debug **: red_channel.c:1077:red_channel_create: channel type 4 id 0 thread_id 0x7f349cdff700
>((null):13444): Spice-Info **: reds.c:3094:spice_server_add_interface: SPICE_INTERFACE_RECORD
>((null):13444): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 6 id 0 thread_id 0x7f35894a3a80
>((null):13444): Spice-Info **: reds.c:3085:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>((null):13444): Spice-Debug **: red_channel.c:1123:red_channel_create_dummy: channel type 5 id 0 thread_id 0x7f35894a3a80
>((null):13444): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):13444): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):13444): SpiceWorker-Debug **: red_worker.c:10997:dev_destroy_surfaces: 
>((null):13444): SpiceWorker-Debug **: red_worker.c:11141:dev_create_primary_surface: 
>((null):13444): SpiceWorker-Debug **: red_worker.c:1236:monitors_config_decref: freeing monitors config
>((null):13444): SpiceWorker-Debug **: red_worker.c:11190:dev_destroy_primary_surface: 
>((null):13444): Spice-Debug **: red_dispatcher.c:833:red_dispatcher_on_vm_start: 
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):13444): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f358aff3190 mcc 0x7f358af6c2f0 connect-id 1592650760
>main_channel_handle_parsed: net test: latency 43.237000 ms, bitrate 8719011 bps (8.315097 Mbps) LOW BANDWIDTH
>((null):13444): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.26(ms)
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):13444): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):13444): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):13444): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):13444): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):13444): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f358aff3190) dcc 0x7f3589432010 stream 0x7f358afc9c90
>((null):13444): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):13444): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):13444): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13444): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 26.54(ms)
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.19(ms)
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.93(ms)
>red_channel_client_disconnect: rcc=0x7f3589432010 (channel=0x7f349821f360 type=2 id=0)
>((null):13444): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 100
>((null):13444): SpiceWorker-Info **: red_worker.c:9239:display_channel_client_on_disconnect: 
>((null):13444): SpiceWorker-Debug **: red_worker.c:9261:display_channel_client_on_disconnect: #draw=0, #red_draw=0, #glz_draw=0
>((null):13444): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f358afa3740 rcc=0x7f358aec01a0 type=6
>red_channel_client_disconnect_dummy: rcc=0x7f358aec01a0 (channel=0x7f358af00590 type=6 id=0)
>((null):13444): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 99
>snd_channel_put: SndChannel=0x7f358afa3740 freed
>((null):13444): Spice-Debug **: snd_worker.c:211:snd_disconnect_channel: SndChannel=0x7f358af86e50 rcc=0x7f358af907c0 type=5
>red_channel_client_disconnect_dummy: rcc=0x7f358af907c0 (channel=0x7f358af00730 type=5 id=0)
>((null):13444): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 98
>snd_channel_put: SndChannel=0x7f358af86e50 freed
>red_channel_client_disconnect: rcc=0x7f358af6c2f0 (channel=0x7f358ae28800 type=1 id=0)
>((null):13444): Spice-Info **: reds_stream.c:276:reds_stream_free: close socket fd 97
>main_channel_client_on_disconnect: rcc=0x7f358af6c2f0
>((null):13444): Spice-Debug **: main_dispatcher.c:160:main_dispatcher_client_disconnect: client 0x7f358aff3190
>((null):13444): Spice-Debug **: main_dispatcher.c:121:main_dispatcher_handle_client_disconnect: client=0x7f358aff3190
>((null):13444): Spice-Info **: reds.c:445:reds_client_disconnect: 
>red_client_destroy: destroy client 0x7f358aff3190 with #channels=4
>((null):13444): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=6
>((null):13444): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f358aec01a0
>red_dispatcher_disconnect_display_peer: 
>((null):13444): SpiceWorker-Info **: red_worker.c:11443:handle_dev_display_disconnect: disconnect display client
>((null):13444): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f3589432010
>((null):13444): Spice-Debug **: snd_worker.c:988:snd_disconnect_channel_client: channel-type=5
>((null):13444): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f358af907c0
>((null):13444): Spice-Debug **: red_channel.c:1241:red_channel_client_unref: destroy rcc=0x7f358af6c2f0
>((null):13444): Spice-Debug **: red_channel.c:2083:red_client_unref: release client=0x7f358aff3190
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1551:reds_handle_main_link: 
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>((null):13444): Spice-Info **: reds.c:1590:reds_handle_main_link: NEW Client 0x7f358af7c970 mcc 0x7f358af6c2f0 connect-id 743159465
>main_channel_handle_parsed: net test: latency 47.907000 ms, bitrate 7664039 bps (7.308997 Mbps) LOW BANDWIDTH
>((null):13444): Spice-Debug **: red_channel.c:838:red_channel_client_start_connectivity_monitoring: 
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.03(ms)
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 5:0, connected successfully, over Secure link
>((null):13444): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 6:0, connected successfully, over Secure link
>((null):13444): Spice-Debug **: snd_worker.c:207:snd_disconnect_channel: not connected
>((null):13444): Spice-Info **: reds.c:2014:reds_handle_auth_mechanism: Auth method: 1
>((null):13444): Spice-Info **: reds.c:1421:reds_info_new_channel: channel 2:0, connected successfully, over Secure link
>((null):13444): Spice-Debug **: red_dispatcher.c:105:red_dispatcher_set_display_peer: 
>((null):13444): SpiceWorker-Info **: red_worker.c:11429:handle_dev_display_connect: connect
>((null):13444): SpiceWorker-Info **: red_worker.c:10599:handle_new_display_channel: add display channel client
>((null):13444): SpiceWorker-Info **: red_worker.c:10607:handle_new_display_channel: New display (client 0x7f358af7c970) dcc 0x7f3498243930 stream 0x7f358af68430
>((null):13444): SpiceWorker-Info **: red_worker.c:10634:handle_new_display_channel: jpeg enabled
>((null):13444): SpiceWorker-Info **: red_worker.c:10635:handle_new_display_channel: zlib-over-glz enabled
>((null):13444): SpiceWorker-Info **: red_worker.c:9635:display_channel_client_wait_for_init: creating encoder with id == 0
>
>Comment:
>Above the last line of the SPICE connection try
>Below cyclic ping/pongs between client and server. Without any picture visible
>
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 26.05(ms) 
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.70(ms)
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 25.63(ms)
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 24.76(ms)
>((null):13444): Spice-Debug **: red_channel.c:1520:red_channel_client_handle_pong: update roundtrip 23.92(ms)

Actions: View

Attachments on bug 1104697: 902192 | 902193 | 902418 | 902559 | 902561 | 907289 | 908784 | 908785