2017-03-09 06:52:38.287+0000: starting up libvirt version: 2.0.0, package: 10.el7_3.5 (Red Hat, Inc. , 2017-02-10-03:02:29, x86-020.build.eng.bos.redhat.com), qemu version: 2.6.0 (qemu-kvm-rhev-2.6.0-28.el7_3.6), hostname: hyper03.spice.brq.redhat.com LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=spice SPICE_DEBUG_LEVEL=9 /usr/libexec/qemu-kvm -name guest=toso-rhel7,debug-threads=on -S -object secret,id=masterKey0,format=raw,file=/var/lib/libvirt/qemu/domain-22-toso-rhel7/master-key.aes -machine pc-i440fx-rhel7.3.0,accel=kvm,usb=off -cpu Nehalem -m size=2097152k,slots=16,maxmem=8388608k -realtime mlock=off -smp 1,maxcpus=16,sockets=16,cores=1,threads=1 -numa node,nodeid=0,cpus=0,mem=2048 -uuid d3f66688-ba48-40c1-881a-81ed660ad397 -smbios 'type=1,manufacturer=Red Hat,product=RHEV Hypervisor,version=7.3-7.el7,serial=36343035-3733-5A43-4A30-323830343546,uuid=d3f66688-ba48-40c1-881a-81ed660ad397' -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/domain-22-toso-rhel7/monitor.sock,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2017-03-09T06:52:38,driftfix=slew -global kvm-pit.lost_tick_policy=discard -no-hpet -no-shutdown -boot strict=on -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x6 -device virtio-serial-pci,id=virtio-serial0,max_ports=16,bus=pci.0,addr=0x5 -drive if=none,id=drive-ide0-1-0,readonly=on -device ide-cd,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/00000001-0001-0001-0001-000000000311/325d0826-e9a1-470a-a5f5-3253e3e2f8c1/images/50918d2d-3af2-4bff-bac9-6312b1c1d065/bdf6803d-e152-4d46-99ac-8ed313cdcfbe,format=raw,if=none,id=drive-scsi0-0-0-0,serial=50918d2d-3af2-4bff-bac9-6312b1c1d065,cache=none,werror=stop,rerror=stop,aio=native -device scsi-hd,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=2 -netdev tap,fd=33,id=hostnet0,vhost=on,vhostfd=36 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:1a:41:80:00:0e,bus=pci.0,addr=0x3,bootindex=1 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/d3f66688-ba48-40c1-881a-81ed660ad397.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/d3f66688-ba48-40c1-881a-81ed660ad397.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=10.34.73.132,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=default,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 -device qxl-vga,id=video0,ram_size=268435456,vram_size=134217728,vram64_size_mb=0,vgamem_mb=64,bus=pci.0,addr=0x2 -device intel-hda,id=sound0,bus=pci.0,addr=0x4 -device hda-duplex,id=sound0-codec0,bus=sound0.0,cad=0 -incoming defer -object rng-random,id=objrng0,filename=/dev/urandom -device virtio-rng-pci,rng=objrng0,id=rng0,bus=pci.0,addr=0x7 -msg timestamp=on Domain id=22 is tainted: custom-argv ** (process:29148): WARNING **: Setting SPICE_DEBUG_LEVEL is deprecated, use G_MESSAGES_DEBUG instead (process:29148): Spice-DEBUG: reds.c:4238:spice_server_set_seamless_migration: seamless migration enabled=1 (process:29148): Spice-DEBUG: reds.c:3398:do_spice_init: starting 0.13.3.222-541d-dirty (process:29148): Spice-DEBUG: char-device.c:678:red_char_device_reset_dev_instance: sin (nil), char device 0x7f43181a4120 (process:29148): Spice-DEBUG: reds.c:2601:reds_init_socket: bound to 10.34.73.132:5900 (process:29148): Spice-DEBUG: reds.c:2824:reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem (process:29148): Spice-DEBUG: reds.c:2836:reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem (process:29148): Spice-DEBUG: reds.c:2845:reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem (process:29148): Spice-DEBUG: red-channel.c:195:red_channel_constructed: 0x7f431817a180: channel type 1 id 0 thread_id 0x7f43159ccc80 (process:29148): Spice-DEBUG: red-channel.c:195:red_channel_constructed: 0x7f431817a8b0: channel type 3 id 0 thread_id 0x7f43159ccc80 (process:29148): Spice-DEBUG: reds.c:3336:spice_server_add_interface: SPICE_INTERFACE_MIGRATION (process:29148): Spice-DEBUG: reds.c:3253:spice_server_add_interface: SPICE_INTERFACE_KEYBOARD (process:29148): Spice-DEBUG: reds.c:3263:spice_server_add_interface: SPICE_INTERFACE_MOUSE 2017-03-09T06:52:38.369062Z qemu-kvm: warning: CPU(s) not present in any NUMA nodes: 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 2017-03-09T06:52:38.369202Z qemu-kvm: warning: All CPU(s) up to maxcpus should be described in NUMA config (process:29148): Spice-DEBUG: reds.c:3275:spice_server_add_interface: SPICE_INTERFACE_QXL (process:29148): Spice-DEBUG: cursor-channel.c:297:cursor_channel_new: create cursor channel (process:29148): Spice-DEBUG: red-channel.c:195:red_channel_constructed: 0x7f431a0230a0: channel type 4 id 0 thread_id 0x7f43159ccc80 (process:29148): Spice-DEBUG: display-channel.c:1995:display_channel_new: create display channel (process:29148): Spice-DEBUG: red-channel.c:195:red_channel_constructed: 0x7f431817a250: channel type 2 id 0 thread_id 0x7f43159ccc80 (process:29148): Spice-DEBUG: display-channel.c:204:display_channel_set_stream_video: sv off (process:29148): Spice-DEBUG: reds.c:3310:spice_server_add_interface: SPICE_INTERFACE_PLAYBACK (process:29148): Spice-DEBUG: red-channel.c:195:red_channel_constructed: 0x7f431a023160: channel type 5 id 0 thread_id 0x7f43159ccc80 (process:29148): Spice-DEBUG: reds.c:3319:spice_server_add_interface: SPICE_INTERFACE_RECORD (process:29148): Spice-DEBUG: red-channel.c:195:red_channel_constructed: 0x7f431a023230: channel type 6 id 0 thread_id 0x7f43159ccc80 (process:29148): Spice-DEBUG: display-channel.c:1829:display_channel_destroy_surfaces: (process:29148): Spice-DEBUG: display-channel.c:149:monitors_config_debug: monitors config count:1 max:1 (process:29148): Spice-DEBUG: display-channel.c:153:monitors_config_debug: +0+0 640x480 (process:29148): Spice-DEBUG: cursor-channel.c:384:cursor_channel_init_client: during_target_migrate: skip init (process:29148): Spice-DEBUG: reds.c:2169:reds_handle_auth_mechanism: Auth method: 1 (process:29148): Spice-DEBUG: reds.c:1710:reds_handle_main_link: (process:29148): Spice-DEBUG: reds.c:582:reds_disconnect: (process:29148): Spice-DEBUG: reds.c:1581:reds_info_new_channel: channel 1:0, connected successfully, over Secure link main_channel_link: add main channel client (process:29148): Spice-DEBUG: red_channel_client_class_init (process:29148): Spice-DEBUG: reds.c:1748:reds_handle_main_link: NEW Client 0x7f4318176e00 mcc 0x7f431aad6a00 connect-id 1804289383 (process:29148): Spice-DEBUG: reds.c:1600:reds_mig_target_client_add: (process:29148): Spice-DEBUG: red-channel-client.c:808:red_channel_client_start_connectivity_monitoring: (process:29148): Spice-DEBUG: red-channel-client.c:1905:red_channel_client_set_migration_seamless: channel type 1 id 0 rcc 0x7f431aad6a00 wait data 1 (process:29148): Spice-DEBUG: reds.c:1833:reds_link_mig_target_channels: 0x7f4318176e00 (process:29148): Spice-DEBUG: reds.c:2169:reds_handle_auth_mechanism: Auth method: 1 (process:29148): Spice-DEBUG: reds.c:1581:reds_info_new_channel: channel 3:0, connected successfully, over Secure link inputs_connect: inputs channel client create (process:29148): Spice-DEBUG: red-channel-client.c:1905:red_channel_client_set_migration_seamless: channel type 3 id 0 rcc 0x7f431bac69d0 wait data 1 (process:29148): Spice-DEBUG: reds.c:2169:reds_handle_auth_mechanism: Auth method: 1 (process:29148): Spice-DEBUG: reds.c:1581:reds_info_new_channel: channel 2:0, connected successfully, over Secure link (process:29148): Spice-DEBUG: red-qxl.c:84:red_qxl_set_display_peer: (process:29148): Spice-DEBUG: reds.c:2169:reds_handle_auth_mechanism: Auth method: 1 (process:29148): Spice-DEBUG: red-channel-client.c:1905:red_channel_client_set_migration_seamless: channel type 2 id 0 rcc 0x7f431bacc5c0 wait data 1 (process:29148): Spice-DEBUG: dcc.c:514:dcc_new: New display (client 0x7f4318176e00) dcc 0x7f431bacc5c0 stream 0x7f431815a760 (process:29148): Spice-DEBUG: display-channel.c:2134:display_channel_update_compression: jpeg disabled (process:29148): Spice-DEBUG: display-channel.c:2135:display_channel_update_compression: zlib-over-glz disabled (process:29148): Spice-DEBUG: reds.c:1581:reds_info_new_channel: channel 4:0, connected successfully, over Secure link red_qxl_set_cursor_peer: (process:29148): Spice-DEBUG: cursor-channel.c:415:cursor_channel_connect: add cursor channel client (process:29148): Spice-DEBUG: red-channel-client.c:1905:red_channel_client_set_migration_seamless: channel type 4 id 0 rcc 0x7f431bad1de0 wait data 0 (process:29148): Spice-DEBUG: cursor-channel.c:384:cursor_channel_init_client: during_target_migrate: skip init (process:29148): Spice-DEBUG: reds.c:2169:reds_handle_auth_mechanism: Auth method: 1 (process:29148): Spice-DEBUG: reds.c:1581:reds_info_new_channel: channel 5:0, connected successfully, over Secure link (process:29148): Spice-DEBUG: red-channel-client.c:1905:red_channel_client_set_migration_seamless: channel type 5 id 0 rcc 0x7f431babb1c0 wait data 0 (process:29148): Spice-DEBUG: reds.c:2169:reds_handle_auth_mechanism: Auth method: 1 (process:29148): Spice-DEBUG: reds.c:1581:reds_info_new_channel: channel 6:0, connected successfully, over Secure link (process:29148): Spice-DEBUG: red-channel-client.c:1905:red_channel_client_set_migration_seamless: channel type 6 id 0 rcc 0x7f431b79c1c0 wait data 0 (process:29148): Spice-DEBUG: reds.c:3295:spice_server_add_interface: SPICE_INTERFACE_TABLET (process:29148): Spice-DEBUG: display-channel.c:1829:display_channel_destroy_surfaces: (process:29148): Spice-DEBUG: display-channel.c:141:monitors_config_unref: freeing monitors config (process:29148): Spice-DEBUG: display-channel.c:149:monitors_config_debug: monitors config count:1 max:1 (process:29148): Spice-DEBUG: display-channel.c:153:monitors_config_debug: +0+0 640x480 (process:29148): Spice-DEBUG: cursor-channel.c:384:cursor_channel_init_client: during_target_migrate: skip init (process:29148): Spice-DEBUG: display-channel.c:141:monitors_config_unref: freeing monitors config (process:29148): Spice-DEBUG: display-channel.c:149:monitors_config_debug: monitors config count:1 max:1 (process:29148): Spice-DEBUG: display-channel.c:153:monitors_config_debug: +0+0 1024x768 (process:29148): Spice-DEBUG: cursor-channel.c:384:cursor_channel_init_client: during_target_migrate: skip init red_qxl_loadvm_commands: (process:29148): Spice-DEBUG: red-qxl.c:231:async_command_alloc: 0x7f431aac8790 (process:29148): Spice-DEBUG: display-channel.c:141:monitors_config_unref: freeing monitors config (process:29148): Spice-DEBUG: display-channel.c:149:monitors_config_debug: monitors config count:1 max:4 (process:29148): Spice-DEBUG: display-channel.c:153:monitors_config_debug: +0+0 1024x768 (process:29148): Spice-DEBUG: red-qxl.c:922:red_qxl_async_complete: 0x7f431aac8790: cookie 139926159362976 2017-03-09T06:52:59.691229Z qemu-kvm: warning: TSC frequency mismatch between VM (2000070 kHz) and host (3092973 kHz), and TSC scaling unavailable (process:29148): Spice-DEBUG: reds.c:3170:spice_server_char_device_add_interface: CHAR_DEVICE vdagent (process:29148): Spice-DEBUG: char-device.c:678:red_char_device_reset_dev_instance: sin 0x7f43180bf6d8, char device 0x7f43181a4120 (process:29148): Spice-DEBUG: char-device.c:744:red_char_device_client_add: char device 0x7f43181a4120, client 0x7f4318176e00 (process:29148): Spice-DEBUG: reds.c:3110:attach_to_red_agent: waiting for migration data (process:29148): Spice-DEBUG: red-channel-client.c:1429:red_channel_client_handle_migrate_data: channel type 3 id 0 rcc 0x7f431bac69d0 size 10 (process:29148): Spice-DEBUG: red-channel-client.c:1429:red_channel_client_handle_migrate_data: channel type 1 id 0 rcc 0x7f431aad6a00 size 65 (process:29148): Spice-DEBUG: reds.c:1388:reds_handle_migrate_data: main-channel: got migrate data (process:29148): Spice-DEBUG: reds.c:1410:reds_handle_migrate_data: restoring state from mig_data (process:29148): Spice-DEBUG: reds.c:1370:reds_agent_state_restore: to agent filter: discard all 0, wait_msg 0, msg_filter_result 0 (process:29148): Spice-DEBUG: reds.c:1374:reds_agent_state_restore: from agent filter: discard all 0, wait_msg 0, msg_filter_result 0 (process:29148): Spice-DEBUG: red-channel-client.c:1395:red_channel_client_handle_pong: update roundtrip 20849.62(ms) (process:29148): Spice-DEBUG: red-channel-client.c:1429:red_channel_client_handle_migrate_data: channel type 2 id 0 rcc 0x7f431bacc5c0 size 88 (process:29148): Spice-DEBUG: dcc.c:1277:restore_surfaces_lossless: (process:29148): Spice-DEBUG: reds.c:1881:reds_on_client_seamless_migrate_complete: (process:29148): Spice-DEBUG: char-device.c:790:red_char_device_start: char device 0x7f43181a4120 (process:29148): Spice-DEBUG: red-channel-client.c:1395:red_channel_client_handle_pong: update roundtrip 1.27(ms) (process:29148): Spice-DEBUG: red-channel-client.c:1395:red_channel_client_handle_pong: update roundtrip 1.03(ms) (process:29148): Spice-DEBUG: red-channel-client.c:1395:red_channel_client_handle_pong: update roundtrip 1.07(ms) red_channel_client_disconnect: rcc=0x7f431bac69d0 (channel=0x7f431817a8b0 type=3 id=0) red_channel_client_disconnect: rcc=0x7f431bad1de0 (channel=0x7f431a0230a0 type=4 id=0) red_channel_client_disconnect: rcc=0x7f431bacc5c0 (channel=0x7f431817a250 type=2 id=0) (process:29148): Spice-DEBUG: display-channel.c:1938:on_disconnect: (process:29148): Spice-DEBUG: display-channel.c:1950:on_disconnect: #draw=32, #glz_draw=0 red_channel_client_disconnect: rcc=0x7f431b79c1c0 (channel=0x7f431a023230 type=6 id=0) red_channel_client_disconnect: rcc=0x7f431babb1c0 (channel=0x7f431a023160 type=5 id=0) red_channel_client_disconnect: rcc=0x7f431aad6a00 (channel=0x7f431817a180 type=1 id=0) main_channel_client_on_disconnect: rcc=0x7f431aad6a00 (process:29148): Spice-DEBUG: main-dispatcher.c:272:main_dispatcher_client_disconnect: client 0x7f4318176e00 (process:29148): Spice-DEBUG: main-dispatcher.c:232:main_dispatcher_handle_client_disconnect: client=0x7f4318176e00 (process:29148): Spice-DEBUG: reds.c:506:reds_client_disconnect: (process:29148): Spice-DEBUG: char-device.c:761:red_char_device_client_remove: char device 0x7f43181a4120, client 0x7f4318176e00 (process:29148): Spice-DEBUG: char-device.c:185:red_char_device_client_free: write_queue_is_empty 1 (process:29148): Spice-DEBUG: char-device.c:776:red_char_device_client_remove: client removed, memory pool will be freed (41 bytes) red_client_destroy: destroy client 0x7f4318176e00 with #channels=6 (process:29148): Spice-DEBUG: reds-stream.c:345:reds_stream_free: close socket fd 37 (process:29148): Spice-DEBUG: reds-stream.c:345:reds_stream_free: close socket fd 32 red_qxl_disconnect_cursor_peer: (process:29148): Spice-DEBUG: reds-stream.c:345:reds_stream_free: close socket fd 34 red_qxl_disconnect_display_peer: (process:29148): Spice-DEBUG: reds-stream.c:345:reds_stream_free: close socket fd 35 (process:29148): Spice-DEBUG: reds-stream.c:345:reds_stream_free: close socket fd 31 (process:29148): Spice-DEBUG: reds-stream.c:345:reds_stream_free: close socket fd 29 (process:29148): Spice-DEBUG: red-client.c:111:red_client_finalize: release client=0x7f4318176e00 2017-03-09T06:53:38.058184Z qemu-kvm: terminating on signal 15 from pid 1978 (process:29148): Spice-DEBUG: reds.c:3370:spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK (process:29148): Spice-DEBUG: reds.c:3373:spice_server_remove_interface: remove SPICE_INTERFACE_RECORD (process:29148): Spice-DEBUG: reds.c:3223:spice_server_char_device_remove_interface: remove CHAR_DEVICE vdagent (process:29148): Spice-DEBUG: char-device.c:800:red_char_device_stop: char device 0x7f43181a4120 (process:29148): Spice-DEBUG: char-device.c:815:red_char_device_reset: char device 0x7f43181a4120 (process:29148): Spice-DEBUG: char-device.c:678:red_char_device_reset_dev_instance: sin (nil), char device 0x7f43181a4120 (process:29148): Spice-DEBUG: char-device.c:678:red_char_device_reset_dev_instance: sin (nil), char device 0x7f43181a4120 2017-03-09 06:53:38.258+0000: shutting down