windows bluescreen after restore

[Amin <amino7@yahoo.com>]

Hi there,

I used 3.0.5 rc3 and rc4 to test windows save/restore.
Whenever I restore a win 2003 or an xp guest, the guest blue-screens.  
Absolutely repeatable. This worked well with earlier unstable builds.

I added some of the logs below - what else can I provide to help 
debugging this?

Regards,
Amin

qemu-dm log

domid: 4
qemu: the number of cpus is 1
Watching /local/domain/4/logdirty/next-active
qemu_map_cache_init nr_buckets = 10000
shared page at pfn 1ffff
buffered io page at pfn 1fffd
Time offset set 0
xs_read(): vncpasswd get error. /vm/83323cd4-5819-2db4-a7db-e3ea9d9da6a6/
vncpasswd.
cirrus_vga_load:re-enable vga acc.lfb_addr=0xf0000000, lfb_end=0xf0800000.
usb_mouse_load:add usb_tablet_event.
Triggered log-dirty buffer switch
I/O request not ready: 0, ptr: 0, port: c002, data: 4, count: 1, size: 1
reset requested in cpu_handle_ioreq.
Issued domain 4 reboot


xend.log

[2007-04-30 18:35:12 2798] DEBUG (XendDomainInfo:210) XendDomainInfo.restore(['domain', ['domid', '3'], ['on_crash', 'destroy'], ['uuid', '83323cd4-5819-2db4-a7db-e3ea9
d9da6a6'], ['bootloader_args'], ['vcpus', '1'], ['name', 'WinXP_1'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['bootloader'], ['maxmem', '512'], ['memory',
 '512'], ['shadow_memory', '5'], ['vcpu_avail', '1'], ['cpu_weight', '256'], ['cpu_cap', '0'], ['features'], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['
start_time', '1177950322.67'], ['cpu_time', '275.278881106'], ['online_vcpus', '1'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['acpi', '1'], ['apic'
, '1'], ['boot', 'c'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['display', ':0'], ['isa', '0'], ['localtime', '0'], ['monitor', '0'], ['nographic', '0'], ['pae'
, '1'], ['rtc_timeoffset', '0'], ['stdvga', '0'], ['usb', '1'], ['usbdevice', 'tablet'], ['vncconsole', '1'], ['vncunused', '1'], ['xauthority', '/root/.xauthYGCR5r'],
['notes', ['SUSPEND_CANCEL', '1']]]], ['status', '2'], ['state', '-b----'], ['store_mfn', '131070'], ['device', ['vif', ['bridge', 'xenbr0'], ['uuid', '72626c56-91dd-bd
2f-de56-e978fae677da'], ['script', 'vif-bridge'], ['mac', '00:16:3e:00:00:aa'], ['type', 'ioemu'], ['backend', '0']]], ['device', ['vbd', ['uname', 'file:/nfs/xen/image
s/WinXP_1/xp_overlay.qcow'], ['uuid', '0ec69e37-3cb5-3241-217c-f7358cb76f3a'], ['mode', 'w'], ['dev', 'hda:disk'], ['backend', '0'], ['bootable', '1']]], ['device', ['v
kbd', ['backend', '0']]], ['device', ['vfb', ['uuid', '83ea558f-77b1-b977-aa0d-8e0cf4ec27c9'], ['vnclisten', '0.0.0.0'], ['location', '0.0.0.0:5900'], ['type', 'vnc'],
['display', ':0'], ['xauthority', '/root/.xauthYGCR5r']]], ['device', ['console', ['protocol', 'vt100'], ['location', '3'], ['uuid', '5cfa7dc4-bb05-a181-f1de-3d5d36f00a
69']]]])
[2007-04-30 18:35:12 2798] DEBUG (XendDomainInfo:1411) XendDomainInfo.constructDomain
[2007-04-30 18:35:12 2798] DEBUG (balloon:113) Balloon: 539380 KiB free; need 2048; done.
[2007-04-30 18:35:12 2798] DEBUG (XendDomain:434) Adding Domain: 4
[2007-04-30 18:35:12 2798] DEBUG (XendDomainInfo:1966) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '5', 'uuid': '83323cd4-5819-2db4-a7db-e3ea9d9da6a
6', 'on_reboot': 'destroy', 'start_time': '1177950322.67', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'destroy', 'vcpus': '1', 'vcpu_avail': '1',
'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (acpi 1) (apic 1) (boot c) (device_model /usr/lib64/xen/bin/qemu-dm) (display :0) (isa 0) (localtime 0) (monitor 0)
(nographic 0) (pae 1) (rtc_timeoffset 0) (stdvga 0) (usb 1) (usbdevice tablet) (vncconsole 1) (vncunused 1) (xauthority /root/.xauthYGCR5r) (notes (SUSPEND_CANCEL 1)))'
, 'name': 'WinXP_1'}
[2007-04-30 18:35:12 2798] INFO (XendDomainInfo:1313) createDevice: vkbd : {'uuid': 'e75cc595-76a1-08a9-4145-1c21ca77820c', 'backend': '0'}
[2007-04-30 18:35:12 2798] DEBUG (DevController:115) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vkbd/4/0'} to /local/d
omain/4/device/vkbd/0.
[2007-04-30 18:35:12 2798] DEBUG (DevController:117) DevController: writing {'frontend-id': '4', 'domain': 'WinXP_1', 'frontend': '/local/domain/4/device/vkbd/0', 'stat
e': '1', 'online': '1'} to /local/domain/0/backend/vkbd/4/0.
[2007-04-30 18:35:12 2798] INFO (XendDomainInfo:1313) createDevice: vfb : {'other_config': {'vnclisten': '0.0.0.0', 'type': 'vnc', 'display': ':0', 'xauthority': '/root
/.xauthYGCR5r'}, 'xauthority': '/root/.xauthYGCR5r', 'vnclisten': '0.0.0.0', 'location': '0.0.0.0:5900', 'type': 'vnc', 'display': ':0', 'uuid': '83ea558f-77b1-b977-aa0
d-8e0cf4ec27c9'}
[2007-04-30 18:35:12 2798] DEBUG (DevController:115) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/4/0'} to /local/do
main/4/device/vfb/0.
[2007-04-30 18:35:12 2798] DEBUG (DevController:117) DevController: writing {'domain': 'WinXP_1', 'frontend': '/local/domain/4/device/vfb/0', 'uuid': '83ea558f-77b1-b97
7-aa0d-8e0cf4ec27c9', 'vnclisten': '0.0.0.0', 'state': '1', 'location': '0.0.0.0:5900', 'online': '1', 'frontend-id': '4', 'type': 'vnc', 'display': ':0', 'xauthority':
 '/root/.xauthYGCR5r'} to /local/domain/0/backend/vfb/4/0.
[2007-04-30 18:35:12 2798] INFO (XendDomainInfo:1313) createDevice: console : {'protocol': 'vt100', 'location': '3', 'uuid': '5cfa7dc4-bb05-a181-f1de-3d5d36f00a69'}
[2007-04-30 18:35:12 2798] DEBUG (DevController:115) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/4/0'} to /loca
l/domain/4/device/console/0.
[2007-04-30 18:35:12 2798] DEBUG (DevController:117) DevController: writing {'domain': 'WinXP_1', 'protocol': 'vt100', 'uuid': '5cfa7dc4-bb05-a181-f1de-3d5d36f00a69', '
frontend': '/local/domain/4/device/console/0', 'state': '1', 'location': '3', 'online': '1', 'frontend-id': '4'} to /local/domain/0/backend/console/4/0.
[2007-04-30 18:35:12 2798] INFO (XendDomainInfo:1313) createDevice: vbd : {'uuid': '0ec69e37-3cb5-3241-217c-f7358cb76f3a', 'bootable': 1, 'driver': 'paravirtualised', '
dev': 'hda:disk', 'uname': 'file:/nfs/xen/images/WinXP_1/xp_overlay.qcow', 'mode': 'w', 'backend': '0'}
[2007-04-30 18:35:12 2798] DEBUG (DevController:115) 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.
[2007-04-30 18:35:12 2798] DEBUG (DevController:117) DevController: writing {'domain': 'WinXP_1', 'frontend': '/local/domain/4/device/vbd/768', 'uuid': '0ec69e37-3cb5-3
241-217c-f7358cb76f3a', 'dev': 'hda', 'state': '1', 'params': '/nfs/xen/images/WinXP_1/xp_overlay.qcow', 'mode': 'w', 'online': '1', 'frontend-id': '4', 'type': 'file'}
 to /local/domain/0/backend/vbd/4/768.
[2007-04-30 18:35:12 2798] INFO (XendDomainInfo:1313) createDevice: vif : {'bridge': 'xenbr0', 'uuid': '72626c56-91dd-bd2f-de56-e978fae677da', 'script': 'vif-bridge', '
mac': '00:16:3e:00:00:aa', 'type': 'ioemu', 'backend': '0'}
[2007-04-30 18:35:12 2798] DEBUG (DevController:115) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/4/0'} to /local/do
main/4/device/vif/0.
[2007-04-30 18:35:12 2798] DEBUG (DevController:117) DevController: writing {'bridge': 'xenbr0', 'domain': 'WinXP_1', 'handle': '0', 'uuid': '72626c56-91dd-bd2f-de56-e9
78fae677da', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/4/device/vif/0', 'mac': '00:16:3e:00:00:aa', 'online': '1', 'frontend-id'
: '4', 'type': 'ioemu'} to /local/domain/0/backend/vif/4/0.
[2007-04-30 18:35:12 2798] DEBUG (XendDomainInfo:824) Storing domain details: {'console/port': '3', 'name': 'WinXP_1', 'console/limit': '1048576', 'image/suspend-cancel
': '1', 'domid': '4', 'vm': '/vm/83323cd4-5819-2db4-a7db-e3ea9d9da6a6', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiproces
sor-suspend': '1', 'store/port': '2'}
[2007-04-30 18:35:12 2798] INFO (XendCheckpoint:191) restore hvm domain 4, apic=1, pae=1
[2007-04-30 18:35:12 2798] DEBUG (XendCheckpoint:201) restore:shadow=0x5, _static_max=0x20000000, _static_min=0x0,
[2007-04-30 18:35:12 2798] DEBUG (balloon:113) Balloon: 538344 KiB free; need 529408; done.
[2007-04-30 18:35:12 2798] DEBUG (XendCheckpoint:213) [xc_restore]: /usr/lib64/xen/bin/xc_restore 4 4 2 3 1 1 1
[2007-04-30 18:35:12 2798] INFO (XendCheckpoint:349) xc_domain_restore start: p2m_size = 100000
[2007-04-30 18:35:12 2798] INFO (XendCheckpoint:349) Reloading memory pages:   0%
[2007-04-30 18:35:20 2798] INFO (XendCheckpoint:349) Received all pages (0 races)
[2007-04-30 18:35:20 2798] INFO (XendCheckpoint:349) Restore exit with rc=0
[2007-04-30 18:35:20 2798] DEBUG (XendCheckpoint:320) store-mfn 131070
[2007-04-30 18:35:21 2798] DEBUG (XendDomainInfo:1595) XendDomainInfo.completeRestore
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: boot, val: c
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: fda, val: None
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: fdb, val: None
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: soundhw, val: None
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: localtime, val: 0
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: serial, val: None
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: std-vga, val: 0
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: isa, val: 0
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: acpi, val: 1
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: usb, val: 1
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: usbdevice, val: tablet
[2007-04-30 18:35:21 2798] DEBUG (image:330) args: k, val: None
[2007-04-30 18:35:21 2798] INFO (image:445) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '4', '-vcpus', '1', '-boot', 'c', '-
acpi', '-usb', '-usbdevice', 'tablet', '-domain-name', 'WinXP_1', '-net', 'nic,vlan=1,macaddr=00:16:3e:00:00:aa,model=rtl8139', '-net', 'tap,vlan=1,bridge=xenbr0', '-vn
cunused', '-vnclisten', '0.0.0.0', '-loadvm', '/tmp/xen.qemu-dm.4', '-vncviewer']
[2007-04-30 18:35:21 2798] INFO (image:449) device model pid: 5479
[2007-04-30 18:35:21 2798] DEBUG (XendDomainInfo:824) Storing domain details: {'console/port': '3', 'name': 'WinXP_1', 'console/limit': '1048576', 'vm': '/vm/83323cd4-5
819-2db4-a7db-e3ea9d9da6a6', 'domid': '4', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiproces
sor-suspend': '1', 'store/ring-ref': '131070', 'store/port': '2'}
[2007-04-30 18:35:21 2798] DEBUG (XendDomainInfo:824) Storing domain details: {'console/port': '3', 'name': 'WinXP_1', 'console/limit': '1048576', 'vm': '/vm/83323cd4-5
819-2db4-a7db-e3ea9d9da6a6', 'domid': '4', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiproces
sor-suspend': '1', 'store/ring-ref': '131070', 'store/port': '2'}
[2007-04-30 18:35:21 2798] DEBUG (XendDomainInfo:905) XendDomainInfo.handleShutdownWatch
[2007-04-30 18:35:21 2798] DEBUG (XendDomainInfo:1609) XendDomainInfo.completeRestore done
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices vif.
[2007-04-30 18:35:21 2798] DEBUG (DevController:153) Waiting for 0.
[2007-04-30 18:35:21 2798] DEBUG (DevController:539) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
[2007-04-30 18:35:21 2798] DEBUG (DevController:553) hotplugStatusCallback 1.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices usb.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices vbd.
[2007-04-30 18:35:21 2798] DEBUG (DevController:153) Waiting for 768.
[2007-04-30 18:35:21 2798] DEBUG (DevController:539) hotplugStatusCallback /local/domain/0/backend/vbd/4/768/hotplug-status.
[2007-04-30 18:35:21 2798] DEBUG (DevController:553) hotplugStatusCallback 1.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices irq.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices vkbd.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices vfb.
[2007-04-30 18:35:21 2798] DEBUG (vfbif:94) skip waiting for HVM vfb
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices console.
[2007-04-30 18:35:21 2798] DEBUG (DevController:153) Waiting for 0.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices pci.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices ioports.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices tap.
[2007-04-30 18:35:21 2798] DEBUG (DevController:148) Waiting for devices vtpm.