WARNING - OLD ARCHIVES

This is an archived copy of the Xen.org mailing list, which we have preserved to ensure that existing links to archives are not broken. The live archive, which contains the latest emails, can be found at http://lists.xen.org/
   
 
 
Xen 
 
Home Products Support Community News
 
   
 

xen-devel

[Xen-devel] blkif migration problem

To: xen-devel@xxxxxxxxxxxxxxxxxxx
Subject: [Xen-devel] blkif migration problem
From: Cristian Zamfir <zamf@xxxxxxxxxxxxx>
Date: Mon, 04 Dec 2006 19:09:52 +0000
Delivery-date: Mon, 04 Dec 2006 11:10:00 -0800
Envelope-to: www-data@xxxxxxxxxxxxxxxxxx
List-help: <mailto:xen-devel-request@lists.xensource.com?subject=help>
List-id: Xen developer discussion <xen-devel.lists.xensource.com>
List-post: <mailto:xen-devel@lists.xensource.com>
List-subscribe: <http://lists.xensource.com/cgi-bin/mailman/listinfo/xen-devel>, <mailto:xen-devel-request@lists.xensource.com?subject=subscribe>
List-unsubscribe: <http://lists.xensource.com/cgi-bin/mailman/listinfo/xen-devel>, <mailto:xen-devel-request@lists.xensource.com?subject=unsubscribe>
Sender: xen-devel-bounces@xxxxxxxxxxxxxxxxxxx
User-agent: Thunderbird 1.5.0.8 (X11/20061117)

Hi,

We are attempting to migrate blkif devices backed by drbd devices. We have used a similar approach to the vTPM migration. Complete migration seems to go without errors on both source and destination. The migrated machine responds to external network queries like ping, arping, nmap but I cannot ssh into it. Also, when using xm console, I get get these messages before the login prompt:

vbd vbd-769: 16 Device in use; refusing to close
netfront: device eth0 has flipping receive path.

... then the  machine hangs after inputing the login username.

My guess is that even though the hotplug scripts returned successfully for the vbd device (according to the xend.log bellow), the vbd did not migrate successfully and the dom0 machine cannot read anything from the disk.

Do you have any suggestion on what the problem might be and where and how to look for more debugging information?

Attached are the xend.logs for the source and the destination.

Thank you.

Cristian



