Attachment 887384 Details for Bug 1088155 – debug.log

debug.log

virt.qemu.repeat1.smp_8.16384m.Host_RHEL.6.5.spice.qcow2.virtio_blk.up.virtio_net.RHEL.6.5.x86_64.migrate.exec.gzip_exec.DEBUG (text/plain), 65.51 KB, created by xhan on 2014-04-18 01:37:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: xhan

Created: 2014-04-18 01:37:38 UTC

Size: 65.51 KB

patch

obsolete

>04/17 09:50:54 DEBUG|      test:0285| Test started. Specified 2 iterations
>04/17 09:50:54 DEBUG|      test:0289| Executing iteration 1 of 2
>04/17 09:50:54 DEBUG|      test:0310| Profilers present. Profiling run started
>04/17 09:50:54 DEBUG|     utils:0109| Running 'git describe --abbrev=4'
>04/17 09:50:54 DEBUG|     utils:0109| Running 'git rev-parse --abbrev-ref HEAD'
>04/17 09:50:54 DEBUG|     utils:0109| Running 'git show --summary --pretty='%H' | head -1'
>04/17 09:50:54 INFO |      virt:0057| Virt Test 'unknown', Branch 'master', SHA1 '7ce84efd9ece5d249ab51119976af3d58c8b9b7d'
>04/17 09:50:54 DEBUG|      virt:0059| Test parameters:
>04/17 09:50:54 DEBUG|      virt:0063|     _name_map_file = {'tests-example.cfg': 'repeat1.smp_8.16384m.acceptance_test_functional.migration', 'subtests.cfg': '(subtest=type_specific).(subtest=migrate).default.exec.gzip_exec', 'guest-hw.cfg': 'no_lvm_support.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net', 'guest-os-internal.cfg': 'Linux.RHEL.6.5.x86_64', 'host-kernel-internal.cfg': 'Host_RHEL.6.5', 'guest-hw-internal.cfg': 'spice.local_stg'}
>04/17 09:50:54 DEBUG|      virt:0063|     _short_name_map_file = {'tests-example.cfg': 'repeat1.smp_8.16384m.acceptance_test_functional.migration', 'subtests.cfg': 'type_specific.migrate.default.exec.gzip_exec', 'guest-hw.cfg': 'no_lvm_support.filesystem.no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net', 'guest-os-internal.cfg': 'Linux.RHEL.6.5.x86_64', 'host-kernel-internal.cfg': 'Host_RHEL.6.5', 'guest-hw-internal.cfg': 'spice.local_stg'}
>04/17 09:50:54 DEBUG|      virt:0063|     address_range_base_mac_r1 = 00:11:22:33:AB:08
>04/17 09:50:54 DEBUG|      virt:0063|     address_ranges = r1
>04/17 09:50:54 DEBUG|      virt:0063|     always_use_tcpdump = yes
>04/17 09:50:54 DEBUG|      virt:0063|     auto_cpu_model = yes
>04/17 09:50:54 DEBUG|      virt:0063|     backup_dir = images/
>04/17 09:50:54 DEBUG|      virt:0063|     backup_image = no
>04/17 09:50:54 DEBUG|      virt:0063|     backup_image_on_check_error = no
>04/17 09:50:54 DEBUG|      virt:0063|     boot_menu = off
>04/17 09:50:54 DEBUG|      virt:0063|     boot_once = c
>04/17 09:50:54 DEBUG|      virt:0063|     boot_order = cdn
>04/17 09:50:54 DEBUG|      virt:0063|     brew_tag = RHEL-6.5-Z-candidate
>04/17 09:50:54 DEBUG|      virt:0063|     cd_format = ide
>04/17 09:50:54 DEBUG|      virt:0063|     cdrom_check_cdrom_pattern = /dev/cdrom-\w+|/dev/cdrom\d*
>04/17 09:50:54 DEBUG|      virt:0063|     cdrom_get_cdrom_cmd = ls /dev/cdrom*
>04/17 09:50:54 DEBUG|      virt:0063|     cdrom_test_cmd = dd if=%s of=/dev/null bs=1 count=1
>04/17 09:50:54 DEBUG|      virt:0063|     cdrom_virtio = isos/windows/virtio-win-latest-signed.iso
>04/17 09:50:54 DEBUG|      virt:0063|     cdroms = cd1
>04/17 09:50:54 DEBUG|      virt:0063|     check_image = yes
>04/17 09:50:54 DEBUG|      virt:0063|     check_img = no
>04/17 09:50:54 DEBUG|      virt:0063|     convert_ppm_files_to_png = yes
>04/17 09:50:54 DEBUG|      virt:0063|     convert_ppm_files_to_png_on_error = yes
>04/17 09:50:54 DEBUG|      virt:0063|     cpu_chk_cmd = ls /sys/devices/system/cpu | egrep -c "cpu[0-9]+$"
>04/17 09:50:54 DEBUG|      virt:0063|     cpu_model = qemu64
>04/17 09:50:54 DEBUG|      virt:0063|     cpu_model_flags = 
>04/17 09:50:54 DEBUG|      virt:0063|     dd_timeout = 900
>04/17 09:50:54 DEBUG|      virt:0063|     dep = ['repeat1.smp_8.16384m.acceptance_test_functional.migration.Host_RHEL.6.5.spice.local_stg.no_lvm_support.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net.Linux.RHEL.6.5.x86_64.(subtest=type_specific).install', 'repeat1.smp_8.16384m.acceptance_test_functional.migration.Host_RHEL.6.5.spice.local_stg.no_lvm_support.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net.Linux.RHEL.6.5.x86_64.(subtest=type_specific).setup', 'repeat1.smp_8.16384m.acceptance_test_functional.migration.Host_RHEL.6.5.spice.local_stg.no_lvm_support.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net.Linux.RHEL.6.5.x86_64.(subtest=type_specific).image_copy', 'repeat1.smp_8.16384m.acceptance_test_functional.migration.Host_RHEL.6.5.spice.local_stg.no_lvm_support.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net.Linux.RHEL.6.5.x86_64.(subtest=type_specific).unattended_install.cdrom']
>04/17 09:50:54 DEBUG|      virt:0063|     display = spice
>04/17 09:50:54 DEBUG|      virt:0063|     drive_cache = none
>04/17 09:50:54 DEBUG|      virt:0063|     drive_format = virtio
>04/17 09:50:54 DEBUG|      virt:0063|     drive_index_cd1 = 1
>04/17 09:50:54 DEBUG|      virt:0063|     drive_index_image1 = 0
>04/17 09:50:54 DEBUG|      virt:0063|     drive_rerror_image1 = 
>04/17 09:50:54 DEBUG|      virt:0063|     drive_werror_image1 = 
>04/17 09:50:54 DEBUG|      virt:0063|     dst_dir = /mnt/linux
>04/17 09:50:54 DEBUG|      virt:0063|     encode_video_files = yes
>04/17 09:50:54 DEBUG|      virt:0063|     extra_params =  -no-kvm-pit-reinjection
>04/17 09:50:54 DEBUG|      virt:0063|     file_transfer_client = scp
>04/17 09:50:54 DEBUG|      virt:0063|     file_transfer_port = 22
>04/17 09:50:54 DEBUG|      virt:0063|     get_time_cmd = date +'TIME: %a %m/%d/%Y %H:%M:%S.%N'
>04/17 09:50:54 DEBUG|      virt:0063|     guest_name = RHEL-Server-6.5-64
>04/17 09:50:54 DEBUG|      virt:0063|     guest_port_remote_shell = 22
>04/17 09:50:54 DEBUG|      virt:0063|     host_kernel_ver_str = Host_RHEL.6.5
>04/17 09:50:54 DEBUG|      virt:0063|     host_setup_flag = 0
>04/17 09:50:54 DEBUG|      virt:0063|     image_aio = native
>04/17 09:50:54 DEBUG|      virt:0063|     image_backend = filesystem
>04/17 09:50:54 DEBUG|      virt:0063|     image_boot = yes
>04/17 09:50:54 DEBUG|      virt:0063|     image_chain = 
>04/17 09:50:54 DEBUG|      virt:0063|     image_clone_command = cp --reflink=auto %s %s
>04/17 09:50:54 DEBUG|      virt:0063|     image_format = qcow2
>04/17 09:50:54 DEBUG|      virt:0063|     image_name = images/RHEL-Server-6.5-64-virtio
>04/17 09:50:54 DEBUG|      virt:0063|     image_raw_device = no
>04/17 09:50:54 DEBUG|      virt:0063|     image_remove_command = rm -rf %s
>04/17 09:50:54 DEBUG|      virt:0063|     image_size = 20G
>04/17 09:50:54 DEBUG|      virt:0063|     image_snapshot = no
>04/17 09:50:54 DEBUG|      virt:0063|     image_unbootable_pattern = Hard Disk.*not a bootable disk
>04/17 09:50:54 DEBUG|      virt:0063|     image_verify_bootable = yes
>04/17 09:50:54 DEBUG|      virt:0063|     images = image1
>04/17 09:50:54 DEBUG|      virt:0063|     images_good = 10.66.90.128:/vol/S2/kvmauto/linux_img
>04/17 09:50:54 DEBUG|      virt:0063|     important_key = guest_name,platform,smp,mem,drive_format,nic_model,type,ksm,image_format,kvm_version,kvm_userspace_version,tag,medium,installation
>04/17 09:50:54 DEBUG|      virt:0063|     inactivity_treshold = 1800
>04/17 09:50:54 DEBUG|      virt:0063|     inactivity_watcher = logimage_remove_commnad = 'rm -rf %s'
>04/17 09:50:54 DEBUG|      virt:0063|     index_enable = yes
>04/17 09:50:54 DEBUG|      virt:0063|     indirect_image_blacklist = /dev/hda[\d]* /dev/sda[\d]* /dev/sg0 /dev/md0
>04/17 09:50:54 DEBUG|      virt:0063|     init_pci_addr = 4
>04/17 09:50:54 DEBUG|      virt:0063|     isa_serials = serial0
>04/17 09:50:54 DEBUG|      virt:0063|     iterations = 2
>04/17 09:50:54 DEBUG|      virt:0063|     job_timeout = 0
>04/17 09:50:54 DEBUG|      virt:0063|     keep_ppm_files = no
>04/17 09:50:54 DEBUG|      virt:0063|     keep_ppm_files_on_error = no
>04/17 09:50:54 DEBUG|      virt:0063|     keep_screendumps = yes
>04/17 09:50:54 DEBUG|      virt:0063|     keep_screendumps_on_error = yes
>04/17 09:50:54 DEBUG|      virt:0063|     keep_video_files = yes
>04/17 09:50:54 DEBUG|      virt:0063|     keep_video_files_on_error = yes
>04/17 09:50:54 DEBUG|      virt:0063|     kill_timeout = 60
>04/17 09:50:54 DEBUG|      virt:0063|     kill_unresponsive_vms = yes
>04/17 09:50:54 DEBUG|      virt:0063|     kill_vm = no
>04/17 09:50:54 DEBUG|      virt:0063|     kill_vm_before_test = no
>04/17 09:50:54 DEBUG|      virt:0063|     kill_vm_gracefully = yes
>04/17 09:50:54 DEBUG|      virt:0063|     kill_vm_in_paused = yes
>04/17 09:50:54 DEBUG|      virt:0063|     kill_vm_on_error = yes
>04/17 09:50:54 DEBUG|      virt:0063|     kill_vm_only_when_paused = yes
>04/17 09:50:54 DEBUG|      virt:0063|     ksm = 0
>04/17 09:50:54 DEBUG|      virt:0063|     kvm_default = {'/sys/module/kvm/parameters/ignore_msrs': 'N', '/sys/module/kvm/parameters/min_timer_period_us': '500', '/sys/module/kvm/parameters/oos_shadow': 'Y', '/sys/module/kvm/parameters/allow_unsafe_assigned_interrupts': 'N'}
>04/17 09:50:54 DEBUG|      virt:0063|     kvm_userspace_ver_cmd = grep -q el5 /proc/version && rpm -qa | grep ^kvm-83-.* || rpm -qa | grep qemu-kvm-[0-9].*-.* | head -n 1
>04/17 09:50:54 DEBUG|      virt:0063|     kvm_ver_cmd = grep -q el5 /proc/version && cat /sys/module/kvm/version || uname -r
>04/17 09:50:54 DEBUG|      virt:0063|     login_timeout = 360
>04/17 09:50:54 DEBUG|      virt:0063|     mac_ip_filter = HWaddr (.\w+:\w+:\w+:\w+:\w+:\w+)\s+?inet addr:(.\d+\.\d+\.\d+\.\d+)
>04/17 09:50:54 DEBUG|      virt:0063|     machine_type = rhel6.5.0
>04/17 09:50:54 DEBUG|      virt:0063|     main_monitor = qmpmonitor1
>04/17 09:50:54 DEBUG|      virt:0063|     main_vm = virt-tests-vm1
>04/17 09:50:54 DEBUG|      virt:0063|     malloc_perturb = no
>04/17 09:50:54 DEBUG|      virt:0063|     max_vms = 2
>04/17 09:50:54 DEBUG|      virt:0063|     mem = 16384
>04/17 09:50:54 DEBUG|      virt:0063|     mem_chk_cmd = dmidecode -t 17 |awk -F: '/Size/ {print $2}'
>04/17 09:50:54 DEBUG|      virt:0063|     mem_chk_cur_cmd = grep MemTotal /proc/meminfo
>04/17 09:50:54 DEBUG|      virt:0063|     mem_chk_re_str = ([0-9]+)
>04/17 09:50:54 DEBUG|      virt:0063|     mig_timeout = 3600
>04/17 09:50:54 DEBUG|      virt:0063|     migration_bg_check_command = pgrep tcpdump
>04/17 09:50:54 DEBUG|      virt:0063|     migration_bg_command = cd /tmp; nohup tcpdump -q -i any -t ip host localhost
>04/17 09:50:54 DEBUG|      virt:0063|     migration_bg_kill_command = pkill tcpdump
>04/17 09:50:54 DEBUG|      virt:0063|     migration_exec_cmd_dst = gzip -c -d %s
>04/17 09:50:54 DEBUG|      virt:0063|     migration_exec_cmd_src = gzip -c > %s
>04/17 09:50:54 DEBUG|      virt:0063|     migration_protocol = exec
>04/17 09:50:54 DEBUG|      virt:0063|     migration_test_command = uname -r
>04/17 09:50:54 DEBUG|      virt:0063|     monitor_type = qmp
>04/17 09:50:54 DEBUG|      virt:0063|     monitor_type_qmpmonitor1 = qmp
>04/17 09:50:54 DEBUG|      virt:0063|     monitors = qmpmonitor1
>04/17 09:50:54 DEBUG|      virt:0063|     name = repeat1.smp_8.16384m.acceptance_test_functional.migration.Host_RHEL.6.5.spice.local_stg.no_lvm_support.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_blk.up.virtio_net.Linux.RHEL.6.5.x86_64.(subtest=type_specific).(subtest=migrate).default.exec.gzip_exec
>04/17 09:50:54 DEBUG|      virt:0063|     netdev_extra_params = 
>04/17 09:50:54 DEBUG|      virt:0063|     netdev_peer_re = \s{2,}(.*?):.*?peer=(.*?)\s
>04/17 09:50:54 DEBUG|      virt:0063|     netdst = switch
>04/17 09:50:54 DEBUG|      virt:0063|     nettype = bridge
>04/17 09:50:54 DEBUG|      virt:0063|     network_query = ifconfig
>04/17 09:50:54 DEBUG|      virt:0063|     nfs_iso = 10.66.90.128:/vol/S2/kvmauto/iso
>04/17 09:50:54 DEBUG|      virt:0063|     nic_mode = tap
>04/17 09:50:54 DEBUG|      virt:0063|     nic_model = virtio
>04/17 09:50:54 DEBUG|      virt:0063|     nics = nic1
>04/17 09:50:54 DEBUG|      virt:0063|     os_type = linux
>04/17 09:50:54 DEBUG|      virt:0063|     package_check_cmd_kernel = uname -r
>04/17 09:50:54 DEBUG|      virt:0063|     package_version_pattern_kernel = [\.\-\_\w\d]+
>04/17 09:50:54 DEBUG|      virt:0063|     password = redhat
>04/17 09:50:54 DEBUG|      virt:0063|     paused_after_start_vm = no
>04/17 09:50:54 DEBUG|      virt:0063|     pci_assignable = no
>04/17 09:50:54 DEBUG|      virt:0063|     ping_pong = 1
>04/17 09:50:54 DEBUG|      virt:0063|     platform = 64
>04/17 09:50:54 DEBUG|      virt:0063|     post_command_noncritical = yes
>04/17 09:50:54 DEBUG|      virt:0063|     post_command_timeout = 600
>04/17 09:50:54 DEBUG|      virt:0063|     pre_check_cmd = grep 'Red Hat Enterprise Linux' /etc/redhat-release
>04/17 09:50:54 DEBUG|      virt:0063|     profilers = kvm_stat 
>04/17 09:50:54 DEBUG|      virt:0063|     qemu_binary = qemu
>04/17 09:50:54 DEBUG|      virt:0063|     qemu_img_binary = qemu-img
>04/17 09:50:54 DEBUG|      virt:0063|     qemu_io_binary = qemu-io
>04/17 09:50:54 DEBUG|      virt:0063|     qemu_sandbox = off
>04/17 09:50:54 DEBUG|      virt:0063|     qxl = on
>04/17 09:50:54 DEBUG|      virt:0063|     qxl_dev_memory = 33554432
>04/17 09:50:54 DEBUG|      virt:0063|     qxl_dev_nr = 1
>04/17 09:50:54 DEBUG|      virt:0063|     reboot_command = shutdown -r now
>04/17 09:50:54 DEBUG|      virt:0063|     redirs = 
>04/17 09:50:54 DEBUG|      virt:0063|     remove_image = no
>04/17 09:50:54 DEBUG|      virt:0063|     requires_kernel = [">= 2.6.32-359", "<  2.6.33-432"]
>04/17 09:50:54 DEBUG|      virt:0063|     restore_image = no
>04/17 09:50:54 DEBUG|      virt:0063|     restore_image_on_check_error = no
>04/17 09:50:54 DEBUG|      virt:0063|     rtc_drift = slew
>04/17 09:50:54 DEBUG|      virt:0063|     run_tcpdump = yes
>04/17 09:50:54 DEBUG|      virt:0063|     screendump_delay = 5
>04/17 09:50:54 DEBUG|      virt:0063|     screendump_quality = 30
>04/17 09:50:54 DEBUG|      virt:0063|     screendump_temp_dir = /dev/shm
>04/17 09:50:54 DEBUG|      virt:0063|     screendump_verbose = no
>04/17 09:50:54 DEBUG|      virt:0063|     shell_client = ssh
>04/17 09:50:54 DEBUG|      virt:0063|     shell_port = 22
>04/17 09:50:54 DEBUG|      virt:0063|     shell_prompt = ^\[.*\][\#\$]\s*$
>04/17 09:50:54 DEBUG|      virt:0063|     shortname = repeat1.smp_8.16384m.Host_RHEL.6.5.spice.qcow2.virtio_blk.up.virtio_net.RHEL.6.5.x86_64.migrate.exec.gzip_exec
>04/17 09:50:54 DEBUG|      virt:0063|     shutdown_command = shutdown -h now
>04/17 09:50:54 DEBUG|      virt:0063|     skip_image_processing = no
>04/17 09:50:54 DEBUG|      virt:0063|     smp = 8
>04/17 09:50:54 DEBUG|      virt:0063|     snapshot_name = vm
>04/17 09:50:54 DEBUG|      virt:0063|     soundcards = hda-duplex
>04/17 09:50:54 DEBUG|      virt:0063|     spice = disable-ticketing
>04/17 09:50:54 DEBUG|      virt:0063|     spice_addr = 0
>04/17 09:50:54 DEBUG|      virt:0063|     spice_agent_mouse = on
>04/17 09:50:54 DEBUG|      virt:0063|     spice_gen_x509 = yes
>04/17 09:50:54 DEBUG|      virt:0063|     spice_image_compression = auto_glz
>04/17 09:50:54 DEBUG|      virt:0063|     spice_ipv4 = yes
>04/17 09:50:54 DEBUG|      virt:0063|     spice_ipv6 = no
>04/17 09:50:54 DEBUG|      virt:0063|     spice_password = 123456
>04/17 09:50:54 DEBUG|      virt:0063|     spice_playback_compression = on
>04/17 09:50:54 DEBUG|      virt:0063|     spice_port = generate
>04/17 09:50:54 DEBUG|      virt:0063|     spice_secure_channels = main, inputs
>04/17 09:50:54 DEBUG|      virt:0063|     spice_ssl = yes
>04/17 09:50:54 DEBUG|      virt:0063|     spice_streaming_video = all
>04/17 09:50:54 DEBUG|      virt:0063|     spice_tls_ciphers = DEFAULT
>04/17 09:50:54 DEBUG|      virt:0063|     spice_tls_port = generate
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_cacert_file = ca-cert.pem
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_cacert_subj = /C=CZ/L=BRNO/O=SPICE/CN=my CA
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_cert_file = server-cert.pem
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_dir = yes
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_key_file = server-key.pem
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_key_password = testPassPhrase
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_prefix = /tmp/spice_x509d
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_secure = no
>04/17 09:50:54 DEBUG|      virt:0063|     spice_x509_server_subj = /C=CZ/L=BRNO/O=SPICE/CN=my Server
>04/17 09:50:54 DEBUG|      virt:0063|     spice_zlib_glz_wan_compression = auto
>04/17 09:50:54 DEBUG|      virt:0063|     srchost = 10.66.85.230
>04/17 09:50:54 DEBUG|      virt:0063|     start_vm = yes
>04/17 09:50:54 DEBUG|      virt:0063|     status_test_command = echo $?
>04/17 09:50:54 DEBUG|      virt:0063|     strict_mode = no
>04/17 09:50:54 DEBUG|      virt:0063|     subtest = migrate
>04/17 09:50:54 DEBUG|      virt:0063|     sysfs_dir = /sys
>04/17 09:50:54 DEBUG|      virt:0063|     take_regular_screendumps = yes
>04/17 09:50:54 DEBUG|      virt:0063|     test_timeout = 14400
>04/17 09:50:54 DEBUG|      virt:0063|     try_serial_login = yes
>04/17 09:50:54 DEBUG|      virt:0063|     type = migration
>04/17 09:50:54 DEBUG|      virt:0063|     url_auto_ip = 192.168.122.1
>04/17 09:50:54 DEBUG|      virt:0063|     usb_controller_tablet1 = uhci
>04/17 09:50:54 DEBUG|      virt:0063|     usb_devices = tablet1
>04/17 09:50:54 DEBUG|      virt:0063|     usb_max_port = 2
>04/17 09:50:54 DEBUG|      virt:0063|     usb_type = ich9-usb-uhci1
>04/17 09:50:54 DEBUG|      virt:0063|     usb_type_tablet1 = usb-tablet
>04/17 09:50:54 DEBUG|      virt:0063|     usb_type_usb1 = ich9-usb-uhci1
>04/17 09:50:54 DEBUG|      virt:0063|     usbs = usb1
>04/17 09:50:54 DEBUG|      virt:0063|     use_autostart = no
>04/17 09:50:54 DEBUG|      virt:0063|     use_check_cpu = yes
>04/17 09:50:54 DEBUG|      virt:0063|     use_debug = yes
>04/17 09:50:54 DEBUG|      virt:0063|     use_nic_scritps = no
>04/17 09:50:54 DEBUG|      virt:0063|     use_no_reboot = no
>04/17 09:50:54 DEBUG|      virt:0063|     use_os_type = yes
>04/17 09:50:54 DEBUG|      virt:0063|     use_os_variant = no
>04/17 09:50:54 DEBUG|      virt:0063|     use_storage = local_stg
>04/17 09:50:54 DEBUG|      virt:0063|     use_virt_install_wait = no
>04/17 09:50:54 DEBUG|      virt:0063|     used_cpus = 
>04/17 09:50:54 DEBUG|      virt:0063|     used_mem = 1024
>04/17 09:50:54 DEBUG|      virt:0063|     username = root
>04/17 09:50:54 DEBUG|      virt:0063|     vcpu_cores = 0
>04/17 09:50:54 DEBUG|      virt:0063|     vcpu_sockets = 2
>04/17 09:50:54 DEBUG|      virt:0063|     vcpu_thread_pattern = u'thread_id':\s+(\d+)
>04/17 09:50:54 DEBUG|      virt:0063|     vcpu_threads = 1
>04/17 09:50:54 DEBUG|      virt:0063|     vfd_size = 1440k
>04/17 09:50:54 DEBUG|      virt:0063|     vga = qxl
>04/17 09:50:54 DEBUG|      virt:0063|     vhost = vhost=on
>04/17 09:50:54 DEBUG|      virt:0063|     virt_install_wait_time = 300
>04/17 09:50:54 DEBUG|      virt:0063|     virt_test_type = qemu
>04/17 09:50:54 DEBUG|      virt:0063|     virtio-blk-pci_scsi = 
>04/17 09:50:54 DEBUG|      virt:0063|     virtio_floppy = isos/windows/virtio-win-latest-signed.vfd.x86_64
>04/17 09:50:54 DEBUG|      virt:0063|     virtio_port_type = serialport
>04/17 09:50:54 DEBUG|      virt:0063|     virtio_ports = vs
>04/17 09:50:54 DEBUG|      virt:0063|     vm_type = qemu
>04/17 09:50:54 DEBUG|      virt:0063|     vms = virt-tests-vm1
>04/17 09:50:54 DEBUG|      virt:0063|     vnc_autoport = yes
>04/17 09:50:54 DEBUG|      virt:0063|     workaround_qemu_qmp_crash = always
>04/17 09:50:54 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'qmp_capabilities' 
>04/17 09:50:54 DEBUG|qemu_monit:1352| Send command: {'execute': 'qmp_capabilities', 'id': 'OllUEljt'}
>04/17 09:50:54 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'help' (via Human Monitor)
>04/17 09:50:54 DEBUG|     utils:0109| Running 'grep -q el5 /proc/version && cat /sys/module/kvm/version || uname -r'
>04/17 09:50:54 DEBUG|env_proces:0520| KVM version: 2.6.32-457.el6.x86_64
>04/17 09:50:54 DEBUG|     utils:0109| Running 'grep -q el5 /proc/version && rpm -qa | grep ^kvm-83-.* || rpm -qa | grep qemu-kvm-[0-9].*-.* | head -n 1'
>04/17 09:50:55 DEBUG|env_proces:0541| KVM userspace version: 
>04/17 09:50:55 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'stop' 
>04/17 09:50:55 DEBUG|qemu_monit:1352| Send command: {'execute': 'stop', 'id': 'MEbczNLR'}
>04/17 09:50:55 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'cont' 
>04/17 09:50:55 DEBUG|qemu_monit:1352| Send command: {'execute': 'cont', 'id': '2A7BXrhV'}
>04/17 09:50:55 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -help'
>04/17 09:50:55 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -device ? 2>&1'
>04/17 09:50:55 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -M ?'
>04/17 09:50:55 DEBUG|     utils:0109| Running 'echo -e 'help
>quit' | /home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -monitor stdio -vnc none'
>04/17 09:50:55 DEBUG|     utils:0109| Running 'echo -e '{ "execute": "qmp_capabilities" }
>{ "execute": "query-commands", "id": "RAND91" }
>{ "execute": "quit" }' | (sleep 1; cat )| /home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -qmp stdio -vnc none | grep return | grep RAND91'
>04/17 09:50:57 DEBUG|   qemu_vm:1664| qemu will run in KVM mode
>04/17 09:50:57 DEBUG|   virt_vm:0590| VM params in env do match requested, continuing.
>04/17 09:50:57 INFO |      virt:0136| Running function: migration.run_migration()
>04/17 09:50:57 DEBUG|   virt_vm:0898| Attempting to log into 'virt-tests-vm1' (timeout 360s)
>04/17 09:50:57 DEBUG|   virt_vm:0667| Found/Verified IP 10.66.84.204 for VM virt-tests-vm1 NIC 0
>04/17 09:50:57 DEBUG|    remote:0219| Login command: 'ssh -o UserKnownHostsFile=/dev/null -o PreferredAuthentications=password -p 22 root@10.66.84.204'
>04/17 09:50:59 DEBUG|   aexpect:1407| Sending command: uname -r
>04/17 09:51:04 DEBUG|   virt_vm:0898| Attempting to log into 'virt-tests-vm1' (timeout 360s)
>04/17 09:51:04 DEBUG|   virt_vm:0667| Found/Verified IP 10.66.84.204 for VM virt-tests-vm1 NIC 0
>04/17 09:51:04 DEBUG|    remote:0219| Login command: 'ssh -o UserKnownHostsFile=/dev/null -o PreferredAuthentications=password -p 22 root@10.66.84.204'
>04/17 09:51:06 DEBUG|   aexpect:1407| Sending command: pgrep tcpdump
>04/17 09:51:06 DEBUG|   aexpect:1407| Sending command: echo $?
>04/17 09:51:06 INFO | migration:0173| Round 0 ping...
>04/17 09:51:06 INFO |   qemu_vm:3037| Migrating to "exec:gzip -c > /var/tmp/exec-W8BLLlhy"
>04/17 09:51:06 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'migrate' 
>04/17 09:51:06 DEBUG|qemu_monit:1352| Send command: {'execute': 'migrate', 'arguments': {'uri': 'exec:gzip -c > /var/tmp/exec-W8BLLlhy', 'blk': False, 'inc': False}, 'id': 'IjizdQyI'}
>04/17 09:51:08 DEBUG|utils_misc:0426| Waiting for migration to complete (2.002079 secs)
>04/17 09:51:08 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:08 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'zI8SUjLh'}
>04/17 09:51:08 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:08 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:08 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:08 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:08 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 17244950528
>04/17 09:51:08 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 26200420
>04/17 09:51:08 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 2050
>04/17 09:51:10 DEBUG|utils_misc:0426| Waiting for migration to complete (4.016788 secs)
>04/17 09:51:10 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:10 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'CWRhUGkz'}
>04/17 09:51:10 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:10 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:10 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:10 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:10 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16593731584
>04/17 09:51:10 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 47436404
>04/17 09:51:10 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 4064
>04/17 09:51:12 DEBUG|utils_misc:0426| Waiting for migration to complete (6.031007 secs)
>04/17 09:51:12 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:12 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'ZesO9kws'}
>04/17 09:51:12 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:12 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:12 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:12 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:12 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16561954816
>04/17 09:51:12 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 79872628
>04/17 09:51:12 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 6077
>04/17 09:51:14 DEBUG|utils_misc:0426| Waiting for migration to complete (8.039513 secs)
>04/17 09:51:14 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:14 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'g1P4xI90'}
>04/17 09:51:14 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:14 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:14 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:14 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:14 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16523026432
>04/17 09:51:14 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 117764980
>04/17 09:51:14 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 8090
>04/17 09:51:16 DEBUG|utils_misc:0426| Waiting for migration to complete (10.056116 secs)
>04/17 09:51:16 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:16 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'eYwHaCHD'}
>04/17 09:51:16 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:16 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:16 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:16 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:16 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16487215104
>04/17 09:51:16 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 153600884
>04/17 09:51:16 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 10103
>04/17 09:51:18 DEBUG| utils_env:0081| (address cache) DHCP lease OK: 9a:cb:cc:cd:ce:cf --> 10.66.85.141
>04/17 09:51:18 DEBUG|utils_misc:0426| Waiting for migration to complete (12.069542 secs)
>04/17 09:51:18 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:18 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'fux4urpC'}
>04/17 09:51:18 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:18 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:18 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:18 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:18 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16450801664
>04/17 09:51:18 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 190026612
>04/17 09:51:18 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 12119
>04/17 09:51:20 DEBUG|utils_misc:0426| Waiting for migration to complete (14.085454 secs)
>04/17 09:51:20 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:20 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'mHDwr1w8'}
>04/17 09:51:20 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:20 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:20 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:20 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:20 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16410562560
>04/17 09:51:20 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 230278004
>04/17 09:51:20 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 14131
>04/17 09:51:22 DEBUG|utils_misc:0426| Waiting for migration to complete (16.096506 secs)
>04/17 09:51:22 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:23 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': '0jHfkVvR'}
>04/17 09:51:23 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 16376139776
>04/17 09:51:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 264708980
>04/17 09:51:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 16244
>04/17 09:51:25 DEBUG|utils_misc:0426| Waiting for migration to complete (18.210756 secs)
>04/17 09:51:25 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:25 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'ElauwAZT'}
>04/17 09:51:25 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 15312674816
>04/17 09:51:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 272589420
>04/17 09:51:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 18284
>04/17 09:51:27 DEBUG|utils_misc:0426| Waiting for migration to complete (20.250502 secs)
>04/17 09:51:27 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:27 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': '41l4kfAJ'}
>04/17 09:51:27 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:27 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:27 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:27 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:27 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 14084767744
>04/17 09:51:27 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 272889216
>04/17 09:51:27 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 20294
>04/17 09:51:29 DEBUG|utils_misc:0426| Waiting for migration to complete (22.260815 secs)
>04/17 09:51:29 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:29 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'xodw71JI'}
>04/17 09:51:29 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:29 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:29 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:29 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:29 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 12905234432
>04/17 09:51:29 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 273279583
>04/17 09:51:29 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 22304
>04/17 09:51:31 DEBUG|utils_misc:0426| Waiting for migration to complete (24.267757 secs)
>04/17 09:51:31 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:31 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'qpy94fPs'}
>04/17 09:51:31 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:31 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:31 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:31 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:31 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 11674234880
>04/17 09:51:31 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 273580147
>04/17 09:51:31 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 24310
>04/17 09:51:33 DEBUG|utils_misc:0426| Waiting for migration to complete (26.273749 secs)
>04/17 09:51:33 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:33 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'Jy1j5Hvv'}
>04/17 09:51:33 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:33 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:33 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:33 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:33 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 10439479296
>04/17 09:51:33 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 273881607
>04/17 09:51:33 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 26317
>04/17 09:51:35 DEBUG|utils_misc:0426| Waiting for migration to complete (28.280531 secs)
>04/17 09:51:35 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:35 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': '8gNN9bjV'}
>04/17 09:51:35 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:35 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:35 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:35 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:35 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 9218330624
>04/17 09:51:35 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 274179739
>04/17 09:51:35 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 28344
>04/17 09:51:37 DEBUG|utils_misc:0426| Waiting for migration to complete (30.308340 secs)
>04/17 09:51:37 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:37 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'KLJWQrpe'}
>04/17 09:51:37 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:37 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:37 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:37 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:37 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 7987490816
>04/17 09:51:37 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 274480239
>04/17 09:51:37 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 30350
>04/17 09:51:39 DEBUG|utils_misc:0426| Waiting for migration to complete (32.313806 secs)
>04/17 09:51:39 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:39 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'Hh1epdzr'}
>04/17 09:51:39 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:39 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:39 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:39 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:39 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 6751444992
>04/17 09:51:39 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 274782019
>04/17 09:51:39 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 32403
>04/17 09:51:41 DEBUG|utils_misc:0426| Waiting for migration to complete (34.369605 secs)
>04/17 09:51:41 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:41 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'o6t1LLs7'}
>04/17 09:51:41 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:41 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:41 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:41 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:41 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 5531959296
>04/17 09:51:41 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 275079767
>04/17 09:51:41 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 34413
>04/17 09:51:43 DEBUG|utils_misc:0426| Waiting for migration to complete (36.380048 secs)
>04/17 09:51:43 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:43 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'RmBU1j2k'}
>04/17 09:51:43 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:43 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:43 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:43 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:43 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 4300849152
>04/17 09:51:43 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 275380331
>04/17 09:51:43 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 36424
>04/17 09:51:45 DEBUG|utils_misc:0426| Waiting for migration to complete (38.387325 secs)
>04/17 09:51:45 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:45 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'T9IKqR9B'}
>04/17 09:51:45 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:45 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:45 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:45 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:45 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 3069759488
>04/17 09:51:45 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 275680895
>04/17 09:51:45 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 38465
>04/17 09:51:47 DEBUG|utils_misc:0426| Waiting for migration to complete (40.431101 secs)
>04/17 09:51:47 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:47 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'UOPH0vZJ'}
>04/17 09:51:47 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:47 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:47 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:47 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:47 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 1837076480
>04/17 09:51:47 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 275981843
>04/17 09:51:47 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 40474
>04/17 09:51:49 DEBUG|utils_misc:0426| Waiting for migration to complete (42.440927 secs)
>04/17 09:51:49 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:49 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'Py7OMfY9'}
>04/17 09:51:49 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:49 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:49 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:49 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:49 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 605663232
>04/17 09:51:49 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 276397142
>04/17 09:51:49 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 42515
>04/17 09:51:51 DEBUG|utils_misc:0426| Waiting for migration to complete (44.479462 secs)
>04/17 09:51:51 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:51 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'b5eEI3dD'}
>04/17 09:51:51 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:51 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:51 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:51 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:51 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 581169152
>04/17 09:51:51 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 299179512
>04/17 09:51:51 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 44523
>04/17 09:51:53 DEBUG|utils_misc:0426| Waiting for migration to complete (46.490220 secs)
>04/17 09:51:53 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:53 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'jgF5VEnw'}
>04/17 09:51:53 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:53 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:53 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:53 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:53 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 554975232
>04/17 09:51:53 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 323370977
>04/17 09:51:53 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 46534
>04/17 09:51:55 DEBUG|utils_misc:0426| Waiting for migration to complete (48.497467 secs)
>04/17 09:51:55 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:55 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'Cw03OjJV'}
>04/17 09:51:55 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:55 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:55 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:55 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:55 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 531308544
>04/17 09:51:55 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 347009000
>04/17 09:51:55 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 48541
>04/17 09:51:57 DEBUG|utils_misc:0426| Waiting for migration to complete (50.507098 secs)
>04/17 09:51:57 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:57 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'Qjfs8juL'}
>04/17 09:51:57 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:57 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:57 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:57 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:57 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 502210560
>04/17 09:51:57 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 375681118
>04/17 09:51:57 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 50555
>04/17 09:51:59 DEBUG|utils_misc:0426| Waiting for migration to complete (52.522047 secs)
>04/17 09:51:59 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:51:59 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'qZQ5vbd2'}
>04/17 09:51:59 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:51:59 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:51:59 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:51:59 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:51:59 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 477179904
>04/17 09:51:59 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 400662662
>04/17 09:51:59 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 52565
>04/17 09:52:01 DEBUG|utils_misc:0426| Waiting for migration to complete (54.532053 secs)
>04/17 09:52:01 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:01 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'dnXNZLFo'}
>04/17 09:52:01 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:01 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:01 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:01 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:01 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 453627904
>04/17 09:52:01 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 423874778
>04/17 09:52:01 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 54577
>04/17 09:52:03 DEBUG|utils_misc:0426| Waiting for migration to complete (56.543956 secs)
>04/17 09:52:03 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:03 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': '4EoKczaU'}
>04/17 09:52:03 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:03 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:03 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:03 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:03 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 425418752
>04/17 09:52:03 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 450548326
>04/17 09:52:03 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 56589
>04/17 09:52:05 DEBUG|utils_misc:0426| Waiting for migration to complete (58.555009 secs)
>04/17 09:52:05 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:05 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'bSGgLu2w'}
>04/17 09:52:05 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:05 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:05 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:05 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:05 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 401915904
>04/17 09:52:05 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 473830045
>04/17 09:52:05 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 58601
>04/17 09:52:07 DEBUG|utils_misc:0426| Waiting for migration to complete (60.567211 secs)
>04/17 09:52:07 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:07 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'UOlHsmMS'}
>04/17 09:52:07 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:07 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:07 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:07 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:07 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 377626624
>04/17 09:52:07 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 497955555
>04/17 09:52:07 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 60611
>04/17 09:52:09 DEBUG|utils_misc:0426| Waiting for migration to complete (62.576592 secs)
>04/17 09:52:09 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:09 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'nti1tJn9'}
>04/17 09:52:09 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:09 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:09 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:09 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:09 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 353329152
>04/17 09:52:09 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 521986852
>04/17 09:52:09 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 62620
>04/17 09:52:11 DEBUG|utils_misc:0426| Waiting for migration to complete (64.586669 secs)
>04/17 09:52:11 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:11 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'FBbfArdu'}
>04/17 09:52:11 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:11 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:11 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:11 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:11 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 322576384
>04/17 09:52:11 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 552883276
>04/17 09:52:11 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 64632
>04/17 09:52:13 DEBUG|utils_misc:0426| Waiting for migration to complete (66.599039 secs)
>04/17 09:52:13 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:13 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'YH6JSYQW'}
>04/17 09:52:13 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:13 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:13 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:13 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:13 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 287395840
>04/17 09:52:13 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 583248160
>04/17 09:52:13 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 66646
>04/17 09:52:15 DEBUG|utils_misc:0426| Waiting for migration to complete (68.613006 secs)
>04/17 09:52:15 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:15 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'QPL3Wkz6'}
>04/17 09:52:15 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:15 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:15 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:15 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:15 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 258297856
>04/17 09:52:15 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 607399551
>04/17 09:52:15 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 68656
>04/17 09:52:17 DEBUG|utils_misc:0426| Waiting for migration to complete (70.623110 secs)
>04/17 09:52:17 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:17 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'UGaQtVaV'}
>04/17 09:52:17 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:17 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:17 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:17 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:17 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 223592448
>04/17 09:52:17 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 634848693
>04/17 09:52:17 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 70669
>04/17 09:52:19 DEBUG|utils_misc:0426| Waiting for migration to complete (72.635860 secs)
>04/17 09:52:19 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:19 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'DOwli0xx'}
>04/17 09:52:19 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:19 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:19 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:19 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:19 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 176246784
>04/17 09:52:19 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 672788273
>04/17 09:52:19 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 72681
>04/17 09:52:21 DEBUG|utils_misc:0426| Waiting for migration to complete (74.649831 secs)
>04/17 09:52:21 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:21 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'muibN51b'}
>04/17 09:52:21 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:21 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:21 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:21 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:21 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 135106560
>04/17 09:52:21 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 713297937
>04/17 09:52:21 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 74694
>04/17 09:52:23 DEBUG|utils_misc:0426| Waiting for migration to complete (76.659451 secs)
>04/17 09:52:23 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:23 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': '5IjMO0ZB'}
>04/17 09:52:23 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: active
>04/17 09:52:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 52998144
>04/17 09:52:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 745996252
>04/17 09:52:23 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 76702
>04/17 09:52:25 DEBUG|utils_misc:0426| Waiting for migration to complete (78.669148 secs)
>04/17 09:52:25 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'query-migrate' 
>04/17 09:52:25 DEBUG|qemu_monit:1352| Send command: {'execute': 'query-migrate', 'id': 'MDrS7XpX'}
>04/17 09:52:25 DEBUG|qemu_monit:1308| (monitor qmpmonitor1) Response to 'query-migrate' (re-formated)
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    status: completed
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    ram: 
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        total: 17280933888
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        remaining: 24576
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)        transferred: 769424403
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    total-time: 78917
>04/17 09:52:25 DEBUG|qemu_monit:1284| (monitor qmpmonitor1)    downtime: 1836
>04/17 09:52:25 DEBUG|   virt_vm:0753| Generating random mac address for nic
>04/17 09:52:25 DEBUG|   qemu_vm:1908| Copying mac for nic nic1 from VM virt-tests-vm1
>04/17 09:52:25 DEBUG| utils_net:0636| Host does not support OpenVSwitch: Missing command: ovs-vswitchd
>04/17 09:52:25 DEBUG|   qemu_vm:1708| Adding VM virt-tests-vm1 NIC ifname t0-Dzza8Q to bridge switch
>04/17 09:52:25 DEBUG| utils_net:0636| Host does not support OpenVSwitch: Missing command: ovs-vswitchd
>04/17 09:52:26 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -help'
>04/17 09:52:26 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -device ? 2>&1'
>04/17 09:52:26 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -M ?'
>04/17 09:52:26 DEBUG|     utils:0109| Running 'echo -e 'help
>quit' | /home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -monitor stdio -vnc none'
>04/17 09:52:26 DEBUG|     utils:0109| Running 'echo -e '{ "execute": "qmp_capabilities" }
>{ "execute": "query-commands", "id": "RAND91" }
>{ "execute": "quit" }' | (sleep 1; cat )| /home/manual_test/stable/autotest/client/tests/virt/qemu/qemu -qmp stdio -vnc none | grep return | grep RAND91'
>04/17 09:52:27 DEBUG|   qemu_vm:1664| qemu will run in KVM mode
>04/17 09:52:27 DEBUG|   qemu_vm:1956| Devices of virt-tests-vm1: [t'PREFIX',t'qemu',t'-S',t'vmname',t'qemu_sandbox',t'machine',t'i440FX',t'PIIX4_PM',t'PIIX3',t'piix3-ide',t'fdc',t'nodefaults',t'VGA-qxl',t'qxl',a'intel-hda',a'hda-duplex',t'QMP-qmpmonitor1',t'SER-serial0',virtio_serial_pci0,t'VIO-vs',t'isa-log',usb1,drive_image1,image1,idvNSnUW,t'NET-bridge',t'mem',t'smp',t'cpu',usb-tablet1,t'display',t'rtc',t'bootmenu',t'extra',t'kvm']
>04/17 09:52:27 DEBUG|   qemu_vm:1957| Buses of virt-tests-vm1
>  drive_image1(QDrive): {drive:image1}
>  usb1.0(uhci): {1:usb-tablet1}
>  floppy(floppy): [None,None]
>  ide(ide): [None,None,None,None]
>  _PCI_CHASSIS_NR(None): {}
>  _PCI_CHASSIS(None): {}
>  pci.0(PCI): {00-00:t'i440FX',01-00:t'PIIX3',01-01:t'piix3-ide',01-03:t'PIIX4_PM',02-00:t'VGA-qxl',03-00:a'intel-hda',04-00:virtio_serial_pci0,05-00:usb1,06-00:image1,07-00:idvNSnUW}
>04/17 09:52:27 INFO |   qemu_vm:2025| Running qemu command (reformatted):
>/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu \
>    -S  \
>    -name 'virt-tests-vm1' \
>    -M rhel6.5.0  \
>    -nodefaults  \
>    -vga qxl  \
>    -global qxl-vga.vram_size=33554432 \
>    -device intel-hda,bus=pci.0,addr=03 \
>    -device hda-duplex  \
>    -chardev socket,id=qmp_id_qmpmonitor1,path=/tmp/monitor-qmpmonitor1-20140417-095106-3izSEl9f,server,nowait \
>    -mon chardev=qmp_id_qmpmonitor1,mode=control  \
>    -chardev socket,id=serial_id_serial0,path=/tmp/serial-serial0-20140417-095106-3izSEl9f,server,nowait \
>    -device isa-serial,chardev=serial_id_serial0 \
>    -device virtio-serial-pci,id=virtio_serial_pci0,bus=pci.0,addr=04  \
>    -chardev socket,id=devvs,path=/tmp/virtio_port-vs-20140417-095106-3izSEl9f,server,nowait \
>    -device virtserialport,chardev=devvs,name=vs,id=vs,bus=virtio_serial_pci0.0  \
>    -chardev socket,id=seabioslog_id_20140417-095106-3izSEl9f,path=/tmp/seabios-20140417-095106-3izSEl9f,server,nowait \
>    -device isa-debugcon,chardev=seabioslog_id_20140417-095106-3izSEl9f,iobase=0x402 \
>    -device ich9-usb-uhci1,id=usb1,bus=pci.0,addr=05 \
>    -drive id=drive_image1,if=none,cache=none,snapshot=off,aio=native,file=/home/manual_test/stable/autotest/client/tests/virt/shared/data/images/RHEL-Server-6.5-64-virtio.qcow2 \
>    -device virtio-blk-pci,id=image1,drive=drive_image1,bootindex=0,bus=pci.0,addr=06 \
>    -device virtio-net-pci,mac=9a:76:77:78:79:7a,id=idvNSnUW,netdev=idLpgLMx,bus=pci.0,addr=07  \
>    -netdev tap,id=idLpgLMx,vhost=on,vhostfd=36,fd=35  \
>    -m 16384  \
>    -smp 8,maxcpus=8,cores=4,threads=1,sockets=2  \
>    -cpu 'Opteron_G3' \
>    -device usb-tablet,id=usb-tablet1,bus=usb1.0,port=1  \
>    -spice port=3001,password=123456,addr=0,tls-port=3201,x509-dir=/tmp/spice_x509d,tls-channel=main,tls-channel=inputs,image-compression=auto_glz,zlib-glz-wan-compression=auto,streaming-video=all,agent-mouse=on,playback-compression=on,ipv4  \
>    -rtc base=utc,clock=host,driftfix=slew  \
>    -boot order=cdn,once=c,menu=off  \
>    -no-kvm-pit-reinjection \
>    -enable-kvm \
>    -incoming "exec:gzip \
>    -c \
>    -d /var/tmp/exec-W8BLLlhy"
>04/17 09:52:28 INFO |   qemu_vm:2034| Created qemu process with parent PID 27199
>04/17 09:52:28 INFO |qemu_monit:0125| Connecting to monitor 'qmpmonitor1'
>04/17 09:52:48 WARNI|qemu_monit:0148| No QMP greeting message received. Output so far: 
>04/17 09:52:49 INFO |   aexpect:0907| [qemu output] red_dispatcher_loadvm_commands:
>04/17 09:52:49 ERROR|   qemu_vm:2080| Could not connect to monitor 'qmpmonitor1'
>04/17 09:52:49 DEBUG|   qemu_vm:2336| Destroying VM virt-tests-vm1 (PID 27201)
>04/17 09:52:49 DEBUG|   qemu_vm:2236| Shutting down VM virt-tests-vm1 (shell)
>04/17 09:52:49 DEBUG|   virt_vm:0667| Found/Verified IP 10.66.84.204 for VM virt-tests-vm1 NIC 0
>04/17 09:52:49 DEBUG|    remote:0219| Login command: 'ssh -o UserKnownHostsFile=/dev/null -o PreferredAuthentications=password -p 22 root@10.66.84.204'
>04/17 09:52:59 DEBUG|   qemu_vm:2248| Login timeout expired    (output: '')
>04/17 09:52:59 DEBUG|   qemu_vm:2346| VM virt-tests-vm1 failed to go down (shell)
>04/17 09:52:59 DEBUG|   qemu_vm:2367| Ending VM virt-tests-vm1 process (killing PID 27199)
>04/17 09:52:59 INFO |   aexpect:0907| [qemu output] (Process terminated with status 0)
>04/17 09:53:00 DEBUG|   qemu_vm:2372| VM virt-tests-vm1 down (process killed)
>04/17 09:53:00 DEBUG| utils_net:0636| Host does not support OpenVSwitch: Missing command: ovs-vswitchd
>04/17 09:53:00 DEBUG|qemu_monit:0267| (monitor qmpmonitor1) Sending command 'cont' 
>04/17 09:53:00 DEBUG|qemu_monit:1352| Send command: {'execute': 'cont', 'id': 'XYxLAwXF'}
>04/17 09:53:00 DEBUG| utils_net:0636| Host does not support OpenVSwitch: Missing command: ovs-vswitchd
>04/17 09:53:00 ERROR|      virt:0155| Test failed: MonitorConnectError: Could not connect to monitor 'qmpmonitor1'
>04/17 09:53:00 DEBUG|env_proces:0241| Encoding video file /home/manual_test/stable/autotest/client/results/default/virt.qemu.repeat1.smp_8.16384m.Host_RHEL.6.5.spice.qcow2.virtio_blk.up.virtio_net.RHEL.6.5.x86_64.migrate.exec.gzip_exec/debug/virt-tests-vm1-1.ogg
>04/17 09:53:00 ERROR|  warnings:0029| /home/manual_test/stable/autotest/client/tests/virt/virttest/video_maker.py:190: DeprecationWarning: gst.Bin.add_many() is deprecated, use gst.Bin.add()
>04/17 09:53:00 ERROR|  warnings:0029|   pipeline.add_many(source, decoder, encoder, container, output)
>04/17 09:53:05 DEBUG|   qemu_vm:2336| Destroying VM virt-tests-vm1 (PID 26937)
>04/17 09:53:05 DEBUG|   qemu_vm:2236| Shutting down VM virt-tests-vm1 (shell)
>04/17 09:53:05 DEBUG|   virt_vm:0667| Found/Verified IP 10.66.84.204 for VM virt-tests-vm1 NIC 0
>04/17 09:53:05 DEBUG|    remote:0219| Login command: 'ssh -o UserKnownHostsFile=/dev/null -o PreferredAuthentications=password -p 22 root@10.66.84.204'
>04/17 09:53:19 INFO |   aexpect:0907| [qemu output] (Process terminated with status 0)
>04/17 09:53:19 DEBUG|   qemu_vm:2343| VM virt-tests-vm1 down (shell)
>04/17 09:53:19 DEBUG| utils_net:0636| Host does not support OpenVSwitch: Missing command: ovs-vswitchd
>04/17 09:53:19 DEBUG|qemu_stora:0392| Checking image file /home/manual_test/stable/autotest/client/tests/virt/shared/data/images/RHEL-Server-6.5-64-virtio.qcow2
>04/17 09:53:19 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu-img info /home/manual_test/stable/autotest/client/tests/virt/shared/data/images/RHEL-Server-6.5-64-virtio.qcow2'
>04/17 09:53:19 DEBUG|     utils:0109| Running '/home/manual_test/stable/autotest/client/tests/virt/qemu/qemu-img check /home/manual_test/stable/autotest/client/tests/virt/shared/data/images/RHEL-Server-6.5-64-virtio.qcow2'
>04/17 09:53:20 ERROR|      test:0414| Exception escaping from test:
>Traceback (most recent call last):
>  File "/home/manual_test/stable/autotest/client/shared/test.py", line 411, in _exec
>    _call_test_function(self.execute, *p_args, **p_dargs)
>  File "/home/manual_test/stable/autotest/client/shared/test.py", line 830, in _call_test_function
>    raise error.UnhandledTestFail(e)
>UnhandledTestFail: Unhandled MonitorConnectError: Could not connect to monitor 'qmpmonitor1'    [context: migrating 'virt-tests-vm1' --> creating destination VM --> creating 'virt-tests-vm1']
>Traceback (most recent call last):
>  File "/home/manual_test/stable/autotest/client/shared/test.py", line 823, in _call_test_function
>    return func(*args, **dargs)
>  File "/home/manual_test/stable/autotest/client/shared/test.py", line 291, in execute
>    postprocess_profiled_run, args, dargs)
>  File "/home/manual_test/stable/autotest/client/shared/test.py", line 209, in _call_run_once
>    *args, **dargs)
>  File "/home/manual_test/stable/autotest/client/shared/test.py", line 313, in run_once_profiling
>    self.run_once(*args, **dargs)
>  File "/home/manual_test/stable/autotest/client/tests/virt/virt.py", line 139, in run_once
>    run_func(self, params, env)
>  File "/home/manual_test/stable/autotest/client/tests/virt/qemu/tests/migration.py", line 179, in run_migration
>    migration_exec_cmd_dst=mig_exec_cmd_dst)
>  File "/home/manual_test/stable/autotest/client/shared/error.py", line 141, in new_fn
>    return fn(*args, **kwargs)
>  File "/home/manual_test/stable/autotest/client/tests/virt/virttest/qemu_vm.py", line 3063, in migrate
>    migration_exec_cmd=migration_exec_cmd_dst)
>  File "/home/manual_test/stable/autotest/client/shared/error.py", line 141, in new_fn
>    return fn(*args, **kwargs)
>  File "/home/manual_test/stable/autotest/client/tests/virt/virttest/qemu_vm.py", line 2078, in create
>    monitor_name, monitor_params, timeout)
>  File "/home/manual_test/stable/autotest/client/tests/virt/virttest/qemu_monitor.py", line 151, in wait_for_create_monitor
>    raise MonitorConnectError(monitor_name)
>MonitorConnectError: Could not connect to monitor 'qmpmonitor1'
>

Actions: View

Attachments on bug 1088155: 887384 | 887527