[Xen-users] HVM Migration issues

["Shuveb Hussain" <shuveb@xxxxxxxxx>]

Hi,

I am running Xen 3.1 on Ubuntu Gutsy, AMD64 distro. When I try to
migrate a Windows XP domain, the domain migrates, but does not get
restored in the destination node. The block device is provided by
open-iscsi/iet and is accessible from both nodes. Below are logs from
both source and destination nodes:

Please throw some light on where the problem could be:

TIA,
Regards,
-- 
Shuveb Hussain.
Money has nothing to do with Happiness,
But, Poverty has a lot to do with Sorrow.
Company: www.binarykarma.com
Blog: www.binarykarma.org


Source node:
---------------------------------------
[2007-10-19 18:02:39 4489] DEBUG (__init__:1094) [xc_save]:
/usr/lib64/xen/bin/xc_save 29 8 0 0 5
[2007-10-19 18:02:39 4489] DEBUG (__init__:1094) Storing domain
details: {'console/port': '3', 'name': 'migrating-WinXP',
'console/limit': '1048576', 'vm':
'/vm/6a776e6e-589d-c01c-df1d-bd50eefefe3e', 'domid': '8',
'image/suspend-cancel': '1', 'cpu/0/availability': 'online',
'memory/target': '131072',
'control/platform-feature-multiprocessor-suspend': '1',
'store/ring-ref': '32766', 'store/port': '2'}
 1: sent 966903, skipped 16105, delta 9918ms, dom0 48%, target 68%,
sent 3194Mb/s, dirtied 59Mb/s 18117 pages
 2: sent 17470, skipped 632, delta 6237ms, dom0 37%, target 68%, sent
91Mb/s, dirtied 5Mb/s 1038 pages
 3: sent 995, skipped 28, delta 211ms, dom0 32%, target 39%, sent
154Mb/s, dirtied 92Mb/s 594 pages
 4: sent 546, skipped 27, delta 175ms, dom0 35%, target 83%, sent
102Mb/s, dirtied 9Mb/s 53 pages
 5: sent 49, skipped 5, delta 48ms, dom0 8%, target 37%, sent 33Mb/s,
dirtied 9Mb/s 14 pages
 6: sent 9, skipped 5, Start last iteration094) Saving memory pages: iter 6   0%
[2007-10-19 18:02:56 4489] DEBUG (__init__:1094) suspend
[2007-10-19 18:02:56 4489] DEBUG (__init__:1094) In saveInputHandler suspend
[2007-10-19 18:02:56 4489] DEBUG (__init__:1094) Suspending 8 ...
[2007-10-19 18:02:56 4489] DEBUG (__init__:1094)
XendDomainInfo.shutdown(suspend)
[2007-10-19 18:02:56 4489] DEBUG (__init__:1094)
XendDomainInfo.handleShutdownWatch
[2007-10-19 18:02:56 4489] INFO (__init__:1094) HVM save:remote shutdown dom 8!
[2007-10-19 18:02:56 4489] INFO (__init__:1094) Domain has shutdown:
name=migrating-WinXP id=8 reason=suspend.
[2007-10-19 18:02:56 4489] INFO (__init__:1094) Domain 8 suspended.
[2007-10-19 18:02:56 4489] INFO (__init__:1094) release_devices for hvm domain
[2007-10-19 18:02:56 4489] INFO (__init__:1094) use sigusr1 to signal qemu 24614
[2007-10-19 18:02:57 4489] INFO (__init__:1094) SUSPEND shinfo 000fffff
[2007-10-19 18:02:57 4489] DEBUG (__init__:1094) Written done
[2007-10-19 18:02:57 4489] INFO (__init__:1094) delta 1231ms, dom0
24%, target 9%, sent 0Mb/s, dirtied 2Mb/s 101 pages
 7: sent 101, skipped 0, delta 23ms, dom0 95%, target 0%, sent
143Mb/s, dirtied 143Mb/s 101 pages
[2007-10-19 18:02:57 4489] INFO (__init__:1094) Total pages sent= 986073 (0.94x)
[2007-10-19 18:02:57 4489] INFO (__init__:1094) (of which 0 were fixups)
[2007-10-19 18:02:57 4489] INFO (__init__:1094) All memory is saved
[2007-10-19 18:02:57 4489] INFO (__init__:1094) Save exit rc=0
[2007-10-19 18:02:58 4489] DEBUG (__init__:1094) XendDomainInfo.destroyDomain(8)


