[2010-11-22 17:57:57 7700] INFO (SrvDaemon:332) Xend Daemon started
[2010-11-22 17:57:57 7700] INFO (SrvDaemon:336) Xend changeset: unavailable.
[2010-11-22 17:57:57 7700] INFO (XendNetwork:114) Not recreating missing unmanaged network peth0
[2010-11-22 17:57:58 7700] DEBUG (XendDomainInfo:149) XendDomainInfo.recreate({'max_vcpu_id': 3, 'cpu_time': 276787139532L, '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 17:57:58 7700] INFO (XendDomainInfo:167) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
[2010-11-22 17:57:58 7700] DEBUG (XendDomain:464) Adding Domain: 0
[2010-11-22 17:57:58 7700] DEBUG (XendDomain:398) number of vcpus to use is 0
[2010-11-22 17:57:58 7700] DEBUG (XendDomainInfo:1891) XendDomainInfo.handleShutdownWatch
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: VBD.set_device not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: VBD.set_type not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: session.get_all_records not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: event.get_record not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: event.get_all not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: VIF.set_device not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: VIF.set_MAC not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: VIF.set_MTU not found
[2010-11-22 17:57:58 7700] WARNING (XendAPI:705) API call: debug.get_all not found
[2010-11-22 17:57:58 7700] 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 17:57:58 7700] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
[2010-11-22 17:59:50 7700] DEBUG (XendDomainInfo:235) XendDomainInfo.restore(['domain', ['domid', '5'], ['cpu_weight', '256'], ['cpu_cap', '0'], ['bootloader'], ['on_crash', 'restart'], ['uuid', '2ec1afef-ecbd-0cea-684b-96ef3c94bf6c'], ['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', '1290419902.2'], ['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:10.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', '794e37dd-a989-8fa9-fbb6-f4716ad332b1'], ['script', '/etc/xen/scripts/vif-bridge'], ['mac', '00:16:3e:53:76:ea'], ['type', 'ioemu'], ['backend', '0']]], ['device', ['console', ['protocol', 'vt100'], ['location', '3'], ['uuid', '516a5b67-5123-31ea-cf2b-1e697107d985']]], ['device', ['vbd', ['uuid', '858a9d40-7dd2-537c-e302-8aaae156213e'], ['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', '777443ff-8dc1-a402-de0f-a5ae03ae6902'], ['vnclisten', '0.0.0.0'], ['vncdisplay', '6'], ['location', '0.0.0.0:5906']]], ['change_home_server', 'False']])
[2010-11-22 17:59:50 7700] DEBUG (XendDomainInfo:2508) XendDomainInfo.constructDomain
[2010-11-22 17:59:50 7700] DEBUG (balloon:220) Balloon: 3269644 KiB free; need 16384; done.
[2010-11-22 17:59:50 7700] DEBUG (XendDomain:464) Adding Domain: 4
[2010-11-22 17:59:50 7700] DEBUG (XendDomainInfo:3400) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '3', 'uuid': '2ec1afef-ecbd-0cea-684b-96ef3c94bf6c', 'on_reboot': 'restart', 'start_time': '1290419902.2', '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:10.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 17:59:50 7700] INFO (XendDomainInfo:2367) createDevice: console : {'protocol': 'vt100', 'location': '3', 'uuid': '516a5b67-5123-31ea-cf2b-1e697107d985'}
[2010-11-22 17:59:50 7700] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/4/0'} to /local/domain/4/device/console/0.
[2010-11-22 17:59:50 7700] DEBUG (DevController:97) DevController: writing {'domain': 'WinXP_HVM_Migrate', 'frontend': '/local/domain/4/device/console/0', 'uuid': '516a5b67-5123-31ea-cf2b-1e697107d985', 'frontend-id': '4', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/4/0.
[2010-11-22 17:59:50 7700] INFO (XendDomainInfo:2367) createDevice: vfb : {'vncunused': '1', 'other_config': {'vncunused': '1', 'vnclisten': '0.0.0.0', 'vnc': '1', 'vncdisplay': '6'}, 'vnc': '1', 'uuid': '777443ff-8dc1-a402-de0f-a5ae03ae6902', 'vnclisten': '0.0.0.0', 'vncdisplay': '6', 'location': '0.0.0.0:5906'}
[2010-11-22 17:59:50 7700] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/4/0'} to /local/domain/4/device/vfb/0.
[2010-11-22 17:59:50 7700] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'WinXP_HVM_Migrate', 'vnc': '1', 'uuid': '777443ff-8dc1-a402-de0f-a5ae03ae6902', 'frontend-id': '4', 'vnclisten': '0.0.0.0', 'vncdisplay': '6', 'state': '1', 'location': '0.0.0.0:5906', 'online': '1', 'frontend': '/local/domain/4/device/vfb/0'} to /local/domain/0/backend/vfb/4/0.
[2010-11-22 17:59:50 7700] INFO (XendDomainInfo:2367) createDevice: vbd : {'uuid': '858a9d40-7dd2-537c-e302-8aaae156213e', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda:disk', 'uname': 'phy:/dev/vg_xen/lv_xp_vm', 'mode': 'w', 'backend': '0'}
[2010-11-22 17:59:50 7700] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/4/768'} to /local/domain/4/device/vbd/768.
[2010-11-22 17:59:50 7700] DEBUG (DevController:97) DevController: writing {'domain': 'WinXP_HVM_Migrate', 'frontend': '/local/domain/4/device/vbd/768', 'uuid': '858a9d40-7dd2-537c-e302-8aaae156213e', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/dev/vg_xen/lv_xp_vm', 'mode': 'w', 'online': '1', 'frontend-id': '4', 'type': 'phy'} to /local/domain/0/backend/vbd/4/768.
[2010-11-22 17:59:50 7700] INFO (XendDomainInfo:2367) createDevice: vif : {'bridge': 'xenbr0', 'uuid': '794e37dd-a989-8fa9-fbb6-f4716ad332b1', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:53:76:ea', 'type': 'ioemu', 'backend': '0'}
[2010-11-22 17:59:50 7700] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/4/0'} to /local/domain/4/device/vif/0.
[2010-11-22 17:59:50 7700] DEBUG (DevController:97) DevController: writing {'bridge': 'xenbr0', 'domain': 'WinXP_HVM_Migrate', 'handle': '0', 'uuid': '794e37dd-a989-8fa9-fbb6-f4716ad332b1', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:53:76:ea', 'frontend-id': '4', 'state': '1', 'online': '1', 'frontend': '/local/domain/4/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/4/0.
[2010-11-22 17:59:50 7700] DEBUG (XendDomainInfo:1804) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'image/suspend-cancel': '1', 'domid': '4', 'vm': '/vm/2ec1afef-ecbd-0cea-684b-96ef3c94bf6c', '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 17:59:50 7700] INFO (XendCheckpoint:268) restore hvm domain 4, apic=1, pae=1
[2010-11-22 17:59:50 7700] DEBUG (image:339) No VNC passwd configured for vfb access
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: boot, val: dca
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: fda, val: None
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: fdb, val: None
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: soundhw, val: ac97
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: localtime, val: 0
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: serial, val: ['pty']
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: std-vga, val: 1
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: isa, val: 0
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: acpi, val: 1
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: usb, val: 0
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: usbdevice, val: tablet
[2010-11-22 17:59:50 7700] DEBUG (image:891) args: gfx_passthru, val: None
[2010-11-22 17:59:50 7700] INFO (image:822) Need to create platform device.[domid:4]
[2010-11-22 17:59:50 7700] DEBUG (XendCheckpoint:286) restore:shadow=0x3, _static_max=0x10000000, _static_min=0x0, 
[2010-11-22 17:59:50 7700] DEBUG (XendCheckpoint:305) [xc_restore]: /usr/lib/xen/bin/xc_restore 15 4 2 3 1 1 1 0
[2010-11-22 17:59:50 7700] INFO (XendCheckpoint:423) xc_domain_restore start: p2m_size = 100000
[2010-11-22 17:59:50 7700] INFO (XendCheckpoint:423) Reloading memory pages:   0%
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) Read 5771 bytes of QEMU data
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) Read 1409 bytes of QEMU data
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) ERROR Internal error: Error when reading batch size
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) ERROR Internal error: error when buffering batch, finishing
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) 
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) Writing 7180 bytes of QEMU data
[2010-11-22 18:00:19 7700] INFO (XendCheckpoint:423) Restore exit with rc=0
[2010-11-22 18:00:19 7700] DEBUG (XendCheckpoint:394) store-mfn 1044476
[2010-11-22 18:00:19 7700] DEBUG (XendDomainInfo:2992) XendDomainInfo.completeRestore
[2010-11-22 18:00:19 7700] DEBUG (image:339) No VNC passwd configured for vfb access
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: boot, val: dca
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: fda, val: None
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: fdb, val: None
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: soundhw, val: ac97
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: localtime, val: 0
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: serial, val: ['pty']
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: std-vga, val: 1
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: isa, val: 0
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: acpi, val: 1
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: usb, val: 0
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: usbdevice, val: tablet
[2010-11-22 18:00:19 7700] DEBUG (image:891) args: gfx_passthru, val: None
[2010-11-22 18:00:19 7700] INFO (image:822) Need to create platform device.[domid:4]
[2010-11-22 18:00:19 7700] INFO (image:418) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '4', '-domain-name', 'WinXP_HVM_Migrate', '-videoram', '4', '-vnc', '0.0.0.0:6', '-vncunused', '-vcpus', '1', '-vcpu_avail', '0x1L', '-boot', 'dca', '-soundhw', 'ac97', '-serial', 'pty', '-std-vga', '-acpi', '-usbdevice', 'tablet', '-net', 'nic,vlan=1,macaddr=00:16:3e:53:76:ea,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap4.0,bridge=xenbr0', '-M', 'xenfv', '-loadvm', '/var/lib/xen/qemu-save.4']
[2010-11-22 18:00:19 7700] INFO (image:467) device model pid: 7996
[2010-11-22 18:00:19 7700] INFO (image:590) waiting for sentinel_fifo
[2010-11-22 18:00:19 7700] DEBUG (XendDomainInfo:1804) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/2ec1afef-ecbd-0cea-684b-96ef3c94bf6c', 'domid': '4', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '262144', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu', 'name': 'WinXP_HVM_Migrate'}
[2010-11-22 18:00:19 7700] DEBUG (XendDomainInfo:3005) XendDomainInfo.completeRestore done
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices tap2.
[2010-11-22 18:00:19 7700] DEBUG (XendDomainInfo:1891) XendDomainInfo.handleShutdownWatch
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vif.
[2010-11-22 18:00:19 7700] DEBUG (DevController:144) Waiting for 0.
[2010-11-22 18:00:19 7700] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
[2010-11-22 18:00:19 7700] DEBUG (DevController:642) hotplugStatusCallback 1.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vkbd.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices ioports.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices tap.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vif2.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices console.
[2010-11-22 18:00:19 7700] DEBUG (DevController:144) Waiting for 0.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vscsi.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vbd.
[2010-11-22 18:00:19 7700] DEBUG (DevController:144) Waiting for 768.
[2010-11-22 18:00:19 7700] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/4/768/hotplug-status.
[2010-11-22 18:00:19 7700] DEBUG (DevController:642) hotplugStatusCallback 1.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices irq.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vfb.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices pci.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vusb.
[2010-11-22 18:00:19 7700] DEBUG (DevController:139) Waiting for devices vtpm.