Login
Log in using an SSO provider:
Fedora Account System
Red Hat Associate
Red Hat Customer
Login using a Red Hat Bugzilla account
Forgot Password
Create an Account
Red Hat Bugzilla – Attachment 1626925 Details for
Bug 1762906
Intermittent error 'Hotplug nic can not get IP address' when executing test case nic_hotplug.vhost_nic.nic_virtio
Home
New
Search
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh90 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
[?]
This site requires JavaScript to be enabled to function correctly, please enable it.
Log file showing nic_virtio test case error 'Hotplug nic can not get ip address'
nic_virtio_fail_job.log (text/plain), 100.59 KB, created by
Karen Mezick
on 2019-10-17 18:56:26 UTC
(
hide
)
Description:
Log file showing nic_virtio test case error 'Hotplug nic can not get ip address'
Filename:
MIME Type:
Creator:
Karen Mezick
Created:
2019-10-17 18:56:26 UTC
Size:
100.59 KB
patch
obsolete
>2019-10-16 16:38:18,563 extension L0189 DEBUG| found extension EntryPoint.parse('human = avocado.plugins.human:HumanJob') >2019-10-16 16:38:18,563 extension L0189 DEBUG| found extension EntryPoint.parse('jobscripts = avocado.plugins.jobscripts:JobScripts') >2019-10-16 16:38:18,564 extension L0189 DEBUG| found extension EntryPoint.parse('teststmpdir = avocado.plugins.teststmpdir:TestsTmpDir') >2019-10-16 16:38:21,956 sysinfo L0405 DEBUG| File /etc/avocado/sysinfo/commands does not exist. >2019-10-16 16:38:21,956 sysinfo L0416 DEBUG| File /etc/avocado/sysinfo/files does not exist. >2019-10-16 16:38:21,956 sysinfo L0444 DEBUG| File /etc/avocado/sysinfo/profilers does not exist. >2019-10-16 16:38:21,964 job L0343 INFO | Command line: /root/venv/bin/avocado run --vt-type qemu --vt-guest-os Linux.RHEL.7.devel.ppc64le --vt-machine-type pseries type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio --vt-extra-params skip_cluster_leak_warn=yes >2019-10-16 16:38:21,965 job L0344 INFO | >2019-10-16 16:38:21,965 job L0378 INFO | Avocado version: 69.0 >2019-10-16 16:38:21,965 job L0379 INFO | >2019-10-16 16:38:21,965 job L0383 INFO | Config files read (in order): >2019-10-16 16:38:21,965 job L0385 INFO | /root/venv/lib/python3.6/site-packages/avocado/etc/avocado/avocado.conf >2019-10-16 16:38:21,965 job L0385 INFO | /root/venv/lib/python3.6/site-packages/avocado/etc/avocado/conf.d/gdb.conf >2019-10-16 16:38:21,965 job L0385 INFO | /root/venv/lib/python3.6/site-packages/avocado/etc/avocado/conf.d/jobscripts.conf >2019-10-16 16:38:21,965 job L0385 INFO | /root/venv/lib/python3.6/site-packages/avocado/etc/avocado/conf.d/resultsdb.conf >2019-10-16 16:38:21,966 job L0385 INFO | /root/venv/lib/python3.6/site-packages/avocado/etc/avocado/conf.d/result_upload.conf >2019-10-16 16:38:21,966 job L0385 INFO | /root/venv/etc/avocado/conf.d/vt.conf >2019-10-16 16:38:21,966 job L0385 INFO | /root/venv/.config/avocado/avocado.conf >2019-10-16 16:38:21,966 job L0386 INFO | >2019-10-16 16:38:21,966 job L0388 INFO | Avocado config: >2019-10-16 16:38:21,968 job L0397 INFO | Section.Key Value >2019-10-16 16:38:21,968 job L0397 INFO | datadir.paths.base_dir /var/lib/avocado >2019-10-16 16:38:21,968 job L0397 INFO | datadir.paths.test_dir /usr/share/doc/avocado/tests >2019-10-16 16:38:21,968 job L0397 INFO | datadir.paths.data_dir /var/lib/avocado/data >2019-10-16 16:38:21,968 job L0397 INFO | datadir.paths.logs_dir ~/avocado/job-results >2019-10-16 16:38:21,968 job L0397 INFO | sysinfo.collect.enabled True >2019-10-16 16:38:21,968 job L0397 INFO | sysinfo.collect.commands_timeout -1 >2019-10-16 16:38:21,968 job L0397 INFO | sysinfo.collect.installed_packages False >2019-10-16 16:38:21,968 job L0397 INFO | sysinfo.collect.profiler False >2019-10-16 16:38:21,968 job L0397 INFO | sysinfo.collect.locale C >2019-10-16 16:38:21,969 job L0397 INFO | sysinfo.collect.per_test False >2019-10-16 16:38:21,969 job L0397 INFO | sysinfo.collectibles.commands /etc/avocado/sysinfo/commands >2019-10-16 16:38:21,969 job L0397 INFO | sysinfo.collectibles.files /etc/avocado/sysinfo/files >2019-10-16 16:38:21,969 job L0397 INFO | sysinfo.collectibles.profilers /etc/avocado/sysinfo/profilers >2019-10-16 16:38:21,969 job L0397 INFO | runner.output.colored True >2019-10-16 16:38:21,969 job L0397 INFO | runner.output.utf8 >2019-10-16 16:38:21,969 job L0397 INFO | remoter.behavior.reject_unknown_hosts False >2019-10-16 16:38:21,969 job L0397 INFO | remoter.behavior.disable_known_hosts False >2019-10-16 16:38:21,969 job L0397 INFO | job.output.loglevel debug >2019-10-16 16:38:21,969 job L0397 INFO | restclient.connection.hostname localhost >2019-10-16 16:38:21,970 job L0397 INFO | restclient.connection.port 9405 >2019-10-16 16:38:21,970 job L0397 INFO | restclient.connection.username >2019-10-16 16:38:21,970 job L0397 INFO | restclient.connection.password >2019-10-16 16:38:21,970 job L0397 INFO | plugins.disable [] >2019-10-16 16:38:21,970 job L0397 INFO | plugins.skip_broken_plugin_notification [] >2019-10-16 16:38:21,970 job L0397 INFO | plugins.loaders ['file', '@DEFAULT'] >2019-10-16 16:38:21,970 job L0397 INFO | gdb.paths.gdb /usr/bin/gdb >2019-10-16 16:38:21,970 job L0397 INFO | gdb.paths.gdbserver /usr/bin/gdbserver >2019-10-16 16:38:21,970 job L0397 INFO | plugins.jobscripts.pre /etc/avocado/scripts/job/pre.d/ >2019-10-16 16:38:21,971 job L0397 INFO | plugins.jobscripts.post /etc/avocado/scripts/job/post.d/ >2019-10-16 16:38:21,971 job L0397 INFO | plugins.jobscripts.warn_non_existing_dir False >2019-10-16 16:38:21,971 job L0397 INFO | plugins.jobscripts.warn_non_zero_status True >2019-10-16 16:38:21,971 job L0397 INFO | vt.setup.backup_image_before_test True >2019-10-16 16:38:21,971 job L0397 INFO | vt.setup.restore_image_after_test True >2019-10-16 16:38:21,971 job L0397 INFO | vt.setup.keep_guest_running False >2019-10-16 16:38:21,971 job L0397 INFO | vt.common.data_dir >2019-10-16 16:38:21,971 job L0397 INFO | vt.common.type_specific_only False >2019-10-16 16:38:21,971 job L0397 INFO | vt.common.mem >2019-10-16 16:38:21,971 job L0397 INFO | vt.common.arch >2019-10-16 16:38:21,972 job L0397 INFO | vt.common.machine_type >2019-10-16 16:38:21,972 job L0397 INFO | vt.common.nettype >2019-10-16 16:38:21,972 job L0397 INFO | vt.common.netdst virbr0 >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.qemu_bin >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.qemu_dst_bin >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.accel kvm >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.vhost off >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.monitor >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.smp 2 >2019-10-16 16:38:21,972 job L0397 INFO | vt.qemu.image_type qcow2 >2019-10-16 16:38:21,973 job L0397 INFO | vt.qemu.nic_model virtio_net >2019-10-16 16:38:21,973 job L0397 INFO | vt.qemu.disk_bus virtio_scsi >2019-10-16 16:38:21,973 job L0397 INFO | vt.qemu.sandbox on >2019-10-16 16:38:21,973 job L0397 INFO | vt.qemu.defconfig yes >2019-10-16 16:38:21,973 job L0397 INFO | vt.qemu.malloc_perturb yes >2019-10-16 16:38:21,973 job L0397 INFO | vt.libvirt.connect_uri qemu:///session >2019-10-16 16:38:21,973 job L0397 INFO | vt.debug.no_cleanup False >2019-10-16 16:38:21,973 job L0398 INFO | >2019-10-16 16:38:21,973 job L0401 INFO | Avocado Data Directories: >2019-10-16 16:38:21,973 job L0402 INFO | >2019-10-16 16:38:21,974 job L0403 INFO | base /var/lib/avocado >2019-10-16 16:38:21,974 job L0404 INFO | tests /root/venv/tests >2019-10-16 16:38:21,974 job L0405 INFO | data /var/lib/avocado/data >2019-10-16 16:38:21,974 job L0406 INFO | logs /root/avocado/job-results/job-2019-10-16T16.38-6beb570 >2019-10-16 16:38:21,975 job L0407 INFO | >2019-10-16 16:38:21,975 job L0413 INFO | No variants available, using defaults only >2019-10-16 16:38:21,975 job L0413 INFO | >2019-10-16 16:38:21,975 job L0413 INFO | Variant : / >2019-10-16 16:38:21,975 job L0416 INFO | Temporary dir: /var/tmp/avocado_trqgboyc/avocado_job_60cflfme >2019-10-16 16:38:21,975 job L0417 INFO | >2019-10-16 16:38:21,975 job L0335 INFO | Job ID: 6beb570786a8202e031d8d1abaf5da623ba84cb5 >2019-10-16 16:38:21,975 job L0338 INFO | >2019-10-16 16:38:22,098 sysinfo L0405 DEBUG| File /etc/avocado/sysinfo/commands does not exist. >2019-10-16 16:38:22,098 sysinfo L0416 DEBUG| File /etc/avocado/sysinfo/files does not exist. >2019-10-16 16:38:22,099 sysinfo L0444 DEBUG| File /etc/avocado/sysinfo/profilers does not exist. >2019-10-16 16:38:22,106 test L0385 INFO | INIT 1-type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio >2019-10-16 16:38:22,107 parameters L0146 DEBUG| PARAMS (key=timeout, path=*, default=14400) => '14400' >2019-10-16 16:38:22,108 test L0417 DEBUG| Test metadata: >2019-10-16 16:38:22,108 test L0425 DEBUG| teststmpdir: /var/tmp/avocado_n_8c7xdp >2019-10-16 16:38:22,108 test L0426 DEBUG| workdir: /var/tmp/avocado_trqgboyc/avocado_job_60cflfme/1-type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio >2019-10-16 16:38:22,282 test L0602 INFO | START 1-type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio >2019-10-16 16:38:22,283 test L0298 DEBUG| DATA (filename=output.expected) => NOT FOUND (data sources: variant, test, file) >2019-10-16 16:38:22,313 test L0312 INFO | Avocado-VT 'unknown' >2019-10-16 16:38:22,313 test L0314 DEBUG| Test parameters: >2019-10-16 16:38:22,314 test L0318 DEBUG| _name_map_file = {'machines.cfg': 'pseries', 'subtests.cfg': '(subtest=type_specific).(subtest=io-github-autotest-qemu).(subtest=nic_hotplug).vhost_nic.nic_virtio', 'host-os.cfg': 'Host.RHEL.m0.u0.Host_arch_ppc64le', 'guest-os.cfg': 'Guest.Linux.RHEL.7.devel.ppc64le', 'guest-hw.cfg': 'bridge.default_bios.no_virtio_rng.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net', 'tests.cfg': 'qemu_kvm_jeos_quick'} >2019-10-16 16:38:22,314 test L0318 DEBUG| _short_name_map_file = {'machines.cfg': 'pseries', 'subtests.cfg': 'type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio', 'host-os.cfg': 'Host.RHEL.m0.u0.Host_arch_ppc64le', 'guest-os.cfg': 'Guest.Linux.RHEL.7.devel.ppc64le', 'guest-hw.cfg': 'bridge.default_bios.no_virtio_rng.filesystem.no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net', 'tests.cfg': 'qemu_kvm_jeos_quick'} >2019-10-16 16:38:22,314 test L0318 DEBUG| auto_cpu_model = yes >2019-10-16 16:38:22,314 test L0318 DEBUG| backup_dir = images/ >2019-10-16 16:38:22,315 test L0318 DEBUG| backup_image = no >2019-10-16 16:38:22,315 test L0318 DEBUG| backup_image_before_testing = yes >2019-10-16 16:38:22,315 test L0318 DEBUG| backup_image_on_check_error = no >2019-10-16 16:38:22,315 test L0318 DEBUG| boot_menu = off >2019-10-16 16:38:22,315 test L0318 DEBUG| boot_once = c >2019-10-16 16:38:22,315 test L0318 DEBUG| boot_order = cdn >2019-10-16 16:38:22,315 test L0318 DEBUG| catch_monitor = catch_monitor >2019-10-16 16:38:22,316 test L0318 DEBUG| cd_format = scsi-cd >2019-10-16 16:38:22,316 test L0318 DEBUG| cdrom_check_cdrom_pattern = /dev/cdrom-\w+|/dev/cdrom\d* >2019-10-16 16:38:22,316 test L0318 DEBUG| cdrom_get_cdrom_cmd = ls /dev/cdrom* >2019-10-16 16:38:22,316 test L0318 DEBUG| cdrom_info_cmd = cat /proc/sys/dev/cdrom/info >2019-10-16 16:38:22,316 test L0318 DEBUG| cdrom_test_cmd = dd if=%s of=/dev/null bs=1 count=1 >2019-10-16 16:38:22,316 test L0318 DEBUG| cdroms = cd1 >2019-10-16 16:38:22,316 test L0318 DEBUG| check_image = yes >2019-10-16 16:38:22,317 test L0318 DEBUG| cmds_installed_host = >2019-10-16 16:38:22,317 test L0318 DEBUG| connect_uri = default >2019-10-16 16:38:22,317 test L0318 DEBUG| convert_ppm_files_to_png = no >2019-10-16 16:38:22,317 test L0318 DEBUG| cpu_chk_cmd = grep -c "^processor\b" /proc/cpuinfo >2019-10-16 16:38:22,317 test L0318 DEBUG| cpu_model_flags = >2019-10-16 16:38:22,317 test L0318 DEBUG| create_vm_libvirt = no >2019-10-16 16:38:22,317 test L0318 DEBUG| dep = ['qemu_kvm_jeos_quick.bridge.default_bios.no_virtio_rng.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net.Guest.Linux.RHEL.7.devel.ppc64le.Host.RHEL.m0.u0.Host_arch_ppc64le.(subtest=type_specific).install', 'qemu_kvm_jeos_quick.bridge.default_bios.no_virtio_rng.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net.Guest.Linux.RHEL.7.devel.ppc64le.Host.RHEL.m0.u0.Host_arch_ppc64le.(subtest=type_specific).setup', 'qemu_kvm_jeos_quick.bridge.default_bios.no_virtio_rng.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net.Guest.Linux.RHEL.7.devel.ppc64le.Host.RHEL.m0.u0.Host_arch_ppc64le.(subtest=type_specific).image_copy', 'qemu_kvm_jeos_quick.bridge.default_bios.no_virtio_rng.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net.Guest.Linux.RHEL.7.devel.ppc64le.Host.RHEL.m0.u0.Host_arch_ppc64le.(subtest=type_specific).unattended_install.cdrom'] >2019-10-16 16:38:22,318 test L0318 DEBUG| display = vnc >2019-10-16 16:38:22,318 test L0318 DEBUG| download_url_stress = http://people.seas.harvard.edu/~apw/stress/stress-1.0.4.tar.gz >2019-10-16 16:38:22,318 test L0318 DEBUG| drive_format = scsi-hd >2019-10-16 16:38:22,318 test L0318 DEBUG| drive_index_cd1 = 1 >2019-10-16 16:38:22,318 test L0318 DEBUG| drive_index_image1 = 0 >2019-10-16 16:38:22,318 test L0318 DEBUG| drive_rerror_image1 = >2019-10-16 16:38:22,318 test L0318 DEBUG| drive_werror_image1 = >2019-10-16 16:38:22,319 test L0318 DEBUG| enable_guest_sosreport = no >2019-10-16 16:38:22,319 test L0318 DEBUG| enable_host_sosreport = no >2019-10-16 16:38:22,319 test L0318 DEBUG| enable_libvirtd_debug_log = yes >2019-10-16 16:38:22,319 test L0318 DEBUG| enable_msix_vectors = yes >2019-10-16 16:38:22,319 test L0318 DEBUG| enable_remote_host_sosreport = no >2019-10-16 16:38:22,319 test L0318 DEBUG| encode_video_files = yes >2019-10-16 16:38:22,319 test L0318 DEBUG| env_cleanup = no >2019-10-16 16:38:22,320 test L0318 DEBUG| extra_params = -net none >2019-10-16 16:38:22,320 test L0318 DEBUG| file_transfer_client = scp >2019-10-16 16:38:22,320 test L0318 DEBUG| file_transfer_port = 22 >2019-10-16 16:38:22,320 test L0318 DEBUG| find_pci_cmd = lspci | grep Ethernet >2019-10-16 16:38:22,320 test L0318 DEBUG| flexible_nic_index = no >2019-10-16 16:38:22,320 test L0318 DEBUG| guest_dmesg_dump_console = no >2019-10-16 16:38:22,320 test L0318 DEBUG| guest_dmesg_ignore = False >2019-10-16 16:38:22,321 test L0318 DEBUG| guest_dmesg_level = 3 >2019-10-16 16:38:22,321 test L0318 DEBUG| guest_port_remote_shell = 22 >2019-10-16 16:38:22,321 test L0318 DEBUG| id = type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio >2019-10-16 16:38:22,321 test L0318 DEBUG| image_backend = filesystem >2019-10-16 16:38:22,321 test L0318 DEBUG| image_chain = >2019-10-16 16:38:22,321 test L0318 DEBUG| image_clone_command = cp --reflink=auto %s %s >2019-10-16 16:38:22,321 test L0318 DEBUG| image_format = qcow2 >2019-10-16 16:38:22,322 test L0318 DEBUG| image_name = images/rhel7devel-ppc64le >2019-10-16 16:38:22,322 test L0318 DEBUG| image_raw_device = no >2019-10-16 16:38:22,322 test L0318 DEBUG| image_remove_command = rm -rf %s >2019-10-16 16:38:22,322 test L0318 DEBUG| image_size = 10G >2019-10-16 16:38:22,322 test L0318 DEBUG| image_unbootable_pattern = Hard Disk.*not a bootable disk >2019-10-16 16:38:22,322 test L0318 DEBUG| image_verify_bootable = yes >2019-10-16 16:38:22,322 test L0318 DEBUG| images = image1 >2019-10-16 16:38:22,323 test L0318 DEBUG| inactivity_treshold = 1800 >2019-10-16 16:38:22,323 test L0318 DEBUG| inactivity_watcher = log >2019-10-16 16:38:22,323 test L0318 DEBUG| indirect_image_blacklist = /dev/hda[\d]* /dev/sda[\d]* /dev/sg0 /dev/md0 >2019-10-16 16:38:22,323 test L0318 DEBUG| inputs = >2019-10-16 16:38:22,323 test L0318 DEBUG| install_virtio = no >2019-10-16 16:38:22,323 test L0318 DEBUG| keep_ppm_files = no >2019-10-16 16:38:22,323 test L0318 DEBUG| keep_ppm_files_on_error = no >2019-10-16 16:38:22,324 test L0318 DEBUG| keep_screendumps = yes >2019-10-16 16:38:22,324 test L0318 DEBUG| keep_screendumps_on_error = yes >2019-10-16 16:38:22,324 test L0318 DEBUG| keep_video_files = yes >2019-10-16 16:38:22,324 test L0318 DEBUG| keep_video_files_on_error = yes >2019-10-16 16:38:22,324 test L0318 DEBUG| kill_timeout = 60 >2019-10-16 16:38:22,324 test L0318 DEBUG| kill_unresponsive_vms = yes >2019-10-16 16:38:22,324 test L0318 DEBUG| kill_vm = yes >2019-10-16 16:38:22,324 test L0318 DEBUG| kill_vm_before_test = no >2019-10-16 16:38:22,325 test L0318 DEBUG| kill_vm_gracefully = yes >2019-10-16 16:38:22,325 test L0318 DEBUG| kill_vm_libvirt = no >2019-10-16 16:38:22,325 test L0318 DEBUG| libvirt_controller = virtio-scsi >2019-10-16 16:38:22,325 test L0318 DEBUG| libvirtd_debug_file = >2019-10-16 16:38:22,325 test L0318 DEBUG| libvirtd_debug_level = 2 >2019-10-16 16:38:22,325 test L0318 DEBUG| libvirtd_log_cleanup = yes >2019-10-16 16:38:22,325 test L0318 DEBUG| local_ip = ENTER.YOUR.LOCAL.EXAMPLE.COM >2019-10-16 16:38:22,326 test L0318 DEBUG| local_pwd = >2019-10-16 16:38:22,326 test L0318 DEBUG| login_timeout = 360 >2019-10-16 16:38:22,326 test L0318 DEBUG| machine_type = pseries >2019-10-16 16:38:22,326 test L0318 DEBUG| main_vm = avocado-vt-vm1 >2019-10-16 16:38:22,326 test L0318 DEBUG| malloc_perturb = yes >2019-10-16 16:38:22,326 test L0318 DEBUG| mem = 1024 >2019-10-16 16:38:22,326 test L0318 DEBUG| mem_chk_cmd = numactl --hardware | awk -F: '/size/ {print $2}' >2019-10-16 16:38:22,327 test L0318 DEBUG| mem_chk_cur_cmd = grep MemTotal /proc/meminfo >2019-10-16 16:38:22,327 test L0318 DEBUG| mem_chk_re_str = [^\$]([0-9]+) >2019-10-16 16:38:22,327 test L0318 DEBUG| memballoon_model = virtio >2019-10-16 16:38:22,327 test L0318 DEBUG| migrate_dest_host = ENTER.YOUR.DEST.EXAMPLE.COM >2019-10-16 16:38:22,327 test L0318 DEBUG| migrate_dest_pwd = PASSWORD.DEST.EXAMPLE >2019-10-16 16:38:22,327 test L0318 DEBUG| migrate_load_vms = >2019-10-16 16:38:22,327 test L0318 DEBUG| migrate_main_vm = avocado-vt-vm1 >2019-10-16 16:38:22,328 test L0318 DEBUG| migrate_port = 49152 >2019-10-16 16:38:22,328 test L0318 DEBUG| migrate_proto = tcp >2019-10-16 16:38:22,328 test L0318 DEBUG| migrate_shared_storage = SHARED_IMAGE.EXAMPLE >2019-10-16 16:38:22,328 test L0318 DEBUG| migrate_source_host = ENTER.YOUR.SOURCE.EXAMPLE.COM >2019-10-16 16:38:22,328 test L0318 DEBUG| migrate_source_pwd = PASSWORD.SOURCE.EXAMPLE >2019-10-16 16:38:22,328 test L0318 DEBUG| migrate_vms = >2019-10-16 16:38:22,328 test L0318 DEBUG| migration_setup = no >2019-10-16 16:38:22,329 test L0318 DEBUG| modprobe_module = >2019-10-16 16:38:22,329 test L0318 DEBUG| monitor_type = qmp >2019-10-16 16:38:22,329 test L0318 DEBUG| monitors = qmpmonitor1 >2019-10-16 16:38:22,329 test L0318 DEBUG| name = qemu_kvm_jeos_quick.bridge.default_bios.no_virtio_rng.(image_backend=filesystem).no_9p_export.smallpages.no_pci_assignable.qcow2.virtio_scsi.smp2.virtio_net.Guest.Linux.RHEL.7.devel.ppc64le.Host.RHEL.m0.u0.Host_arch_ppc64le.(subtest=type_specific).(subtest=io-github-autotest-qemu).(subtest=nic_hotplug).vhost_nic.nic_virtio.pseries >2019-10-16 16:38:22,329 test L0318 DEBUG| netdev_extra_params_hotplug_nic1 = ,vhost=on >2019-10-16 16:38:22,329 test L0318 DEBUG| netdev_peer_re = (.*?): .*?\\\s(.*?): >2019-10-16 16:38:22,329 test L0318 DEBUG| netdst = virbr0 >2019-10-16 16:38:22,330 test L0318 DEBUG| nettype = bridge >2019-10-16 16:38:22,330 test L0318 DEBUG| nic_hotplug_count = 1 >2019-10-16 16:38:22,330 test L0318 DEBUG| nic_model = virtio >2019-10-16 16:38:22,330 test L0318 DEBUG| nics = >2019-10-16 16:38:22,330 test L0318 DEBUG| os_type = linux >2019-10-16 16:38:22,330 test L0318 DEBUG| os_variant = rhel7 >2019-10-16 16:38:22,330 test L0318 DEBUG| other_tests_dirs = >2019-10-16 16:38:22,331 test L0318 DEBUG| password = 123456 >2019-10-16 16:38:22,331 test L0318 DEBUG| paused_after_start_vm = no >2019-10-16 16:38:22,331 test L0318 DEBUG| pci_assignable = no >2019-10-16 16:38:22,331 test L0318 DEBUG| pci_model = virtio-net-pci >2019-10-16 16:38:22,331 test L0318 DEBUG| pci_test_cmd = echo %s; nslookup www.redhat.com >2019-10-16 16:38:22,331 test L0318 DEBUG| pci_type = nic >2019-10-16 16:38:22,331 test L0318 DEBUG| power9_compat = no >2019-10-16 16:38:22,332 test L0318 DEBUG| power9_compat_remote = no >2019-10-16 16:38:22,332 test L0318 DEBUG| profilers = kvm_stat >2019-10-16 16:38:22,332 test L0318 DEBUG| provider = io-github-autotest-qemu >2019-10-16 16:38:22,332 test L0318 DEBUG| qemu_binary = /usr/libexec/qemu-kvm >2019-10-16 16:38:22,332 test L0318 DEBUG| qemu_dst_binary = /usr/libexec/qemu-kvm >2019-10-16 16:38:22,332 test L0318 DEBUG| qemu_img_binary = /usr/bin/qemu-img >2019-10-16 16:38:22,332 test L0318 DEBUG| qemu_io_binary = /usr/bin/qemu-io >2019-10-16 16:38:22,333 test L0318 DEBUG| reboot_command = shutdown -r now >2019-10-16 16:38:22,333 test L0318 DEBUG| redirs = remote_shell >2019-10-16 16:38:22,333 test L0318 DEBUG| reference_cmd = lspci >2019-10-16 16:38:22,333 test L0318 DEBUG| remote_ip = ENTER.YOUR.REMOTE.EXAMPLE.COM >2019-10-16 16:38:22,333 test L0318 DEBUG| remote_pwd = >2019-10-16 16:38:22,333 test L0318 DEBUG| remote_user = root >2019-10-16 16:38:22,333 test L0318 DEBUG| remove_image_image1 = no >2019-10-16 16:38:22,334 test L0318 DEBUG| restore_image = no >2019-10-16 16:38:22,334 test L0318 DEBUG| restore_image_after_testing = yes >2019-10-16 16:38:22,334 test L0318 DEBUG| restore_image_on_check_error = no >2019-10-16 16:38:22,334 test L0318 DEBUG| restore_smt = no >2019-10-16 16:38:22,334 test L0318 DEBUG| rpmbuild_path = /root/rpmbuild/ >2019-10-16 16:38:22,334 test L0318 DEBUG| run_dhclient = no >2019-10-16 16:38:22,334 test L0318 DEBUG| run_tcpdump = yes >2019-10-16 16:38:22,335 test L0318 DEBUG| screendump_delay = 5 >2019-10-16 16:38:22,335 test L0318 DEBUG| screendump_quality = 30 >2019-10-16 16:38:22,335 test L0318 DEBUG| screendump_temp_dir = /dev/shm >2019-10-16 16:38:22,335 test L0318 DEBUG| screendump_verbose = no >2019-10-16 16:38:22,335 test L0318 DEBUG| scsi_hba = virtio-scsi-pci >2019-10-16 16:38:22,335 test L0318 DEBUG| serials = serial0 >2019-10-16 16:38:22,335 test L0318 DEBUG| shell_client = ssh >2019-10-16 16:38:22,336 test L0318 DEBUG| shell_linesep = \n >2019-10-16 16:38:22,336 test L0318 DEBUG| shell_port = 22 >2019-10-16 16:38:22,336 test L0318 DEBUG| shell_prompt = ^\[.*\][\#\$]\s*$ >2019-10-16 16:38:22,336 test L0318 DEBUG| shortname = qcow2.virtio_scsi.smp2.virtio_net.Guest.RHEL.7.devel.ppc64le.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio >2019-10-16 16:38:22,336 test L0318 DEBUG| shutdown_command = shutdown -h now >2019-10-16 16:38:22,336 test L0318 DEBUG| skip_cluster_leak_warn = yes >2019-10-16 16:38:22,336 test L0318 DEBUG| skip_image_check_during_running = no >2019-10-16 16:38:22,337 test L0318 DEBUG| skip_image_processing = no >2019-10-16 16:38:22,337 test L0318 DEBUG| smp = 2 >2019-10-16 16:38:22,337 test L0318 DEBUG| start_vm = yes >2019-10-16 16:38:22,337 test L0318 DEBUG| status_test_command = echo $? >2019-10-16 16:38:22,337 test L0318 DEBUG| store_vm_register = yes >2019-10-16 16:38:22,337 test L0318 DEBUG| stress_args = --cpu 4 --io 4 --vm 2 --vm-bytes 256M >2019-10-16 16:38:22,337 test L0318 DEBUG| stress_install_from_repo = no >2019-10-16 16:38:22,338 test L0318 DEBUG| strict_mode = no >2019-10-16 16:38:22,338 test L0318 DEBUG| subtest = io-github-autotest-qemu.nic_hotplug >2019-10-16 16:38:22,338 test L0318 DEBUG| take_regular_screendumps = yes >2019-10-16 16:38:22,338 test L0318 DEBUG| test_timeout = 14400 >2019-10-16 16:38:22,338 test L0318 DEBUG| type = nic_hotplug >2019-10-16 16:38:22,338 test L0318 DEBUG| url_auto_ip = >2019-10-16 16:38:22,338 test L0318 DEBUG| usb_bus = usb1.0 >2019-10-16 16:38:22,339 test L0318 DEBUG| usb_controller = xhci >2019-10-16 16:38:22,339 test L0318 DEBUG| usb_devices = tablet1 >2019-10-16 16:38:22,339 test L0318 DEBUG| usb_max_port = 6 >2019-10-16 16:38:22,339 test L0318 DEBUG| usb_type = nec-usb-xhci >2019-10-16 16:38:22,339 test L0318 DEBUG| usb_type_tablet1 = usb-tablet >2019-10-16 16:38:22,339 test L0318 DEBUG| usb_type_usb1 = nec-usb-xhci >2019-10-16 16:38:22,339 test L0318 DEBUG| usbs = usb1 >2019-10-16 16:38:22,340 test L0318 DEBUG| use_autostart = no >2019-10-16 16:38:22,340 test L0318 DEBUG| use_check_cpu = yes >2019-10-16 16:38:22,340 test L0318 DEBUG| use_debug = yes >2019-10-16 16:38:22,340 test L0318 DEBUG| use_no_reboot = no >2019-10-16 16:38:22,340 test L0318 DEBUG| use_os_type = yes >2019-10-16 16:38:22,340 test L0318 DEBUG| use_os_variant = no >2019-10-16 16:38:22,340 test L0318 DEBUG| use_virt_install_wait = no >2019-10-16 16:38:22,341 test L0318 DEBUG| used_cpus = 2 >2019-10-16 16:38:22,341 test L0318 DEBUG| used_mem = 512 >2019-10-16 16:38:22,341 test L0318 DEBUG| username = root >2019-10-16 16:38:22,341 test L0318 DEBUG| vcpu_thread_pattern = thread_id.?[:|=]\s*(\d+) >2019-10-16 16:38:22,341 test L0318 DEBUG| verify_guest_dmesg = yes >2019-10-16 16:38:22,341 test L0318 DEBUG| verify_host_dmesg = yes >2019-10-16 16:38:22,341 test L0318 DEBUG| vfd_size = 1440k >2019-10-16 16:38:22,342 test L0318 DEBUG| vga = std >2019-10-16 16:38:22,342 test L0318 DEBUG| vga_use_legacy_expression = no >2019-10-16 16:38:22,342 test L0318 DEBUG| virt_install_wait_time = 300 >2019-10-16 16:38:22,342 test L0318 DEBUG| virt_test_type = qemu >2019-10-16 16:38:22,342 test L0318 DEBUG| virtinstall_extra_args = >2019-10-16 16:38:22,342 test L0318 DEBUG| vm_arch_name = ppc64le >2019-10-16 16:38:22,342 test L0318 DEBUG| vm_register_delay = 5 >2019-10-16 16:38:22,343 test L0318 DEBUG| vm_type = qemu >2019-10-16 16:38:22,343 test L0318 DEBUG| vms = avocado-vt-vm1 >2019-10-16 16:38:22,343 test L0318 DEBUG| vnc_autoport = yes >2019-10-16 16:38:22,343 test L0318 DEBUG| wait_secs_for_hook_up = 3 >2019-10-16 16:38:22,353 test L0371 DEBUG| Searching for test modules that match 'type = nic_hotplug' and 'provider = io-github-autotest-qemu' on this cartesian dict >2019-10-16 16:38:22,354 test L0385 DEBUG| Found subtest module /var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py >2019-10-16 16:38:22,357 utils_env L0088 WARNI| Creating new, empty env file >2019-10-16 16:38:22,363 process L0626 INFO | Running 'grep revision /proc/cpuinfo | awk '{print $3}' | head -n 1' >2019-10-16 16:38:22,367 process L0458 DEBUG| [stdout] 2.1 >2019-10-16 16:38:22,367 process L0714 INFO | Command 'grep revision /proc/cpuinfo | awk '{print $3}' | head -n 1' finished with 0 after 0.0025827884674072266s >2019-10-16 16:38:22,388 process L0626 INFO | Running 'ppc64_cpu --smt' >2019-10-16 16:38:22,399 process L0458 DEBUG| [stdout] SMT is off >2019-10-16 16:38:22,400 process L0714 INFO | Command 'ppc64_cpu --smt' finished with 0 after 0.010915279388427734s >2019-10-16 16:38:23,533 env_process L0901 DEBUG| KVM version: 4.18.0-141.el8.ppc64le >2019-10-16 16:38:23,550 env_process L0936 DEBUG| KVM userspace version(qemu): 2.12.0 (qemu-kvm-2.12.0-88.module+el8.1.0+4233+bc44be3f) >2019-10-16 16:38:23,550 process L0626 INFO | Running 'true' >2019-10-16 16:38:23,552 process L0714 INFO | Command 'true' finished with 0 after 0.0006265640258789062s >2019-10-16 16:38:23,553 process L0626 INFO | Running 'ps -o comm 1' >2019-10-16 16:38:23,589 process L0458 DEBUG| [stdout] COMMAND >2019-10-16 16:38:23,589 process L0714 INFO | Command 'ps -o comm 1' finished with 0 after 0.03531193733215332s >2019-10-16 16:38:23,590 process L0458 DEBUG| [stdout] systemd >2019-10-16 16:38:23,626 storage L0471 DEBUG| Image backup /var/lib/avocado/data/avocado-vt/images/rhel7devel-ppc64le.qcow2.backup already exists, skipping... >2019-10-16 16:38:23,948 utils_misc L2010 DEBUG| Detected CPU vendor as 'unknown' >2019-10-16 16:38:23,968 qemu_vm L2296 DEBUG| qemu will run in KVM mode >2019-10-16 16:38:23,973 qemu_vm L2853 DEBUG| Devices of avocado-vt-vm1: [t'PREFIX',t'qemu',t'-S',t'vmname',t'machine',t'i440FX',t'PIIX4_PM',t'PIIX3',t'piix3-ide',t'fdc',t'nodefaults',a'VGA',t'QMP-qmpmonitor1',t'QMP-catch_monitor',t'SER-serial0',t'isa-log',usb1,virtio_scsi_pci0,drive_image1,image1,t'mem',t'smp',usb-tablet1,t'tcp-redir',t'display',t'rtc',t'bootmenu',t'extra',t'kvm'] >2019-10-16 16:38:23,973 qemu_vm L2854 DEBUG| Buses of avocado-vt-vm1 > drive_image1(QDrive): {drive:image1} > virtio_scsi_pci0.0(virtio-scsi-pci): {0-0:image1} > usb1.0(xhci): {1:usb-tablet1} > floppy(floppy): [None,None] > ide(ide): [None,None,None,None] > _PCI_CHASSIS_NR(None): {} > _PCI_CHASSIS(None): {} > pci.0(PCI): {0x0-0x0:t'i440FX',0x1-0x0:t'PIIX3',0x1-0x1:t'piix3-ide',0x1-0x3:t'PIIX4_PM',0x2-0x0:a'VGA',0x3-0x0:usb1,0x4-0x0:virtio_scsi_pci0} >2019-10-16 16:38:23,973 qemu_vm L2918 INFO | Running qemu command (reformatted): >MALLOC_PERTURB_=1 /usr/libexec/qemu-kvm \ > -S \ > -name 'avocado-vt-vm1' \ > -machine pseries \ > -nodefaults \ > -device VGA,bus=pci.0,addr=0x2 \ > -chardev socket,id=qmp_id_qmpmonitor1,path=/var/tmp/avocado_trqgboyc/monitor-qmpmonitor1-20191016-163823-wmCOSYgv,server,nowait \ > -mon chardev=qmp_id_qmpmonitor1,mode=control \ > -chardev socket,id=qmp_id_catch_monitor,path=/var/tmp/avocado_trqgboyc/monitor-catch_monitor-20191016-163823-wmCOSYgv,server,nowait \ > -mon chardev=qmp_id_catch_monitor,mode=control \ > -chardev socket,id=serial_id_serial0,path=/var/tmp/avocado_trqgboyc/serial-serial0-20191016-163823-wmCOSYgv,server,nowait \ > -device spapr-vty,reg=0x30000000,chardev=serial_id_serial0 \ > -device nec-usb-xhci,id=usb1,bus=pci.0,addr=0x3 \ > -device virtio-scsi-pci,id=virtio_scsi_pci0,bus=pci.0,addr=0x4 \ > -drive id=drive_image1,if=none,format=qcow2,file=/var/lib/avocado/data/avocado-vt/images/rhel7devel-ppc64le.qcow2 \ > -device scsi-hd,id=image1,drive=drive_image1 \ > -m 1024 \ > -smp 2,maxcpus=2,cores=1,threads=1,sockets=2 \ > -device usb-tablet,id=usb-tablet1,bus=usb1.0,port=1 \ > -vnc :0 \ > -rtc base=utc,clock=host \ > -boot order=cdn,once=c,menu=off,strict=off \ > -net none \ > -enable-kvm >2019-10-16 16:38:25,064 qemu_vm L2928 INFO | Created qemu process with parent PID 75519 >2019-10-16 16:38:25,374 qemu_monitor L0148 INFO | Connecting to monitor '<<class 'virttest.qemu_monitor.QMPMonitor'>> qmpmonitor1' >2019-10-16 16:38:25,475 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'qmp_capabilities' >2019-10-16 16:38:25,475 qemu_monitor L1700 DEBUG| Send command: {'execute': 'qmp_capabilities', 'id': 'M6VBEkKL'} >2019-10-16 16:38:25,503 qemu_monitor L0148 INFO | Connecting to monitor '<<class 'virttest.qemu_monitor.QMPMonitor'>> catch_monitor' >2019-10-16 16:38:25,604 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.catch_monitor) Sending command 'qmp_capabilities' >2019-10-16 16:38:25,605 qemu_monitor L1700 DEBUG| Send command: {'execute': 'qmp_capabilities', 'id': 'FAt4Ncvw'} >2019-10-16 16:38:25,645 qemu_vm L3036 DEBUG| VM appears to be alive with PID 75521 >2019-10-16 16:38:25,645 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'query-cpus' >2019-10-16 16:38:25,646 qemu_monitor L1700 DEBUG| Send command: {'execute': 'query-cpus', 'id': 'FeN106T1'} >2019-10-16 16:38:25,647 qemu_monitor L1656 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Response to 'query-cpus' (re-formated) >2019-10-16 16:38:25,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) arch: ppc >2019-10-16 16:38:25,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) current: True >2019-10-16 16:38:25,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) props: >2019-10-16 16:38:25,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) core-id: 0 >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) CPU: 0 >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) nip: 256 >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) qom_path: /machine/unattached/device[0]/thread[0] >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) halted: False >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) thread_id: 75528 >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) arch: ppc >2019-10-16 16:38:25,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) current: False >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) props: >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) core-id: 1 >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) CPU: 1 >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) nip: 256 >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) qom_path: /machine/unattached/device[1]/thread[0] >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) halted: True >2019-10-16 16:38:25,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) thread_id: 75529 >2019-10-16 16:38:26,865 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'cont' >2019-10-16 16:38:26,866 qemu_monitor L1700 DEBUG| Send command: {'execute': 'cont', 'id': 'pm3h6fjd'} >2019-10-16 16:38:26,872 virt_vm L0935 WARNI| Unable to read serial console >2019-10-16 16:38:26,873 virt_vm L0995 WARNI| Unable to read serial console >2019-10-16 16:38:26,877 utils_net L3233 WARNI| No IP address of host was provided, using IP address on virbr0 interface >2019-10-16 16:38:26,878 virt_vm L1338 DEBUG| Attempting to log into 'avocado-vt-vm1' via serial console (timeout 360s) >2019-10-16 16:39:18,459 nic_hotplug L0168 INFO | Disable other link(s) of guest >2019-10-16 16:39:18,459 nic_hotplug L0179 DEBUG| Hotplug 1th 'virtio-net-pci' nic named 'hotplug_nic1' >2019-10-16 16:39:18,460 virt_vm L0884 DEBUG| Generating random mac address for nic >2019-10-16 16:39:18,582 error_context L0079 DEBUG| Context: (hotplug_nic) --> Opening tap device node for t0-owX79I >2019-10-16 16:39:18,618 error_context L0079 INFO | Context: (hotplug_nic) --> Assigning tap idbTA7vt to qemu by fd >2019-10-16 16:39:18,619 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'getfd' >2019-10-16 16:39:18,619 qemu_monitor L1700 DEBUG| Send command: {'execute': 'getfd', 'arguments': {'fdname': 'idbTA7vt'}, 'id': 'SpsnFqpS'} >2019-10-16 16:39:18,622 error_context L0079 DEBUG| Context: (hotplug_nic) --> Raising interface for nic {'netdev_extra_params': ',vhost=on', 'nic_name': 'hotplug_nic1', 'nic_model': 'virtio-net-pci', 'nettype': 'bridge', 'netdst': 'virbr0', 'mac': '9a:b5:b6:b7:b8:b9', 'vlan': '0', 'device_id': 'idccVpRw', 'queues': '1', 'netdev_id': 'id89Pepe', 'ifname': 't0-owX79I', 'tapfd_ids': ['idbTA7vt'], 'vectors': 4} on vm avocado-vt-vm1 with attach_cmd netdev_add type=tap,id=idccVpRw,fd=33 >2019-10-16 16:39:18,626 error_context L0079 DEBUG| Context: (hotplug_nic) --> Raising bridge for nic {'netdev_extra_params': ',vhost=on', 'nic_name': 'hotplug_nic1', 'nic_model': 'virtio-net-pci', 'nettype': 'bridge', 'netdst': 'virbr0', 'mac': '9a:b5:b6:b7:b8:b9', 'vlan': '0', 'device_id': 'idccVpRw', 'queues': '1', 'netdev_id': 'id89Pepe', 'ifname': 't0-owX79I', 'tapfd_ids': ['idbTA7vt'], 'vectors': 4} on vm avocado-vt-vm1 with attach_cmd netdev_add type=tap,id=idccVpRw,fd=33 >2019-10-16 16:39:18,626 utils_net L1131 DEBUG| Host does not support OpenVSwitch: Command 'ovs-vswitchd' could not be found in any of the PATH dirs: ['/usr/sbin', '/usr/local/sbin', '/root/bin', '/sbin', '/usr/local/bin', '/usr/libexec', '/usr/bin', '/root/venv/bin', '/bin'] >2019-10-16 16:39:18,629 error_context L0079 DEBUG| Context: (hotplug_nic) --> Hotplugging nic {'netdev_extra_params': ',vhost=on', 'nic_name': 'hotplug_nic1', 'nic_model': 'virtio-net-pci', 'nettype': 'bridge', 'netdst': 'virbr0', 'mac': '9a:b5:b6:b7:b8:b9', 'vlan': '0', 'device_id': 'idccVpRw', 'queues': '1', 'netdev_id': 'id89Pepe', 'ifname': 't0-owX79I', 'tapfd_ids': ['idbTA7vt'], 'vectors': 4} on vm avocado-vt-vm1 with attach_cmd netdev_add type=tap,id=idccVpRw,fd=33,vhost=on >2019-10-16 16:39:18,630 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'netdev_add' >2019-10-16 16:39:18,631 qemu_monitor L1700 DEBUG| Send command: {'execute': 'netdev_add', 'arguments': {'type': 'tap', 'id': 'idccVpRw', 'fd': 33, 'vhost': 'on'}, 'id': 'dq9OvV1p'} >2019-10-16 16:39:18,633 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'info network' (via Human Monitor) >2019-10-16 16:39:18,635 qemu_monitor L1656 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Response to 'info network' (re-formated) >2019-10-16 16:39:18,636 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) idccVpRw: index=0,type=tap,fd=33 >2019-10-16 16:39:18,636 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'device_add' >2019-10-16 16:39:18,636 qemu_monitor L1700 DEBUG| Send command: {'execute': 'device_add', 'arguments': {'driver': 'virtio-net-pci', 'netdev': 'idccVpRw', 'mac': '9a:b5:b6:b7:b8:b9', 'id': 'hotplug_nic1', 'vectors': 4}, 'id': 'oivQB1WX'} >2019-10-16 16:39:18,641 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'info qtree' (via Human Monitor) >2019-10-16 16:39:18,644 qemu_monitor L1656 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Response to 'info qtree' (re-formated) >2019-10-16 16:39:18,644 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: main-system-bus >2019-10-16 16:39:18,644 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type System >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: spapr-pci-host-bridge, id "" >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) index = 0 (0x0) >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) mem_win_size = 2147483648 (0x80000000) >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) mem64_win_size = 1099511627776 (0x10000000000) >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) io_win_size = 65536 (0x10000) >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dynamic-reconfiguration = true >2019-10-16 16:39:18,645 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dma_win_addr = 0 (0x0) >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dma_win_size = 1073741824 (0x40000000) >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dma64_win_addr = 576460752303423488 (0x800000000000000) >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ddw = true >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) pgsz = 69632 (0x11000) >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) numa_node = 4294967295 (0xffffffff) >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) pre-2.8-migration = false >2019-10-16 16:39:18,646 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) pcie-extended-configuration-space = true >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) gpa = 70368744177664 (0x400000000000) >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) atsd = 140737488355328 (0x800000000000) >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: pci.0 >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type PCI >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: virtio-net-pci, id "hotplug_nic1" >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ioeventfd = true >2019-10-16 16:39:18,647 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) vectors = 4 (0x4) >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) virtio-pci-bus-master-bug-migration = false >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) disable-legacy = "off" >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) disable-modern = false >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) migrate-extra = true >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) modern-pio-notify = false >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-disable-pcie = false >2019-10-16 16:39:18,648 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) page-per-vq = false >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-ignore-backend-features = false >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ats = false >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-deverr-init = true >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-lnkctl-init = true >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-pm-init = true >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) addr = 00.0 >2019-10-16 16:39:18,649 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) romfile = "efi-virtio.rom" >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rombar = 1 (0x1) >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) multifunction = false >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) command_serr_enable = true >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-lnksta-dllla = true >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-extcap-init = true >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) class Ethernet controller, addr 00:00.0, pci id 1af4:1000 (sub 1af4:0001) >2019-10-16 16:39:18,650 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 0: i/o at 0xffffffffffffffff [0x1e] >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 1: mem at 0xffffffffffffffff [0xffe] >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 4: mem at 0xffffffffffffffff [0x3ffe] >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 6: mem at 0xffffffffffffffff [0x3fffe] >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: virtio-bus >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type virtio-pci-bus >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: virtio-net-device, id "" >2019-10-16 16:39:18,651 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) csum = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) guest_csum = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) gso = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) guest_tso4 = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) guest_tso6 = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) guest_ecn = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) guest_ufo = true >2019-10-16 16:39:18,652 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) guest_announce = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) host_tso4 = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) host_tso6 = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) host_ecn = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) host_ufo = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) mrg_rxbuf = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) status = true >2019-10-16 16:39:18,653 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ctrl_vq = true >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ctrl_rx = true >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ctrl_vlan = true >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ctrl_rx_extra = true >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ctrl_mac_addr = true >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ctrl_guest_offloads = true >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) mq = false >2019-10-16 16:39:18,654 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) mac = "9a:b5:b6:b7:b8:b9" >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) vlan = <null> >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) netdev = "idccVpRw" >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-txtimer = 150000 (0x249f0) >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-txburst = 256 (0x100) >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) tx = "" >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rx_queue_size = 256 (0x100) >2019-10-16 16:39:18,655 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) tx_queue_size = 256 (0x100) >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) host_mtu = 0 (0x0) >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-mtu-bypass-backend = true >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) speed = -1 (0xffffffffffffffff) >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) duplex = "" >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) indirect_desc = true >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) event_idx = true >2019-10-16 16:39:18,656 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) notify_on_empty = true >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) any_layout = true >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) iommu_platform = false >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: virtio-scsi-pci, id "virtio_scsi_pci0" >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ioeventfd = true >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) vectors = 4 (0x4) >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) virtio-pci-bus-master-bug-migration = false >2019-10-16 16:39:18,657 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) disable-legacy = "off" >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) disable-modern = false >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) migrate-extra = true >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) modern-pio-notify = false >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-disable-pcie = false >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) page-per-vq = false >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-ignore-backend-features = false >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ats = false >2019-10-16 16:39:18,658 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-deverr-init = true >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-lnkctl-init = true >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-pm-init = true >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) addr = 04.0 >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) romfile = "" >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rombar = 1 (0x1) >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) multifunction = false >2019-10-16 16:39:18,659 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) command_serr_enable = true >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-lnksta-dllla = true >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-extcap-init = true >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) class SCSI controller, addr 00:04.0, pci id 1af4:1004 (sub 1af4:0008) >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 0: i/o at 0x40 [0x7f] >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 1: mem at 0x81030000 [0x81030fff] >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 4: mem at 0x210000000000 [0x210000003fff] >2019-10-16 16:39:18,660 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: virtio-bus >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type virtio-pci-bus >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: virtio-scsi-device, id "" >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) num_queues = 1 (0x1) >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) virtqueue_size = 128 (0x80) >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) max_sectors = 65535 (0xffff) >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) cmd_per_lun = 128 (0x80) >2019-10-16 16:39:18,661 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) hotplug = true >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) param_change = true >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) iothread = "" >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) indirect_desc = true >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) event_idx = true >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) notify_on_empty = true >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) any_layout = true >2019-10-16 16:39:18,662 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) iommu_platform = false >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: virtio_scsi_pci0.0 >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type SCSI >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: scsi-hd, id "image1" >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) drive = "drive_image1" >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) logical_block_size = 512 (0x200) >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) physical_block_size = 512 (0x200) >2019-10-16 16:39:18,663 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) min_io_size = 0 (0x0) >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) opt_io_size = 0 (0x0) >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) discard_granularity = 4096 (0x1000) >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) write-cache = "auto" >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) share-rw = false >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rerror = "auto" >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) werror = "auto" >2019-10-16 16:39:18,664 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) ver = "2.5+" >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) serial = "" >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) vendor = "QEMU" >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) product = "QEMU HARDDISK" >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) removable = false >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dpofua = false >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) wwn = 0 (0x0) >2019-10-16 16:39:18,665 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) port_wwn = 0 (0x0) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) port_index = 0 (0x0) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) max_unmap_size = 1073741824 (0x40000000) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) max_io_size = 2147483647 (0x7fffffff) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rotation_rate = 0 (0x0) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) scsi_version = 5 (0x5) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) cyls = 16383 (0x3fff) >2019-10-16 16:39:18,666 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) heads = 16 (0x10) >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) secs = 63 (0x3f) >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) channel = 0 (0x0) >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) scsi-id = 0 (0x0) >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) lun = 0 (0x0) >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: nec-usb-xhci, id "usb1" >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) msi = "auto" >2019-10-16 16:39:18,667 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) msix = "auto" >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) superspeed-ports-first = true >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) force-pcie-endcap = false >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) intrs = 16 (0x10) >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) slots = 64 (0x40) >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) streams = true >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) p2 = 4 (0x4) >2019-10-16 16:39:18,668 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) p3 = 4 (0x4) >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) addr = 03.0 >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) romfile = "" >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rombar = 1 (0x1) >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) multifunction = false >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) command_serr_enable = true >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-lnksta-dllla = true >2019-10-16 16:39:18,669 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-extcap-init = true >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) class USB controller, addr 00:03.0, pci id 1033:0194 (sub 1af4:1100) >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 0: mem at 0x81020000 [0x81023fff] >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: usb1.0 >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type usb-bus >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: usb-tablet, id "usb-tablet1" >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) usb_version = 2 (0x2) >2019-10-16 16:39:18,670 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) display = "" >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) head = 0 (0x0) >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) port = "1" >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) serial = "" >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) full-path = true >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) msos-desc = true >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) addr 0.1, port 1, speed 480, name QEMU USB Tablet, attached >2019-10-16 16:39:18,671 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: VGA, id "" >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) vgamem_mb = 16 (0x10) >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) mmio = true >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) qemu-extended-regs = true >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) addr = 02.0 >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) romfile = "vgabios-stdvga.bin" >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) rombar = 1 (0x1) >2019-10-16 16:39:18,672 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) multifunction = false >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) command_serr_enable = true >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-lnksta-dllla = true >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) x-pcie-extcap-init = true >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) class VGA controller, addr 00:02.0, pci id 1234:1111 (sub 1af4:1100) >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 0: mem at 0x80000000 [0x80ffffff] >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 2: mem at 0x81000000 [0x81000fff] >2019-10-16 16:39:18,673 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bar 6: mem at 0xffffffffffffffff [0xfffe] >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: spapr-vio-bridge, id "" >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) bus: spapr-vio >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) type spapr-vio-bus >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: spapr-vty, id "vty@30000000" >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) reg = 805306368 (0x30000000) >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) chardev = "serial_id_serial0" >2019-10-16 16:39:18,674 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) irq = 4103 (0x1007) >2019-10-16 16:39:18,675 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) dev: spapr-nvram, id "nvram@71000000" >2019-10-16 16:39:18,675 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) reg = 1895825408 (0x71000000) >2019-10-16 16:39:18,675 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) drive = "" >2019-10-16 16:39:18,675 qemu_monitor L1632 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) irq = 4098 (0x1002) >2019-10-16 16:39:18,675 nic_hotplug L0181 INFO | Check if new interface gets ip address >2019-10-16 16:40:48,785 virt_vm L1338 DEBUG| Attempting to log into 'avocado-vt-vm1' via serial console (timeout 90s) >2019-10-16 16:40:59,794 client L1126 DEBUG| Sending command: echo %OS% >2019-10-16 16:40:59,896 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:41:00,004 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '' -i >2019-10-16 16:41:00,130 client L1126 DEBUG| Sending command: test -d /sys/class/net/enp0s0 >2019-10-16 16:41:00,232 client L1126 DEBUG| Sending command: echo $? >2019-10-16 16:41:00,332 client L1126 DEBUG| Sending command: cat /sys/class/net/enp0s0/address >2019-10-16 16:41:00,437 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 || ip address show enp0s0 >2019-10-16 16:41:00,542 utils_net L3448 WARNI| No VM's NIC got IP address >2019-10-16 16:41:00,542 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:41:00,650 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '9a:b5:b6:b7:b8:b9' -i >2019-10-16 16:41:00,754 client L1162 DEBUG| Sending command (safe): test -f /etc/sysconfig/network-scripts/ifcfg-enp0s0 || echo 'DEVICE=enp0s0 >BOOTPROTO=dhcp >ONBOOT=yes' > /etc/sysconfig/network-scripts/ifcfg-enp0s0 >2019-10-16 16:41:00,856 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 up >2019-10-16 16:41:00,960 client L1162 DEBUG| Sending command (safe): dhclient -r >2019-10-16 16:41:01,166 client L1162 DEBUG| Sending command (safe): dhclient enp0s0 >2019-10-16 16:42:02,797 client L1162 DEBUG| Sending command (safe): arp -n|awk '/^[1-9]/{print "arp -d " $1}'|sh >2019-10-16 16:43:34,016 virt_vm L1338 DEBUG| Attempting to log into 'avocado-vt-vm1' via serial console (timeout 90s) >2019-10-16 16:43:45,025 client L1126 DEBUG| Sending command: echo %OS% >2019-10-16 16:43:45,126 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:43:45,232 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '' -i >2019-10-16 16:43:45,337 client L1126 DEBUG| Sending command: test -d /sys/class/net/enp0s0 >2019-10-16 16:43:45,438 client L1126 DEBUG| Sending command: echo $? >2019-10-16 16:43:45,539 client L1126 DEBUG| Sending command: cat /sys/class/net/enp0s0/address >2019-10-16 16:43:45,641 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 || ip address show enp0s0 >2019-10-16 16:43:45,746 utils_net L3448 WARNI| No VM's NIC got IP address >2019-10-16 16:43:45,746 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:43:45,850 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '9a:b5:b6:b7:b8:b9' -i >2019-10-16 16:43:45,957 client L1162 DEBUG| Sending command (safe): test -f /etc/sysconfig/network-scripts/ifcfg-enp0s0 || echo 'DEVICE=enp0s0 >BOOTPROTO=dhcp >ONBOOT=yes' > /etc/sysconfig/network-scripts/ifcfg-enp0s0 >2019-10-16 16:43:46,059 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 up >2019-10-16 16:43:46,162 client L1162 DEBUG| Sending command (safe): dhclient -r >2019-10-16 16:43:47,368 client L1162 DEBUG| Sending command (safe): dhclient enp0s0 >2019-10-16 16:44:48,959 client L1162 DEBUG| Sending command (safe): arp -n|awk '/^[1-9]/{print "arp -d " $1}'|sh >2019-10-16 16:46:20,180 virt_vm L1338 DEBUG| Attempting to log into 'avocado-vt-vm1' via serial console (timeout 90s) >2019-10-16 16:46:31,184 client L1126 DEBUG| Sending command: echo %OS% >2019-10-16 16:46:31,285 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:46:31,391 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '' -i >2019-10-16 16:46:31,496 client L1126 DEBUG| Sending command: test -d /sys/class/net/enp0s0 >2019-10-16 16:46:31,597 client L1126 DEBUG| Sending command: echo $? >2019-10-16 16:46:31,698 client L1126 DEBUG| Sending command: cat /sys/class/net/enp0s0/address >2019-10-16 16:46:31,801 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 || ip address show enp0s0 >2019-10-16 16:46:31,904 utils_net L3448 WARNI| No VM's NIC got IP address >2019-10-16 16:46:31,905 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:46:32,011 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '9a:b5:b6:b7:b8:b9' -i >2019-10-16 16:46:32,116 client L1162 DEBUG| Sending command (safe): test -f /etc/sysconfig/network-scripts/ifcfg-enp0s0 || echo 'DEVICE=enp0s0 >BOOTPROTO=dhcp >ONBOOT=yes' > /etc/sysconfig/network-scripts/ifcfg-enp0s0 >2019-10-16 16:46:32,219 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 up >2019-10-16 16:46:32,321 client L1162 DEBUG| Sending command (safe): dhclient -r >2019-10-16 16:46:33,532 client L1162 DEBUG| Sending command (safe): dhclient enp0s0 >2019-10-16 16:47:34,498 client L1162 DEBUG| Sending command (safe): arp -n|awk '/^[1-9]/{print "arp -d " $1}'|sh >2019-10-16 16:47:35,608 process L0626 INFO | Running 'arp -aen' >2019-10-16 16:47:35,611 process L0458 DEBUG| [stdout] Address HWtype HWaddress Flags Mask Iface >2019-10-16 16:47:35,611 process L0714 INFO | Command 'arp -aen' finished with 0 after 0.0011875629425048828s >2019-10-16 16:47:35,612 process L0458 DEBUG| [stdout] 192.168.122.202 (incomplete) virbr0 >2019-10-16 16:47:35,612 process L0458 DEBUG| [stdout] 192.168.122.21 (incomplete) virbr0 >2019-10-16 16:47:35,612 process L0458 DEBUG| [stdout] 192.168.122.83 ether 9a:46:47:48:49:4a C virbr0 >2019-10-16 16:47:35,612 process L0458 DEBUG| [stdout] 192.168.122.86 ether 9a:a8:a9:aa:ab:ac C virbr0 >2019-10-16 16:47:35,613 process L0458 DEBUG| [stdout] 10.0.1.254 ether e8:b6:c2:10:dc:81 C enP5p9s0f0 >2019-10-16 16:47:35,613 process L0458 DEBUG| [stdout] 192.168.122.19 (incomplete) virbr0 >2019-10-16 16:47:35,613 process L0458 DEBUG| [stdout] 192.168.122.4 (incomplete) virbr0 >2019-10-16 16:47:35,633 nic_hotplug L0079 DEBUG| Can't get IP address: >2019-10-16 16:47:35,633 nic_hotplug L0080 DEBUG| Cached IP: None >2019-10-16 16:47:35,634 nic_hotplug L0081 DEBUG| ARP table: Address HWtype HWaddress Flags Mask Iface >192.168.122.202 (incomplete) virbr0 >192.168.122.21 (incomplete) virbr0 >192.168.122.83 ether 9a:46:47:48:49:4a C virbr0 >192.168.122.86 ether 9a:a8:a9:aa:ab:ac C virbr0 >10.0.1.254 ether e8:b6:c2:10:dc:81 C enP5p9s0f0 >192.168.122.19 (incomplete) virbr0 >192.168.122.4 (incomplete) virbr0 >2019-10-16 16:47:35,634 nic_hotplug L0244 INFO | Re-enabling the primary link(s) >2019-10-16 16:47:35,635 nic_hotplug L0247 INFO | Reboot vm to verify it alive after hotunplug nic(s) >2019-10-16 16:47:35,635 error_context L0097 INFO | Context: rebooting 'avocado-vt-vm1' >2019-10-16 16:47:35,635 virt_vm L1153 DEBUG| Attempting to log into 'avocado-vt-vm1' (timeout 240s) >2019-10-16 16:51:35,931 virt_vm L1338 DEBUG| Attempting to log into 'avocado-vt-vm1' via serial console (timeout 240s) >2019-10-16 16:51:46,944 client L1126 DEBUG| Sending command: echo %OS% >2019-10-16 16:51:47,045 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:51:47,150 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '' -i >2019-10-16 16:51:47,254 client L1126 DEBUG| Sending command: test -d /sys/class/net/enp0s0 >2019-10-16 16:51:47,355 client L1126 DEBUG| Sending command: echo $? >2019-10-16 16:51:47,456 client L1126 DEBUG| Sending command: cat /sys/class/net/enp0s0/address >2019-10-16 16:51:47,559 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 || ip address show enp0s0 >2019-10-16 16:51:47,662 utils_net L3448 WARNI| No VM's NIC got IP address >2019-10-16 16:51:58,713 client L1126 DEBUG| Sending command: ipconfig || ifconfig >2019-10-16 16:51:59,215 client L1126 DEBUG| Sending command: ip route || route print >2019-10-16 16:51:59,318 virt_vm L1143 ERROR| Guest network status: > bash: ipconfig: command not found... >enp0s0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500 > ether 9a:b5:b6:b7:b8:b9 txqueuelen 1000 (Ethernet) > RX packets 393 bytes 20728 (20.2 KiB) > RX errors 0 dropped 54 overruns 0 frame 0 > TX packets 1 bytes 325 (325.0 B) > TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 > >lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536 > inet 127.0.0.1 netmask 255.0.0.0 > inet6 ::1 prefixlen 128 scopeid 0x10<host> > loop txqueuelen 1000 (Local Loopback) > RX packets 8 bytes 480 (480.0 B) > RX errors 0 dropped 0 overruns 0 frame 0 > TX packets 8 bytes 480 (480.0 B) > TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 > > >Guest route table: > >2019-10-16 16:51:59,321 stacktrace L0041 ERROR| >2019-10-16 16:51:59,321 stacktrace L0044 ERROR| Reproduced traceback from: /root/venv/lib/python3.6/site-packages/avocado_vt/test.py:438 >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 188, in run >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| test.fail("Hotplug nic can not get ip address") >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado/core/test.py", line 1030, in fail >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| raise exceptions.TestFail(message) >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| avocado.core.exceptions.TestFail: Hotplug nic can not get ip address >2019-10-16 16:51:59,325 stacktrace L0047 ERROR| >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| During handling of the above exception, another exception occurred: >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 249, in run >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| session = vm.reboot(serial=serial) >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:51:59,326 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4259, in reboot >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| _reboot() >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4232, in _shell_reboot >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| timeout=timeout) >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 1158, in wait_for_login >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| ip_version=self.ip_version) >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:51:59,327 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 858, in wait_for_get_address >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| ipaddr = self.get_address(nic_index, ip_version) >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 729, in get_address >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| return self._get_address(nic, ip_version) >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 767, in _get_address >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| raise VMIPAddressMissingError(mac, ip_version) >2019-10-16 16:51:59,328 stacktrace L0047 ERROR| virttest.virt_vm.VMIPAddressMissingError: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 >2019-10-16 16:51:59,328 stacktrace L0048 ERROR| >2019-10-16 16:51:59,336 virt_vm L1153 DEBUG| Attempting to log into 'avocado-vt-vm1' (timeout 240s) >2019-10-16 16:55:59,634 virt_vm L1338 DEBUG| Attempting to log into 'avocado-vt-vm1' via serial console (timeout 240s) >2019-10-16 16:56:10,644 client L1126 DEBUG| Sending command: echo %OS% >2019-10-16 16:56:10,751 client L1162 DEBUG| Sending command (safe): ifconfig -a >2019-10-16 16:56:10,857 client L1162 DEBUG| Sending command (safe): ip link | grep -B1 '' -i >2019-10-16 16:56:10,962 client L1126 DEBUG| Sending command: test -d /sys/class/net/enp0s0 >2019-10-16 16:56:11,063 client L1126 DEBUG| Sending command: echo $? >2019-10-16 16:56:11,165 client L1126 DEBUG| Sending command: cat /sys/class/net/enp0s0/address >2019-10-16 16:56:11,279 client L1162 DEBUG| Sending command (safe): ifconfig enp0s0 || ip address show enp0s0 >2019-10-16 16:56:11,388 utils_net L3448 WARNI| No VM's NIC got IP address >2019-10-16 16:56:22,443 client L1126 DEBUG| Sending command: ipconfig || ifconfig >2019-10-16 16:56:22,755 client L1126 DEBUG| Sending command: ip route || route print >2019-10-16 16:56:22,858 virt_vm L1143 ERROR| Guest network status: > bash: ipconfig: command not found... >enp0s0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500 > inet6 fe80::98b5:b6ff:feb7:b8b9 prefixlen 64 scopeid 0x20<link> > ether 9a:b5:b6:b7:b8:b9 txqueuelen 1000 (Ethernet) > RX packets 524 bytes 27540 (26.8 KiB) > RX errors 0 dropped 54 overruns 0 frame 0 > TX packets 1 bytes 325 (325.0 B) > TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 > >lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536 > inet 127.0.0.1 netmask 255.0.0.0 > inet6 ::1 prefixlen 128 scopeid 0x10<host> > loop txqueuelen 1000 (Local Loopback) > RX packets 8 bytes 480 (480.0 B) > RX errors 0 dropped 0 overruns 0 frame 0 > TX packets 8 bytes 480 (480.0 B) > TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 > > >Guest route table: > >2019-10-16 16:56:22,858 stacktrace L0041 ERROR| >2019-10-16 16:56:22,858 stacktrace L0044 ERROR| Reproduced traceback from: /root/venv/lib/python3.6/site-packages/avocado_vt/test.py:458 >2019-10-16 16:56:22,860 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:56:22,860 stacktrace L0047 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 188, in run >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| test.fail("Hotplug nic can not get ip address") >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado/core/test.py", line 1030, in fail >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| raise exceptions.TestFail(message) >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| avocado.core.exceptions.TestFail: Hotplug nic can not get ip address >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| During handling of the above exception, another exception occurred: >2019-10-16 16:56:22,861 stacktrace L0047 ERROR| >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 427, in _runTest >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| run_func(self, params, env) >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 249, in run >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| session = vm.reboot(serial=serial) >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:56:22,862 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4259, in reboot >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| _reboot() >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4232, in _shell_reboot >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| timeout=timeout) >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 1158, in wait_for_login >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| ip_version=self.ip_version) >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:56:22,863 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 858, in wait_for_get_address >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| ipaddr = self.get_address(nic_index, ip_version) >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 729, in get_address >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| return self._get_address(nic, ip_version) >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 767, in _get_address >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| raise VMIPAddressMissingError(mac, ip_version) >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| virttest.virt_vm.VMIPAddressMissingError: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 >2019-10-16 16:56:22,864 stacktrace L0047 ERROR| >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| During handling of the above exception, another exception occurred: >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/env_process.py", line 1101, in postprocess >2019-10-16 16:56:22,865 stacktrace L0047 ERROR| vm.verify_dmesg(dmesg_log_file=guest_dmesg_log_file) >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 955, in verify_dmesg >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| session = self.wait_for_login() >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 1158, in wait_for_login >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| ip_version=self.ip_version) >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 858, in wait_for_get_address >2019-10-16 16:56:22,866 stacktrace L0047 ERROR| ipaddr = self.get_address(nic_index, ip_version) >2019-10-16 16:56:22,867 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 729, in get_address >2019-10-16 16:56:22,867 stacktrace L0047 ERROR| return self._get_address(nic, ip_version) >2019-10-16 16:56:22,867 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 767, in _get_address >2019-10-16 16:56:22,867 stacktrace L0047 ERROR| raise VMIPAddressMissingError(mac, ip_version) >2019-10-16 16:56:22,867 stacktrace L0047 ERROR| virttest.virt_vm.VMIPAddressMissingError: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 >2019-10-16 16:56:22,867 stacktrace L0048 ERROR| >2019-10-16 16:56:22,867 test L0463 ERROR| Exception raised during postprocessing: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 >2019-10-16 16:56:22,871 stacktrace L0041 ERROR| >2019-10-16 16:56:22,871 stacktrace L0044 ERROR| Reproduced traceback from: /root/venv/lib/python3.6/site-packages/avocado/core/test.py:844 >2019-10-16 16:56:22,872 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:56:22,872 stacktrace L0047 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 188, in run >2019-10-16 16:56:22,872 stacktrace L0047 ERROR| test.fail("Hotplug nic can not get ip address") >2019-10-16 16:56:22,872 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado/core/test.py", line 1030, in fail >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| raise exceptions.TestFail(message) >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| avocado.core.exceptions.TestFail: Hotplug nic can not get ip address >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| During handling of the above exception, another exception occurred: >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| Traceback (most recent call last): >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 301, in runTest >2019-10-16 16:56:22,873 stacktrace L0047 ERROR| raise self.__status # pylint: disable=E0702 >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 252, in setUp >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| self._runTest() >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 427, in _runTest >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| run_func(self, params, env) >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 249, in run >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| session = vm.reboot(serial=serial) >2019-10-16 16:56:22,874 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4259, in reboot >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| _reboot() >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4232, in _shell_reboot >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| timeout=timeout) >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 1158, in wait_for_login >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| ip_version=self.ip_version) >2019-10-16 16:56:22,875 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| return fn(*args, **kwargs) >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 858, in wait_for_get_address >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| ipaddr = self.get_address(nic_index, ip_version) >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 729, in get_address >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| return self._get_address(nic, ip_version) >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 767, in _get_address >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| raise VMIPAddressMissingError(mac, ip_version) >2019-10-16 16:56:22,876 stacktrace L0047 ERROR| virttest.virt_vm.VMIPAddressMissingError: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 >2019-10-16 16:56:22,877 stacktrace L0048 ERROR| >2019-10-16 16:56:22,877 test L0849 DEBUG| Local variables: >2019-10-16 16:56:22,967 test L0852 DEBUG| -> self <class 'avocado_vt.test.VirtTest'>: 1-type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio >2019-10-16 16:56:22,967 test L0298 DEBUG| DATA (filename=output.expected) => NOT FOUND (data sources: variant, test, file) >2019-10-16 16:56:22,968 test L0298 DEBUG| DATA (filename=stdout.expected) => NOT FOUND (data sources: variant, test, file) >2019-10-16 16:56:22,968 test L0298 DEBUG| DATA (filename=stderr.expected) => NOT FOUND (data sources: variant, test, file) >2019-10-16 16:56:22,981 test L0997 ERROR| Traceback (most recent call last): > >2019-10-16 16:56:22,981 test L0997 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 188, in run > test.fail("Hotplug nic can not get ip address") > >2019-10-16 16:56:22,981 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado/core/test.py", line 1030, in fail > raise exceptions.TestFail(message) > >2019-10-16 16:56:22,981 test L0997 ERROR| avocado.core.exceptions.TestFail: Hotplug nic can not get ip address > >2019-10-16 16:56:22,981 test L0997 ERROR| >During handling of the above exception, another exception occurred: > > >2019-10-16 16:56:22,981 test L0997 ERROR| Traceback (most recent call last): > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado/core/test.py", line 938, in _run_avocado > raise test_exception > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado/core/test.py", line 839, in _run_avocado > testMethod() > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 301, in runTest > raise self.__status # pylint: disable=E0702 > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 252, in setUp > self._runTest() > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/avocado_vt/test.py", line 427, in _runTest > run_func(self, params, env) > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/var/lib/avocado/data/avocado-vt/test-providers.d/downloads/io-github-autotest-qemu/qemu/tests/nic_hotplug.py", line 249, in run > session = vm.reboot(serial=serial) > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn > return fn(*args, **kwargs) > >2019-10-16 16:56:22,982 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4259, in reboot > _reboot() > >2019-10-16 16:56:22,983 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/qemu_vm.py", line 4232, in _shell_reboot > timeout=timeout) > >2019-10-16 16:56:22,983 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 1158, in wait_for_login > ip_version=self.ip_version) > >2019-10-16 16:56:22,983 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/error_context.py", line 135, in new_fn > return fn(*args, **kwargs) > >2019-10-16 16:56:22,983 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 858, in wait_for_get_address > ipaddr = self.get_address(nic_index, ip_version) > >2019-10-16 16:56:22,983 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 729, in get_address > return self._get_address(nic, ip_version) > >2019-10-16 16:56:22,983 test L0997 ERROR| File "/root/venv/lib/python3.6/site-packages/virttest/virt_vm.py", line 767, in _get_address > raise VMIPAddressMissingError(mac, ip_version) > >2019-10-16 16:56:22,983 test L0997 ERROR| virttest.virt_vm.VMIPAddressMissingError: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 > >2019-10-16 16:56:22,984 test L1012 ERROR| ERROR 1-type_specific.io-github-autotest-qemu.nic_hotplug.vhost_nic.nic_virtio -> VMIPAddressMissingError: No ipv4 DHCP lease for MAC 9a:b5:b6:b7:b8:b9 >2019-10-16 16:56:22,984 test L1001 INFO | >2019-10-16 16:56:23,163 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'qmp_capabilities' >2019-10-16 16:56:23,163 qemu_monitor L1700 DEBUG| Send command: {'execute': 'qmp_capabilities', 'id': 'Z0Gu9dxH'} >2019-10-16 16:56:23,273 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.catch_monitor) Sending command 'qmp_capabilities' >2019-10-16 16:56:23,273 qemu_monitor L1700 DEBUG| Send command: {'execute': 'qmp_capabilities', 'id': '8uLzzryB'} >2019-10-16 16:56:23,316 qemu_vm L3248 DEBUG| Destroying VM avocado-vt-vm1 (PID 75521) >2019-10-16 16:56:23,317 qemu_vm L3262 DEBUG| Ending VM avocado-vt-vm1 process (monitor) >2019-10-16 16:56:23,317 qemu_monitor L0344 DEBUG| (monitor avocado-vt-vm1.qmpmonitor1) Sending command 'quit' >2019-10-16 16:56:23,317 qemu_monitor L1700 DEBUG| Send command: {'execute': 'quit', 'id': '8X61ILRw'} >2019-10-16 16:56:23,559 client L0605 INFO | [qemu output] (Process terminated with status 0) >2019-10-16 16:56:23,818 qemu_vm L3274 DEBUG| VM avocado-vt-vm1 down (monitor) >2019-10-16 16:56:24,177 utils_net L1131 DEBUG| Host does not support OpenVSwitch: Command 'ovs-vswitchd' could not be found in any of the PATH dirs: ['/usr/sbin', '/usr/local/sbin', '/root/bin', '/sbin', '/usr/local/bin', '/usr/libexec', '/usr/bin', '/root/venv/bin', '/bin'] >2019-10-16 16:56:24,180 job L0495 INFO | Test results available in /root/avocado/job-results/job-2019-10-16T16.38-6beb570 >2019-10-16 16:56:24,181 extension L0189 DEBUG| found extension EntryPoint.parse('json = avocado.plugins.jsonresult:JSONResult') >2019-10-16 16:56:24,182 extension L0189 DEBUG| found extension EntryPoint.parse('xunit = avocado.plugins.xunit:XUnitResult') >2019-10-16 16:56:24,182 extension L0189 DEBUG| found extension EntryPoint.parse('zip_archive = avocado.plugins.archive:Archive')
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=1626925">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1762906
: 1626925 |
1626926
|
1627242