[2006-12-04 17:22:53 xend 2674] INFO (SrvDaemon:283) Xend Daemon started
[2006-12-04 17:22:53 xend 2674] INFO (SrvDaemon:287) Xend changeset: 
unavailable .
[2006-12-04 17:22:53 xend.XendDomainInfo 2674] DEBUG (XendDomainInfo:212) 
XendDomainInfo.recreate({'paused': 0, 'cpu_time': 8725777156L, 'ssidref': 0, 
'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'shutdown_reason': 
0, 'dying': 0, 'dom': 0, 'mem_kb': 254720, 'maxmem_kb': -4, 'max_vcpu_id': 0, 
'crashed': 0, 'running': 1, 'shutdown': 0, 'online_vcpus': 1, 'blocked': 0})
[2006-12-04 17:22:53 xend.XendDomainInfo 2674] INFO (XendDomainInfo:224) 
Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
[2006-12-04 17:22:53 xend.XendDomainInfo 2674] WARNING (XendDomainInfo:246) No 
vm path in store for existing domain 0
[2006-12-04 17:22:53 xend.XendDomainInfo 2674] DEBUG (XendDomainInfo:712) 
Storing VM details: {'shadow_memory': '0', 'uuid': 
'00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'on_poweroff': 
'destroy', 'name': 'Domain-0', 'xend/restart_count': '0', 'vcpus': '1', 
'vcpu_avail': '1', 'memory': '249', 'on_crash': 'restart', 'maxmem': '249'}
[2006-12-04 17:22:53 xend.XendDomainInfo 2674] DEBUG (XendDomainInfo:747) 
Storing domain details: {'cpu/0/availability': 'online', 'memory/target': 
'254976', 'name': 'Domain-0', 'console/limit': '1048576', 'vm': 
'/vm/00000000-0000-0000-0000-000000000000', 'domid': '0'}
[2006-12-04 17:22:53 xend 2674] DEBUG (XendDomain:153) number of vcpus to use 
is 0
[2006-12-04 17:22:53 xend.XendDomainInfo 2684] DEBUG (XendDomainInfo:933) 
XendDomainInfo.handleShutdownWatch
[2006-12-04 17:35:26 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:265) 
XendDomainInfo.restore(['domain', ['domid', '3'], ['uuid', 
'3be156ff-6298-0d86-0b94-27c58262f4c0'], ['vcpus', '1'], ['vcpu_avail', '1'], 
['cpu_weight', '1.0'], ['memory', '232'], ['shadow_memory', '0'], ['maxmem', 
'232'], ['features'], ['name', 'vm01'], ['on_poweroff', 'destroy'], 
['on_reboot', 'restart'], ['on_crash', 'restart'], ['image', ['linux', 
['kernel', '/boot/vmlinuz-2.6-xenU'], ['ip', 
'130.209.253.132:1.2.3.4:130.209.240.48:255.255.240.0:raoul.dcs.gla.ac.uk:eth0:off'],
 ['root', '/dev/hda1']]], ['device', ['vif', ['backend', '0'], ['script', 
'vif-bridge'], ['mac', '00:16:3E:F6:BB:01']]], ['device', ['vbd', ['backend', 
'0'], ['dev', 'hda1:disk'], ['uname', 'phy:drbd1'], ['mode', 'w']]], ['state', 
'------'], ['shutdown_reason', 'poweroff'], ['cpu_time', '0.891476194'], 
['online_vcpus', '1'], ['up_time', '85.1114499569'], ['start_time', 
'1165253683.61'], ['store_mfn', '4781'], ['console_mfn', '4780']])
[2006-12-04 17:35:26 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:296) 
parseConfig: config is ['domain', ['domid', '3'], ['uuid', 
'3be156ff-6298-0d86-0b94-27c58262f4c0'], ['vcpus', '1'], ['vcpu_avail', '1'], 
['cpu_weight', '1.0'], ['memory', '232'], ['shadow_memory', '0'], ['maxmem', 
'232'], ['features'], ['name', 'vm01'], ['on_poweroff', 'destroy'], 
['on_reboot', 'restart'], ['on_crash', 'restart'], ['image', ['linux', 
['kernel', '/boot/vmlinuz-2.6-xenU'], ['ip', 
'130.209.253.132:1.2.3.4:130.209.240.48:255.255.240.0:raoul.dcs.gla.ac.uk:eth0:off'],
 ['root', '/dev/hda1']]], ['device', ['vif', ['backend', '0'], ['script', 
'vif-bridge'], ['mac', '00:16:3E:F6:BB:01']]], ['device', ['vbd', ['backend', 
'0'], ['dev', 'hda1:disk'], ['uname', 'phy:drbd1'], ['mode', 'w']]], ['state', 
'------'], ['shutdown_reason', 'poweroff'], ['cpu_time', '0.891476194'], 
['online_vcpus', '1'], ['up_time', '85.1114499569'], ['start_time', 
'1165253683.61'], ['store_mfn', '4781'], ['console_mfn', '4780']]
[2006-12-04 17:35:26 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:395) 
parseConfig: result is {'shadow_memory': 0, 'uuid': 
'3be156ff-6298-0d86-0b94-27c58262f4c0', 'on_crash': 'restart', 'on_reboot': 
'restart', 'localtime': None, 'image': ['linux', ['kernel', 
'/boot/vmlinuz-2.6-xenU'], ['ip', 
'130.209.253.132:1.2.3.4:130.209.240.48:255.255.240.0:raoul.dcs.gla.ac.uk:eth0:off'],
 ['root', '/dev/hda1']], 'on_poweroff': 'destroy', 'bootloader_args': None, 
'cpus': None, 'name': 'vm01', 'backend': [], 'vcpus': 1, 'cpu_weight': 1.0, 
'features': None, 'vcpu_avail': 1, 'memory': 232, 'device': [('vif', ['vif', 
['backend', '0'], ['script', 'vif-bridge'], ['mac', '00:16:3E:F6:BB:01']]), 
('vbd', ['vbd', ['backend', '0'], ['dev', 'hda1:disk'], ['uname', 'phy:drbd1'], 
['mode', 'w']])], 'bootloader': None, 'cpu': None, 'maxmem': 232}
[2006-12-04 17:35:26 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:1253) 
XendDomainInfo.construct: None
[2006-12-04 17:35:26 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:712) 
Storing VM details: {'shadow_memory': '0', 'uuid': 
'3be156ff-6298-0d86-0b94-27c58262f4c0', 'on_reboot': 'restart', 'image': 
'(linux (kernel /boot/vmlinuz-2.6-xenU) (ip 
130.209.253.132:1.2.3.4:130.209.240.48:255.255.240.0:raoul.dcs.gla.ac.uk:eth0:off)
 (root /dev/hda1))', 'on_poweroff': 'destroy', 'name': 'vm01', 
