search

autotest / virt-test

Linux Virtualization Tests
Other
97 stars 139 forks source link

VMAddressVerificationError: #80

Closed lynnqy closed 11 years ago

lynnqy commented 12 years ago

VMAddressVerificationError: Could not verify DHCP lease: 9a:4f:50:51:52:53 --> 192.168.122.100# My system is Fedora 15.32,the guest os is also Fedora 15.32.Should I change the settings of base.cfg?

lmr commented 11 years ago

This error usually means the guest did not boot (kernel panic, non bootable hard drive, etc). Opening a vnc connection or looking at the video/screenshots produced by the virt-tests should tell you exactly what happened.

jlegido commented 10 years ago

Hi.

I'm start learning virt-test, but I'm experiencing random qemu type_specific.migrate.default.tcp tests. On the same environment I run the test, and it randomly fails with same error:

VMAddressVerificationError: Could not verify DHCP lease

Let me copy/paste the lines where the test are passed and failed:

FAILED 18:05:30 DEBUG| Could not verify DHCP lease: 9a:e9:ea:eb:ec:ed --> 10.2.3.201 18:06:00 DEBUG| Destroying VM virt-tests-vm1 (PID 17407) 18:06:00 DEBUG| Shutting down VM virt-tests-vm1 (shell) 18:06:00 DEBUG| Could not verify DHCP lease: 9a:e9:ea:eb:ec:ed --> 10.2.3.201 18:06:00 DEBUG| VM virt-tests-vm1 failed to go down (shell) 18:06:00 DEBUG| Ending VM virt-tests-vm1 process (monitor) 18:06:00 INFO | [qemu output](Process terminated with status 0) 18:06:01 DEBUG| VM virt-tests-vm1 down (monitor) 18:06:01 DEBUG| Checking image file /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2 18:06:01 DEBUG| Running '/usr/bin/qemu-img info /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2' 18:06:01 DEBUG| Running '/usr/bin/qemu-img check /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2' 18:06:01 DEBUG| Copying /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2.backup -> /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2 18:06:02 ERROR| 18:06:02 ERROR| Traceback (most recent call last): 18:06:02 ERROR| File "/srv/no_data/virt-test/virttest/standalone_test.py", line 196, in run_once 18:06:02 ERROR| run_func(self, params, env) 18:06:02 ERROR| File "/srv/no_data/virt-test/qemu/tests/migration.py", line 191, in run 18:06:02 ERROR| session2 = vm.wait_for_login(timeout=30) 18:06:02 ERROR| File "/srv/no_data/virt-test/virttest/virt_vm.py", line 959, in wait_for_login 18:06:02 ERROR| return self.login(nic_index, internal_timeout, username, password) 18:06:02 ERROR| File "/usr/lib/pymodules/python2.7/autotest/client/shared/error.py", line 138, in new_fn 18:06:02 ERROR| return fn(_args, *_kwargs) 18:06:02 ERROR| File "/srv/no_data/virt-test/virttest/virt_vm.py", line 902, in login 18:06:02 ERROR| address = self.get_address(nic_index) 18:06:02 ERROR| File "/srv/no_data/virt-test/virttest/virt_vm.py", line 683, in get_address 18:06:02 ERROR| raise VMAddressVerificationError(nic.mac, arp_ip) 18:06:02 ERROR| VMAddressVerificationError: Could not verify DHCP lease: 9a:e9:ea:eb:ec:ed --> 10.2.3.201 18:06:02 ERROR| 18:06:02 ERROR| FAIL type_specific.migrate.default.tcp -> VMAddressVerificationError: Could not verify DHCP lease: 9a:e9:ea:eb:ec:ed --> 10.2.3.201 18:06:02 INFO |

PASSED

17:45:31 DEBUG| Could not verify DHCP lease: 9a:18:19:1a:1b:1c --> 10.2.3.229 17:45:37 DEBUG| Found/Verified IP 10.2.3.229 for VM virt-tests-vm1 NIC 0 17:45:37 DEBUG| Login command: 'ssh -o UserKnownHostsFile=/dev/null -o PreferredAuthentications=password -p 22 root@10.2.3.229' 17:45:39 INFO | Logged in after migration 17:45:39 DEBUG| Sending command: pgrep tcpdump 17:45:39 DEBUG| Sending command: echo $? 17:45:39 DEBUG| Sending command: help 17:45:39 DEBUG| Sending command: pkill -9 tcpdump 17:45:39 DEBUG| Sending command: echo $? 17:45:39 DEBUG| Destroying VM virt-tests-vm1 (PID 13666) 17:45:39 DEBUG| Shutting down VM virt-tests-vm1 (shell) 17:45:39 DEBUG| Found/Verified IP 10.2.3.229 for VM virt-tests-vm1 NIC 0 17:45:39 DEBUG| Login command: 'ssh -o UserKnownHostsFile=/dev/null -o PreferredAuthentications=password -p 22 root@10.2.3.229' 17:45:42 INFO | [qemu output](Process terminated with status 0) 17:45:43 DEBUG| VM virt-tests-vm1 down (shell) 17:45:43 DEBUG| Checking image file /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2 17:45:43 DEBUG| Running '/usr/bin/qemu-img info /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2' 17:45:43 DEBUG| Running '/usr/bin/qemu-img check /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2' 17:45:43 DEBUG| Copying /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2.backup -> /srv/no_data/virt-test/shared/data/images/jeos-19-64.qcow2 17:45:45 INFO | PASS type_specific.migrate.default.tcp 17:45:45 INFO |

I'm doing a manual test to see if I can ssh to the VM:

(screen 1)

tail -F virt-test/logs/latest/debug.log | grep "DEBUG| Could not verify DHCP lease"

Once I see a line like:

18:42:32 DEBUG| Could not verify DHCP lease: 9a:c1:c2:c3:c4:c5 --> 10.2.3.223

I hit, in another screen:

ssh $(cat virt-test/logs/latest/debug.log | grep "DEBUG| Could not verify DHCP lease" | awk '{print $10}' | tail -n 1)

And usually I get the ssh prompt.

So summarizing:

  1. The test randomly fails (not able yet to determine a root cause)
  2. The error comes just below, but not always, the following line appears in the log:

DEBUG| Could not verify DHCP lease:

If there's anything else that I can test just le know and I'll paste the result. Otherwise please consider re-opening this ticket.

Many thanks