[2010-11-22 18:03:30 7592] INFO (SrvDaemon:332) Xend Daemon started
[2010-11-22 18:03:30 7592] INFO (SrvDaemon:336) Xend changeset: unavailable.
[2010-11-22 18:03:31 7592] DEBUG (XendDomainInfo:149) XendDomainInfo.recreate({'max_vcpu_id': 3, 'cpu_time': 185702931522L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 255, 'dying': 0, 'online_vcpus': 4, 'domid': 0, 'paused': 0, 'crashed': 0, 'running': 1, 'maxmem_kb': 17179869180L, 'shutdown': 0, 'mem_kb': 524288L, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0, 'name': 'Domain-0'})
[2010-11-22 18:03:31 7592] INFO (XendDomainInfo:167) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
[2010-11-22 18:03:31 7592] DEBUG (XendDomain:464) Adding Domain: 0
[2010-11-22 18:03:31 7592] DEBUG (XendDomain:398) number of vcpus to use is 0
[2010-11-22 18:03:31 7592] DEBUG (XendDomainInfo:1891) XendDomainInfo.handleShutdownWatch
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: VBD.set_device not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: VBD.set_type not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: session.get_all_records not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: event.get_record not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: event.get_all not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: VIF.set_device not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: VIF.set_MAC not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: VIF.set_MTU not found
[2010-11-22 18:03:31 7592] WARNING (XendAPI:705) API call: debug.get_all not found
[2010-11-22 18:03:31 7592] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xen-api.sock; authentication has been disabled for this server.
[2010-11-22 18:03:31 7592] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
[2010-11-22 18:06:44 7592] DEBUG (XendDomainInfo:235) XendDomainInfo.restore(['domain', ['domid', '5'], ['cpu_weight', '256'], ['cpu_cap', '0'], ['bootloader'], ['on_crash', 'restart'], ['uuid', '8a1fce68-d1ca-63cf-30a7-24cf2ed548f7'], ['bootloader_args'], ['vcpus', '1'], ['description'], ['name', 'WinXP_HVM_Migrate'], ['cpus', [[]]], ['on_reboot', 'restart'], ['on_poweroff', 'destroy'], ['maxmem', '256'], ['memory', '256'], ['shadow_memory', '3'], ['vcpu_avail', '1'], ['features'], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['start_time', '1290420248.36'], ['cpu_time', '0.0'], ['online_vcpus', '1'], ['image', ['hvm', ['kernel'], ['superpages', '0'], ['videoram', '4'], ['hpet', '0'], ['stdvga', '1'], ['vnclisten', '0.0.0.0'], ['loader', '/usr/lib64/xen/boot/hvmloader'], ['xen_platform_pci', '1'], ['rtc_timeoffset', '0'], ['pci', []], ['hap', '1'], ['localtime', '0'], ['timer_mode', '1'], ['vncdisplay', '6'], ['pci_msitranslate', '1'], ['oos', '1'], ['apic', '1'], ['sdl', '0'], ['usbdevice', 'tablet'], ['display', 'localhost:11.0'], ['vpt_align', '1'], ['serial', 'pty'], ['vncunused', '1'], ['soundhw', 'ac97'], ['boot', 'dca'], ['pae', '1'], ['viridian', '0'], ['acpi', '1'], ['vnc', '1'], ['nographic', '0'], ['nomigrate', '0'], ['usb', '0'], ['tsc_mode', '0'], ['guest_os_type', 'default'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['pci_power_mgmt', '0'], ['xauthority', '/home/crilit/.Xauthority'], ['isa', '0'], ['notes', ['SUSPEND_CANCEL', '1']]]], ['status', '2'], ['state', '--p---'], ['store_mfn', '1044476'], ['device', ['vif', ['bridge', 'xenbr0'], ['uuid', 'd02a38fb-c731-2ff7-2496-973be59caeb4'], ['script', '/etc/xen/scripts/vif-bridge'], ['mac', '00:16:3e:54:e1:54'], ['type', 'ioemu'], ['backend', '0']]], ['device', ['console', ['protocol', 'vt100'], ['location', '3'], ['uuid', '44036f50-8832-a662-c587-dda32d49d0e6']]], ['device', ['vbd', ['uuid', '61ba0cc5-b6fe-4a6d-431c-ffd4df5c93af'], ['bootable', '1'], ['dev', 'hda:disk'], ['uname', 'phy:/dev/vg_xen/lv_xp_vm'], ['mode', 'w'], ['backend', '0'], ['VDI']]], ['device', ['vfb', ['vncunused', '1'], ['vnc', '1'], ['uuid', 'a794b889-6d20-bd59-8ab0-6ddce513fc26'], ['vnclisten', '0.0.0.0'], ['vncdisplay', '6'], ['location', '0.0.0.0:5906']]], ['change_home_server', 'False']])
[2010-11-22 18:06:44 7592] DEBUG (XendDomainInfo:2508) XendDomainInfo.constructDomain
[2010-11-22 18:06:44 7592] DEBUG (balloon:220) Balloon: 3269620 KiB free; need 16384; done.
[2010-11-22 18:06:44 7592] DEBUG (XendDomain:464) Adding Domain: 6
[2010-11-22 18:06:44 7592] DEBUG (XendDomainInfo:3400) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '3', 'uuid': '8a1fce68-d1ca-63cf-30a7-24cf2ed548f7', 'on_reboot': 'restart', 'start_time': '1290420248.36', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': '(hvm (kernel ) (superpages 0) (videoram 4) (hpet 0) (stdvga 1) (vnclisten 0.0.0.0) (loader /usr/lib64/xen/boot/hvmloader) (xen_platform_pci 1) (rtc_timeoffset 0) (pci ()) (hap 1) (localtime 0) (timer_mode 1) (vncdisplay 6) (pci_msitranslate 1) (oos 1) (apic 1) (sdl 0) (usbdevice tablet) (display localhost:11.0) (vpt_align 1) (serial pty) (vncunused 1) (soundhw ac97) (boot dca) (pae 1) (viridian 0) (acpi 1) (vnc 1) (nographic 0) (nomigrate 0) (usb 0) (tsc_mode 0) (guest_os_type default) (device_model /usr/lib64/xen/bin/qemu-dm) (pci_power_mgmt 0) (xauthority /home/crilit/.Xauthority) (isa 0) (notes (SUSPEND_CANCEL 1)))', 'name': 'WinXP_HVM_Migrate'}
[2010-11-22 18:06:44 7592] INFO (XendDomainInfo:2367) createDevice: console : {'protocol': 'vt100', 'location': '3', 'uuid': '44036f50-8832-a662-c587-dda32d49d0e6'}
[2010-11-22 18:06:44 7592] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/6/0'} to /local/domain/6/device/console/0.
[2010-11-22 18:06:44 7592] DEBUG (DevController:97) DevController: writing {'domain': 'WinXP_HVM_Migrate', 'frontend': '/local/domain/6/device/console/0', 'uuid': '44036f50-8832-a662-c587-dda32d49d0e6', 'frontend-id': '6', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/6/0.
[2010-11-22 18:06:44 7592] INFO (XendDomainInfo:2367) createDevice: vfb : {'vncunused': '1', 'other_config': {'vncunused': '1', 'vnclisten': '0.0.0.0', 'vnc': '1', 'vncdisplay': '6'}, 'vnc': '1', 'uuid': 'a794b889-6d20-bd59-8ab0-6ddce513fc26', 'vnclisten': '0.0.0.0', 'vncdisplay': '6', 'location': '0.0.0.0:5906'}
[2010-11-22 18:06:44 7592] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/6/0'} to /local/domain/6/device/vfb/0.
[2010-11-22 18:06:44 7592] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'WinXP_HVM_Migrate', 'vnc': '1', 'uuid': 'a794b889-6d20-bd59-8ab0-6ddce513fc26', 'frontend-id': '6', 'vnclisten': '0.0.0.0', 'vncdisplay': '6', 'state': '1', 'location': '0.0.0.0:5906', 'online': '1', 'frontend': '/local/domain/6/device/vfb/0'} to /local/domain/0/backend/vfb/6/0.
[2010-11-22 18:06:44 7592] INFO (XendDomainInfo:2367) createDevice: vbd : {'uuid': '61ba0cc5-b6fe-4a6d-431c-ffd4df5c93af', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda:disk', 'uname': 'phy:/dev/vg_xen/lv_xp_vm', 'mode': 'w', 'backend': '0'}
[2010-11-22 18:06:44 7592] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/6/768'} to /local/domain/6/device/vbd/768.
[2010-11-22 18:06:44 7592] DEBUG (DevController:97) DevController: writing {'domain': 'WinXP_HVM_Migrate', 'frontend': '/local/domain/6/device/vbd/768', 'uuid': '61ba0cc5-b6fe-4a6d-431c-ffd4df5c93af', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/dev/vg_xen/lv_xp_vm', 'mode': 'w', 'online': '1', 'frontend-id': '6', 'type': 'phy'} to /local/domain/0/backend/vbd/6/768.
[2010-11-22 18:06:44 7592] INFO (XendDomainInfo:2367) createDevice: vif : {'bridge': 'xenbr0', 'uuid': 'd02a38fb-c731-2ff7-2496-973be59caeb4', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:54:e1:54', 'type': 'ioemu', 'backend': '0'}
[2010-11-22 18:06:45 7592] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/6/0'} to /local/domain/6/device/vif/0.
[2010-11-22 18:06:45 7592] DEBUG (DevController:97) DevController: writing {'bridge': 'xenbr0', 'domain': 'WinXP_HVM_Migrate', 'handle': '0', 'uuid': 'd02a38fb-c731-2ff7-2496-973be59caeb4', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:54:e1:54', 'frontend-id': '6', 'state': '1', 'online': '1', 'frontend': '/local/domain/6/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/6/0.
[2010-11-22 18:06:45 7592] DEBUG (XendDomainInfo:1804) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'image/suspend-cancel': '1', 'domid': '6', 'vm': '/vm/8a1fce68-d1ca-63cf-30a7-24cf2ed548f7', 'cpu/0/availability': 'online', 'memory/target': '262144', 'control/platform-feature-multiprocessor-suspend': '1', 'console/type': 'ioemu', 'store/port': '2', 'name': 'WinXP_HVM_Migrate'}
[2010-11-22 18:06:45 7592] INFO (XendCheckpoint:268) restore hvm domain 6, apic=1, pae=1
[2010-11-22 18:06:45 7592] DEBUG (image:339) No VNC passwd configured for vfb access
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: boot, val: dca
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: fda, val: None
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: fdb, val: None
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: soundhw, val: ac97
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: localtime, val: 0
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: serial, val: ['pty']
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: std-vga, val: 1
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: isa, val: 0
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: acpi, val: 1
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: usb, val: 0
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: usbdevice, val: tablet
[2010-11-22 18:06:45 7592] DEBUG (image:891) args: gfx_passthru, val: None
[2010-11-22 18:06:45 7592] INFO (image:822) Need to create platform device.[domid:6]
[2010-11-22 18:06:45 7592] DEBUG (XendCheckpoint:286) restore:shadow=0x3, _static_max=0x10000000, _static_min=0x0, 
[2010-11-22 18:06:45 7592] DEBUG (XendCheckpoint:305) [xc_restore]: /usr/lib/xen/bin/xc_restore 15 6 2 3 1 1 1 0
[2010-11-22 18:06:45 7592] INFO (XendCheckpoint:423) xc_domain_restore start: p2m_size = 100000
[2010-11-22 18:06:45 7592] INFO (XendCheckpoint:423) Reloading memory pages:   0%
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) Read 7180 bytes of QEMU data
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) ERROR Internal error: Error when reading batch size
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) ERROR Internal error: error when buffering batch, finishing
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) 
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) Writing 7180 bytes of QEMU data
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) ERROR Internal error: error setting the HVM context
[2010-11-22 18:07:15 7592] INFO (XendCheckpoint:423) Restore exit with rc=1
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:3053) XendDomainInfo.destroy: domid=6
[2010-11-22 18:07:15 7592] ERROR (XendDomainInfo:3067) XendDomainInfo.destroy: domain destruction failed.
Traceback (most recent call last):
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomainInfo.py", line 3060, in destroy
    xc.domain_pause(self.domid)