'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '232', 
'on_crash': 'restart', 'maxmem': '232'}
[2006-12-04 17:35:26 xend 3030] DEBUG (DevController:110) DevController: 
writing {'backend-id': '0', 'mac': '00:16:3E:F6:BB:01', 'handle': '0', 'state': 
'1', 'backend': '/local/domain/0/backend/vif/1/0'} to 
/local/domain/1/device/vif/0.
[2006-12-04 17:35:26 xend 3030] DEBUG (DevController:112) DevController: 
writing {'domain': 'vm01', 'handle': '0', 'script': 
'/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': 
'/local/domain/1/device/vif/0', 'mac': '00:16:3E:F6:BB:01', 'online': '1', 
'frontend-id': '1'} to /local/domain/0/backend/vif/1/0.
[2006-12-04 17:35:26 xend 3030] DEBUG (DevController:110) DevController: 
writing {'backend-id': '0', 'virtual-device': '769', 'device-type': 'disk', 
'state': '1', 'backend': '/local/domain/0/backend/vbd/1/769'} to 
/local/domain/1/device/vbd/769.
[2006-12-04 17:35:26 xend 3030] DEBUG (DevController:112) DevController: 
writing {'domain': 'vm01', 'frontend': '/local/domain/1/device/vbd/769', 'dev': 
'hda1', 'state': '1', 'params': 'drbd1', 'mode': 'w', 'online': '1', 
'frontend-id': '1', 'type': 'phy'} to /local/domain/0/backend/vbd/1/769.
[2006-12-04 17:35:26 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:747) 
Storing domain details: {'console/port': '2', 'name': 'vm01', 'console/limit': 
'1048576', 'vm': '/vm/3be156ff-6298-0d86-0b94-27c58262f4c0', 'domid': '1', 
'cpu/0/availability': 'online', 'memory/target': '237568', 'store/port': '1'}
[2006-12-04 17:35:26 xend 3030] DEBUG (balloon:127) Balloon: 770752 KiB free; 
need 245760; done.
[2006-12-04 17:35:26 xend 3030] DEBUG (XendCheckpoint:155) [xc_restore]: 
/usr/lib/xen/bin/xc_restore 4 1 61440 1 2
[2006-12-04 17:35:27 xend 3039] ERROR (XendCheckpoint:236) xc_linux_restore 
start: max_pfn = f000
[2006-12-04 17:35:27 xend 3039] ERROR (XendCheckpoint:236) Increased domain 
reservation by 3c000 KB
[2006-12-04 17:35:27 xend 3039] ERROR (XendCheckpoint:236) Reloading memory 
pages:   0%
[2006-12-04 17:35:48 xend 3030] DEBUG (XendCheckpoint:209) store-mfn 4781
[2006-12-04 17:35:48 xend 3030] DEBUG (XendCheckpoint:209) console-mfn 4780
[2006-12-04 17:35:48 xend 3039] ERROR (XendCheckpoint:236) Received all pages 
(0 races)
[2006-12-04 17:35:48 xend 3039] ERROR (XendCheckpoint:236) 100%
[2006-12-04 17:35:48 xend 3039] ERROR (XendCheckpoint:236) Memory reloaded.
[2006-12-04 17:35:48 xend 3039] ERROR (XendCheckpoint:236) Decreased 
reservation by 2094 pages
[2006-12-04 17:35:48 xend 3039] ERROR (XendCheckpoint:236) Domain ready to be 
built.
[2006-12-04 17:35:48 xend 3039] ERROR (XendCheckpoint:236) Restore exit with 
rc=0
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
vif.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:149) Waiting for 0.
[2006-12-04 17:35:48 xend 2684] DEBUG (DevController:464) hotplugStatusCallback 
/local/domain/0/backend/vif/1/0/hotplug-status.
[2006-12-04 17:35:48 xend 2684] DEBUG (DevController:479) hotplugStatusCallback 
1.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
usb.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
vbd.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:149) Waiting for 769.
[2006-12-04 17:35:48 xend 2684] DEBUG (DevController:464) hotplugStatusCallback 
/local/domain/0/backend/vbd/1/769/hotplug-status.
[2006-12-04 17:35:48 xend 2684] DEBUG (DevController:479) hotplugStatusCallback 
1.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
irq.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
pci.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
ioports.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
tap.
[2006-12-04 17:35:48 xend 3030] DEBUG (DevController:143) Waiting for devices 
vtpm.
[2006-12-04 17:35:48 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:672) 
XendDomainInfo.completeRestore
[2006-12-04 17:35:48 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:747) 
Storing domain details: {'console/ring-ref': '4780', 'console/port': '2', 
'name': 'vm01', 'console/limit': '1048576', 'vm': 
'/vm/3be156ff-6298-0d86-0b94-27c58262f4c0', 'domid': '1', 'cpu/0/availability': 
'online', 'memory/target': '237568', 'store/ring-ref': '4781', 'store/port': 
'1'}
[2006-12-04 17:35:48 xend.XendDomainInfo 3030] DEBUG (XendDomainInfo:682) 
XendDomainInfo.completeRestore done
[2006-12-04 17:35:48 xend.XendDomainInfo 2684] DEBUG (XendDomainInfo:933) 
XendDomainInfo.handleShutdownWatch

Attachment: xend.log.src
Description: WAIS Source

_______________________________________________
Xen-devel mailing list
Xen-devel@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-devel
<Prev in Thread] Current Thread [Next in Thread>