[2010-11-22 17:58:02 6968] INFO (SrvDaemon:332) Xend Daemon started
[2010-11-22 17:58:02 6968] INFO (SrvDaemon:336) Xend changeset: unavailable.
[2010-11-22 17:58:02 6968] DEBUG (XendDomainInfo:149) XendDomainInfo.recreate({'max_vcpu_id': 3, 'cpu_time': 141791321561L, '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:58:02 6968] INFO (XendDomainInfo:167) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
[2010-11-22 17:58:03 6968] DEBUG (XendDomain:464) Adding Domain: 0
[2010-11-22 17:58:03 6968] DEBUG (XendDomain:398) number of vcpus to use is 0
[2010-11-22 17:58:03 6968] DEBUG (XendDomainInfo:1891) XendDomainInfo.handleShutdownWatch
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: VBD.set_device not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: VBD.set_type not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: session.get_all_records not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: event.get_record not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: event.get_all not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: VIF.set_device not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: VIF.set_MAC not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: VIF.set_MTU not found
[2010-11-22 17:58:03 6968] WARNING (XendAPI:705) API call: debug.get_all not found
[2010-11-22 17:58:03 6968] 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:58:03 6968] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
[2010-11-22 17:58:22 6968] DEBUG (XendDomainInfo:101) XendDomainInfo.create(['vm', ['name', 'WinXP_HVM_Migrate'], ['memory', 256], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 1], ['oos', 1], ['image', ['hvm', ['kernel', '/usr/lib64/xen/boot/hvmloader'], ['videoram', 4], ['serial', 'pty'], ['acpi', 1], ['apic', 1], ['boot', 'dca'], ['cpuid', []], ['cpuid_check', []], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['display', 'localhost:10.0'], ['fda', ''], ['fdb', ''], ['guest_os_type', 'default'], ['hap', 1], ['hpet', 0], ['isa', 0], ['keymap', ''], ['localtime', 0], ['nographic', 0], ['oos', 1], ['pae', 1], ['pci', []], ['pci_msitranslate', 1], ['pci_power_mgmt', 0], ['rtc_timeoffset', 0], ['sdl', 0], ['soundhw', 'ac97'], ['stdvga', 1], ['timer_mode', 1], ['usb', 0], ['usbdevice', 'tablet'], ['vcpus', 1], ['vnc', 1], ['vncdisplay', 6], ['vnclisten', '0.0.0.0'], ['vncunused', 1], ['viridian', 0], ['vpt_align', 1], ['xauthority', '/home/crilit/.Xauthority'], ['xen_platform_pci', 1], ['memory_sharing', 0], ['vncpasswd', 'XXXXXXXX'], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'phy:/dev/vg_xen/lv_xp_vm'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'xenbr0'], ['type', 'ioemu']]]])
[2010-11-22 17:58:22 6968] DEBUG (XendDomainInfo:2508) XendDomainInfo.constructDomain
[2010-11-22 17:58:22 6968] DEBUG (balloon:220) Balloon: 3269620 KiB free; need 16384; done.
[2010-11-22 17:58:22 6968] DEBUG (XendDomain:464) Adding Domain: 5
[2010-11-22 17:58:22 6968] DEBUG (XendDomainInfo:2818) XendDomainInfo.initDomain: 5 256
[2010-11-22 17:58:22 6968] DEBUG (image:339) No VNC passwd configured for vfb access
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: boot, val: dca
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: fda, val: None
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: fdb, val: None
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: soundhw, val: ac97
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: localtime, val: 0
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: serial, val: ['pty']
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: std-vga, val: 1
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: isa, val: 0
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: acpi, val: 1
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: usb, val: 0
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: usbdevice, val: tablet
[2010-11-22 17:58:22 6968] DEBUG (image:891) args: gfx_passthru, val: None
[2010-11-22 17:58:22 6968] INFO (image:822) Need to create platform device.[domid:5]
[2010-11-22 17:58:22 6968] DEBUG (XendDomainInfo:2845) _initDomain:shadow_memory=0x0, memory_static_max=0x10000000, memory_static_min=0x0.
[2010-11-22 17:58:22 6968] INFO (image:182) buildDomain os=hvm dom=5 vcpus=1
[2010-11-22 17:58:22 6968] DEBUG (image:949) domid          = 5
[2010-11-22 17:58:22 6968] DEBUG (image:950) image          = /usr/lib64/xen/boot/hvmloader
[2010-11-22 17:58:22 6968] DEBUG (image:951) store_evtchn   = 2
[2010-11-22 17:58:22 6968] DEBUG (image:952) memsize        = 256
[2010-11-22 17:58:22 6968] DEBUG (image:953) target         = 256
[2010-11-22 17:58:22 6968] DEBUG (image:954) vcpus          = 1
[2010-11-22 17:58:22 6968] DEBUG (image:955) vcpu_avail     = 1
[2010-11-22 17:58:22 6968] DEBUG (image:956) acpi           = 1
[2010-11-22 17:58:22 6968] DEBUG (image:957) apic           = 1
[2010-11-22 17:58:22 6968] 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}
[2010-11-22 17:58:22 6968] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/5/0'} to /local/domain/5/device/vfb/0.
[2010-11-22 17:58:22 6968] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'WinXP_HVM_Migrate', 'vnc': '1', 'uuid': '777443ff-8dc1-a402-de0f-a5ae03ae6902', 'frontend-id': '5', 'vnclisten': '0.0.0.0', 'vncdisplay': '6', 'state': '1', 'online': '1', 'frontend': '/local/domain/5/device/vfb/0'} to /local/domain/0/backend/vfb/5/0.
[2010-11-22 17:58:22 6968] INFO (XendDomainInfo:2367) createDevice: vbd : {'uuid': '858a9d40-7dd2-537c-e302-8aaae156213e', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda', 'uname': 'phy:/dev/vg_xen/lv_xp_vm', 'mode': 'w'}
[2010-11-22 17:58:22 6968] DEBUG (DevController:95) 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.
[2010-11-22 17:58:22 6968] DEBUG (DevController:97) DevController: writing {'domain': 'WinXP_HVM_Migrate', 'frontend': '/local/domain/5/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': '5', 'type': 'phy'} to /local/domain/0/backend/vbd/5/768.
[2010-11-22 17:58:22 6968] INFO (XendDomainInfo:2367) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3e:53:76:ea', 'type': 'ioemu', 'uuid': '794e37dd-a989-8fa9-fbb6-f4716ad332b1'}
[2010-11-22 17:58:22 6968] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/5/0'} to /local/domain/5/device/vif/0.
[2010-11-22 17:58:22 6968] 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': '5', 'state': '1', 'online': '1', 'frontend': '/local/domain/5/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/5/0.
[2010-11-22 17:58:22 6968] INFO (image:418) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '5', '-domain-name', 'WinXP_HVM_Migrate', '-videoram', '4', '-vnc', '0.0.0.0:6', '-vncunused', '-vcpus', '1', '-vcpu_avail', '0x1', '-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=tap5.0,bridge=xenbr0', '-M', 'xenfv']
[2010-11-22 17:58:22 6968] INFO (image:467) device model pid: 7123
[2010-11-22 17:58:22 6968] 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:58:22 6968] INFO (image:590) waiting for sentinel_fifo
[2010-11-22 17:58:22 6968] DEBUG (XendDomainInfo:1804) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/2ec1afef-ecbd-0cea-684b-96ef3c94bf6c', 'domid': '5', '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 17:58:22 6968] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/5/0'} to /local/domain/5/device/console/0.
[2010-11-22 17:58:22 6968] DEBUG (DevController:97) DevController: writing {'domain': 'WinXP_HVM_Migrate', 'frontend': '/local/domain/5/device/console/0', 'uuid': '516a5b67-5123-31ea-cf2b-1e697107d985', 'frontend-id': '5', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/5/0.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices tap2.
[2010-11-22 17:58:22 6968] DEBUG (XendDomainInfo:1891) XendDomainInfo.handleShutdownWatch
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vif.
[2010-11-22 17:58:22 6968] DEBUG (DevController:144) Waiting for 0.
[2010-11-22 17:58:22 6968] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/5/0/hotplug-status.
[2010-11-22 17:58:22 6968] DEBUG (DevController:642) hotplugStatusCallback 1.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vkbd.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices ioports.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices tap.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vif2.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices console.
[2010-11-22 17:58:22 6968] DEBUG (DevController:144) Waiting for 0.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vscsi.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vbd.
[2010-11-22 17:58:22 6968] DEBUG (DevController:144) Waiting for 768.
[2010-11-22 17:58:22 6968] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/5/768/hotplug-status.
[2010-11-22 17:58:22 6968] DEBUG (DevController:642) hotplugStatusCallback 1.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices irq.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vfb.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices pci.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vusb.
[2010-11-22 17:58:22 6968] DEBUG (DevController:139) Waiting for devices vtpm.
[2010-11-22 17:58:22 6968] INFO (XendDomain:1206) Domain WinXP_HVM_Migrate (5) unpaused.
[2010-11-22 17:59:50 6968] DEBUG (XendCheckpoint:124) [xc_save]: /usr/lib/xen/bin/xc_save 35 5 0 0 5
[2010-11-22 17:59:50 6968] INFO (XendCheckpoint:423) xc_save: failed to get the suspend evtchn port
[2010-11-22 17:59:50 6968] INFO (XendCheckpoint:423) 
[2010-11-22 18:00:13 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 1   0%  5% 10% 15% 20% 25% 30% 35% 40% 45% 50% 55% 60% 65% 70% 75% 80% 85% 90% 95%
 1: sent 64680, skipped 5976, delta 23556ms, dom0 30%, target 5%, sent 89Mb/s, dirtied 13Mb/s 9700 pages
[2010-11-22 18:00:16 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 2   0%  5%
 2: sent 9132, skipped 550, delta 3066ms, dom0 26%, target 2%, sent 97Mb/s, dirtied 25Mb/s 2405 pages
[2010-11-22 18:00:17 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 3   0%
 3: sent 1956, skipped 449, delta 686ms, dom0 32%, target 3%, sent 93Mb/s, dirtied 78Mb/s 1638 pages
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 4   0%
 4: sent 1454, skipped 184, delta 527ms, dom0 25%, target 3%, sent 90Mb/s, dirtied 61Mb/s 985 pages
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 5   0%
 5: sent 985, skipped 0, delta 341ms, dom0 31%, target 2%, sent 94Mb/s, dirtied 14Mb/s 155 pages
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 6   0%
 6: sent 155, skipped 0, delta 67ms, dom0 77%, target 0%, sent 75Mb/s, dirtied 478Mb/s 979 pages
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 7   0%
 7: sent 979, skipped 0, delta 334ms, dom0 23%, target 1%, sent 96Mb/s, dirtied 13Mb/s 140 pages
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 8   0%
 8: sent 140, skipped 0, delta 21ms, dom0 100%, target 0%, sent 218Mb/s, dirtied 65Mb/s 42 pages
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 9   0%
 9: sent 42, skipped 0, Start last iteration
[2010-11-22 18:00:18 6968] DEBUG (XendCheckpoint:394) suspend
[2010-11-22 18:00:18 6968] DEBUG (XendCheckpoint:127) In saveInputHandler suspend
[2010-11-22 18:00:18 6968] DEBUG (XendCheckpoint:129) Suspending 5 ...
[2010-11-22 18:00:18 6968] DEBUG (XendDomainInfo:519) XendDomainInfo.shutdown(suspend)
[2010-11-22 18:00:18 6968] DEBUG (XendDomainInfo:1891) XendDomainInfo.handleShutdownWatch
[2010-11-22 18:00:18 6968] INFO (XendDomainInfo:536) HVM save:remote shutdown dom 5!
[2010-11-22 18:00:18 6968] INFO (XendDomainInfo:2088) Domain has shutdown: name=migrating-WinXP_HVM_Migrate id=5 reason=suspend.
[2010-11-22 18:00:18 6968] INFO (XendCheckpoint:135) Domain 5 suspended.
[2010-11-22 18:00:19 6968] INFO (image:538) signalDeviceModel:restore dm state to running
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) SUSPEND shinfo 000fffff
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) delta 300ms, dom0 18%, target 0%, sent 4Mb/s, dirtied 11Mb/s 103 pages
[2010-11-22 18:00:19 6968] DEBUG (XendCheckpoint:144) Written done
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) Saving memory pages: iter 10   0%
 10: sent 103, skipped 0, delta 15ms, dom0 0%, target 0%, sent 225Mb/s, dirtied 225Mb/s 103 pages
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) Total pages sent= 79626 (0.08x)
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) (of which 0 were fixups)
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) All memory is saved
[2010-11-22 18:00:19 6968] INFO (XendCheckpoint:423) Save exit rc=0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:3053) XendDomainInfo.destroy: domid=5
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2411) Destroying device model
[2010-11-22 18:00:19 6968] INFO (image:615) migrating-WinXP_HVM_Migrate device model terminated
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2418) Releasing devices
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2424) Removing vif/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2424) Removing console/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2424) Removing vbd/768
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2424) Removing vfb/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2416) No device model
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2418) Releasing devices
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2424) Removing vif/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:2424) Removing vbd/768
[2010-11-22 18:00:19 6968] DEBUG (XendDomainInfo:1286) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768