Error: (3, 'No such process')
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:2416) No device model
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:2418) Releasing devices
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:2424) Removing vif/0
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:2424) Removing console/0
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:2424) Removing vbd/768
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:2424) Removing vfb/0
[2010-11-22 18:07:15 7592] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2010-11-22 18:07:16 7592] INFO (XendDomain:1107) Domain WinXP_HVM_Migrate (8a1fce68-d1ca-63cf-30a7-24cf2ed548f7) deleted.
[2010-11-22 18:07:16 7592] ERROR (XendCheckpoint:357) /usr/lib/xen/bin/xc_restore 15 6 2 3 1 1 1 0 failed
Traceback (most recent call last):
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendCheckpoint.py", line 309, in restore
    forkHelper(cmd, fd, handler.handler, True)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendCheckpoint.py", line 411, in forkHelper
    raise XendError("%s failed" % string.join(cmd))
XendError: /usr/lib/xen/bin/xc_restore 15 6 2 3 1 1 1 0 failed
[2010-11-22 18:07:16 7592] ERROR (XendDomain:1175) Restore failed
Traceback (most recent call last):
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendDomain.py", line 1159, in domain_restore_fd
    dominfo = XendCheckpoint.restore(self, fd, paused=paused, relocating=relocating)
  File "/usr/local/lib/python2.6/dist-packages/xen/xend/XendCheckpoint.py", line 358, in restore
    raise exn
XendError: /usr/lib/xen/bin/xc_restore 15 6 2 3 1 1 1 0 failed