Destination node:
---------------------------------------------------
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094)
XendDomainInfo.restore(['domain', ['domid', '8'], ['on_crash',
'restart'], ['uuid', '6a776e6e-589d-c01c-df1d-bd50eefefe3e'],
['bootloader_args'], ['vcpus', '1'], ['name', 'WinXP'],
['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['bootloader'],
['maxmem', '128'], ['memory', '128'], ['shadow_memory', '2'],
['vcpu_avail', '1'], ['cpu_weight', '256'], ['cpu_cap', '0'],
['features'], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'],
['start_time', '1192797102.39'], ['cpu_time', '28.947338176'],
['online_vcpus', '1'], ['image', ['hvm', ['kernel',
'/usr/lib/xen/boot/hvmloader'], ['acpi', '1'], ['apic', '1'], ['boot',
'c'], ['device_model', '/usr/lib/xen/bin/qemu-dm'], ['pae', '1'],
['rtc_timeoffset', '0'], ['notes', ['SUSPEND_CANCEL', '1']]]],
['status', '2'], ['state', 'r-----'], ['store_mfn', '32766'],
['device', ['vif', ['bridge', 'xenbr0'], ['uuid',
'a7a84908-0f93-ca41-8964-b6f78ad8e97b'], ['script', 'vif-bridge'],
['mac', '00:16:3e:42:2c:e6'], ['type', 'netfront'], ['backend',
'0']]], ['device', ['vbd', ['uname',
'phy:/dev/vm/XP_Disk_119105605246'], ['uuid',
'7bb498e4-e409-813e-4b7b-3fe5c4df8c9e'], ['mode', 'w'], ['dev',
'hda:disk'], ['backend', '0'], ['bootable', '1']]], ['device',
['vkbd', ['backend', '0']]], ['device', ['vfb', ['type', 'vnc'],
['uuid', 'c06b4b87-739e-0e06-f4a3-6612dd45aa5e'], ['location',
'localhost:5902']]], ['device', ['console', ['protocol', 'vt100'],
['location', '3'], ['uuid',
'87bd6940-71c1-f8e1-1e02-2434dbbda9e2']]]])
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) XendDomainInfo.constructDomain
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) Balloon: 135104 KiB
free; need 2048; done.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) Adding Domain: 5
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) Storing VM details:
{'on_xend_stop': 'ignore', 'shadow_memory': '2', 'uuid':
'6a776e6e-589d-c01c-df1d-bd50eefefe3e', 'on_reboot': 'restart',
'start_time': '1192797102.39', 'on_poweroff': 'destroy',
'on_xend_start': 'ignore', 'on_crash': 'restart',
'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'image':
'(hvm (kernel /usr/lib/xen/boot/hvmloader) (acpi 1) (apic 1) (boot c)
(device_model /usr/lib/xen/bin/qemu-dm) (pae 1) (rtc_timeoffset 0)
(notes (SUSPEND_CANCEL 1)))', 'name': 'WinXP'}
[2007-10-19 18:02:39 10530] INFO (__init__:1094) createDevice: vkbd :
{'uuid': '95bdbf44-0cdc-67ad-73b4-ad9db4bb20d4', 'backend': '0'}
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'state': '1', 'backend-id': '0', 'backend':
'/local/domain/0/backend/vkbd/5/0'} to /local/domain/5/device/vkbd/0.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'frontend-id': '5', 'domain': 'WinXP', 'frontend':
'/local/domain/5/device/vkbd/0', 'state': '1', 'online': '1'} to
/local/domain/0/backend/vkbd/5/0.
[2007-10-19 18:02:39 10530] INFO (__init__:1094) createDevice: vfb :
{'other_config': {'type': 'vnc'}, 'type': 'vnc', 'uuid':
'c06b4b87-739e-0e06-f4a3-6612dd45aa5e', 'location': 'localhost:5902'}
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'state': '1', 'backend-id': '0', 'backend':
'/local/domain/0/backend/vfb/5/0'} to /local/domain/5/device/vfb/0.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'domain': 'WinXP', 'frontend':
'/local/domain/5/device/vfb/0', 'uuid':
'c06b4b87-739e-0e06-f4a3-6612dd45aa5e', 'state': '1', 'location':
'localhost:5902', 'online': '1', 'frontend-id': '5', 'type': 'vnc'} to
/local/domain/0/backend/vfb/5/0.
[2007-10-19 18:02:39 10530] INFO (__init__:1094) createDevice: console
: {'protocol': 'vt100', 'location': '3', 'uuid':
'87bd6940-71c1-f8e1-1e02-2434dbbda9e2'}
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'state': '1', 'backend-id': '0', 'backend':
'/local/domain/0/backend/console/5/0'} to
/local/domain/5/device/console/0.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'domain': 'WinXP', 'protocol': 'vt100', 'uuid':
'87bd6940-71c1-f8e1-1e02-2434dbbda9e2', 'frontend':
'/local/domain/5/device/console/0', 'state': '1', 'location': '3',
'online': '1', 'frontend-id': '5'} to
/local/domain/0/backend/console/5/0.
[2007-10-19 18:02:39 10530] INFO (__init__:1094) createDevice: vbd :
{'uuid': '7bb498e4-e409-813e-4b7b-3fe5c4df8c9e', 'bootable': 1,
'driver': 'paravirtualised', 'dev': 'hda:disk', 'uname':
'phy:/dev/vm/XP_Disk_119105605246', 'mode': 'w', 'backend': '0'}
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'backend-id': '0', 'virtual-device': '768', 'device-type':
'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/5/768'}
to /local/domain/5/device/vbd/768.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'domain': 'WinXP', 'frontend':
'/local/domain/5/device/vbd/768', 'uuid':
'7bb498e4-e409-813e-4b7b-3fe5c4df8c9e', 'dev': 'hda', 'state': '1',
'params': '/dev/vm/XP_Disk_119105605246', 'mode': 'w', 'online': '1',
'frontend-id': '5', 'type': 'phy'} to
/local/domain/0/backend/vbd/5/768.
[2007-10-19 18:02:39 10530] INFO (__init__:1094) createDevice: vif :
{'bridge': 'xenbr0', 'uuid': 'a7a84908-0f93-ca41-8964-b6f78ad8e97b',
'script': 'vif-bridge', 'mac': '00:16:3e:42:2c:e6', 'type':
'netfront', 'backend': '0'}
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'backend-id': '0', 'mac': '00:16:3e:42:2c:e6', 'handle': '0',
'state': '1', 'backend': '/local/domain/0/backend/vif/5/0'} to
/local/domain/5/device/vif/0.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) DevController:
writing {'bridge': 'xenbr0', 'domain': 'WinXP', 'handle': '0', 'uuid':
'a7a84908-0f93-ca41-8964-b6f78ad8e97b', 'script':
'/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend':
'/local/domain/5/device/vif/0', 'mac': '00:16:3e:42:2c:e6', 'online':
'1', 'frontend-id': '5', 'type': 'netfront'} to
/local/domain/0/backend/vif/5/0.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) Storing domain
details: {'console/port': '3', 'name': 'WinXP', 'console/limit':
'1048576', 'image/suspend-cancel': '1', 'domid': '5', 'vm':
'/vm/6a776e6e-589d-c01c-df1d-bd50eefefe3e', 'cpu/0/availability':
'online', 'memory/target': '131072',
'control/platform-feature-multiprocessor-suspend': '1', 'store/port':
'2'}
[2007-10-19 18:02:39 10530] INFO (__init__:1094) restore hvm domain 5,
apic=1, pae=1
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) restore:shadow=0x2,
_static_max=0x8000000, _static_min=0x0,
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) Balloon: 134068 KiB
free; need 133120; done.
[2007-10-19 18:02:39 10530] DEBUG (__init__:1094) [xc_restore]:
/usr/lib64/xen/bin/xc_restore 26 5 2 3 1 1 1
[2007-10-19 18:02:39 10530] INFO (__init__:1094) xc_domain_restore
start: p2m_size = 100000
[2007-10-19 18:02:39 10530] INFO (__init__:1094) Reloading memory pages:   0%
[2007-10-19 18:02:57 10530] INFO (__init__:1094) Received all pages (0 races)
[2007-10-19 18:02:57 10530] INFO (__init__:1094) Restore exit with rc=0
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) store-mfn 32766
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) XendDomainInfo.completeRestore
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: boot, val: c
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: fda, val: None
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: fdb, val: None
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: soundhw, val: None
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: localtime, val: None
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: serial, val: None
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: std-vga, val: None
[2007-10-19 18:02:57 10530] DEBUG (__init__:1094) args: isa, val: None
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) args: acpi, val: 1
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) args: usb, val: None
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) args: usbdevice, val: None
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) args: k, val: None
[2007-10-19 18:02:58 10530] INFO (__init__:1094) spawning device
models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d',
'5', '-vcpus', '1', '-boot', 'c', '-acpi', '-domain-name', 'WinXP',
'-vncunused', '-vnclisten', '0.0.0.0', '-loadvm',
'/tmp/xen.qemu-dm.5']
[2007-10-19 18:02:58 10530] INFO (__init__:1094) device model pid: 12885
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Storing domain
details: {'console/port': '3', 'name': 'WinXP', 'console/limit':
'1048576', 'vm': '/vm/6a776e6e-589d-c01c-df1d-bd50eefefe3e', 'domid':
'5', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online',
'memory/target': '131072',
'control/platform-feature-multiprocessor-suspend': '1',
'store/ring-ref': '32766', 'store/port': '2'}
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094)
XendDomainInfo.handleShutdownWatch
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094)
XendDomainInfo.completeRestore done
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices vif.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for 0.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094)
hotplugStatusCallback /local/domain/0/backend/vif/5/0/hotplug-status.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) hotplugStatusCallback 1.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices usb.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices vbd.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for 768.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094)
hotplugStatusCallback
/local/domain/0/backend/vbd/5/768/hotplug-status.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) hotplugStatusCallback 1.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices irq.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices vkbd.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices vfb.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) skip waiting for HVM vfb
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices console.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for 0.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices pci.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices ioports.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices tap.
[2007-10-19 18:02:58 10530] DEBUG (__init__:1094) Waiting for devices vtpm.

_______________________________________________
Xen-users mailing list
Xen-users@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-users