[Fedora-xen] FC5 problem with vif, udev and HAL
Tomas Kouba
tomas at jikos.cz
Fri Aug 11 08:37:24 UTC 2006
Hello,
I have a problem with domU starting. I use
vif=['']
configuration option for setting network.
When the domain starts it hangs for a while and then
ends with an error:
[root at fik ~]# xm create -c vmid=1
Using config file "/etc/xen/xmdefconfig".
Error: Device 0 (vif) could not be connected. Hotplug scripts not working.
I have googled some pieces of advice but nothing applies for me
(I have enough loop devices, I cannot install hotplug package since it is
no longer used in FC5).
Does anybody have a hint what can be wrong?
Some (hopefully) useful logs:
xend.log:
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:185)
XendDomainInfo.create(['vm', ['name', 'ce'], ['memory', 256], ['vcpus',
1], ['image', ['linux', ['kernel',
'/boot/vmlinuz-2.6.17-1.2157_FC5xen'], ['ip',
'192.168.1.101/24:1.2.3.4:192.168.1.128::ce:eth0:dhcp'], ['root',
'/dev/sda1'], ['args', 'fastboot ro']]], ['device', ['vbd', ['uname',
'phy:vgpool/ce'], ['dev', 'sda1'], ['mode', 'w']]], ['device', ['vbd',
['uname', 'phy:vgpool/ceswap'], ['dev', 'sda2'], ['mode', 'w']]],
['device', ['vif', ['ip', '10.0.9.1/16'], ['mac', '00:16:3E:47:A1:09'],
['vifname', 'cevif']]]])
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:291)
parseConfig: config is ['vm', ['name', 'ce'], ['memory', 256], ['vcpus',
1], ['image', ['linux', ['kernel',
'/boot/vmlinuz-2.6.17-1.2157_FC5xen'], ['ip',
'192.168.1.101/24:1.2.3.4:192.168.1.128::ce:eth0:dhcp'], ['root',
'/dev/sda1'], ['args', 'fastboot ro']]], ['device', ['vbd', ['uname',
'phy:vgpool/ce'], ['dev', 'sda1'], ['mode', 'w']]], ['device', ['vbd',
['uname', 'phy:vgpool/ceswap'], ['dev', 'sda2'], ['mode', 'w']]],
['device', ['vif', ['ip', '10.0.9.1/16'], ['mac', '00:16:3E:47:A1:09'],
['vifname', 'cevif']]]]
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:390)
parseConfig: result is {'uuid': None, 'on_crash': None, 'on_reboot':
None, 'image': ['linux', ['kernel',
'/boot/vmlinuz-2.6.17-1.2157_FC5xen'], ['ip',
'192.168.1.101/24:1.2.3.4:192.168.1.128::ce:eth0:dhcp'], ['root',
'/dev/sda1'], ['args', 'fastboot ro']], 'on_poweroff': None,
'bootloader_args': None, 'cpus': None, 'name': 'ce', 'backend': [],
'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None,
'memory': 256, 'device': [('vbd', ['vbd', ['uname', 'phy:vgpool/ce'],
['dev', 'sda1'], ['mode', 'w']]), ('vbd', ['vbd', ['uname',
'phy:vgpool/ceswap'], ['dev', 'sda2'], ['mode', 'w']]), ('vif', ['vif',
['ip', '10.0.9.1/16'], ['mac', '00:16:3E:47:A1:09'], ['vifname',
'cevif']])], 'bootloader': None, 'cpu': None, 'maxmem': None}
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:1216)
XendDomainInfo.construct: None
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:1248)
XendDomainInfo.initDomain: 2 1.0
[2006-08-11 10:28:50 xend] DEBUG (balloon:128) Balloon: free 261; need
261; done.
[2006-08-11 10:28:50 xend] INFO (image:134) buildDomain os=linux dom=2
vcpus=1
[2006-08-11 10:28:50 xend] DEBUG (image:177) dom = 2
[2006-08-11 10:28:50 xend] DEBUG (image:178) image =
/boot/vmlinuz-2.6.17-1.2157_FC5xen
[2006-08-11 10:28:50 xend] DEBUG (image:179) store_evtchn = 1
[2006-08-11 10:28:50 xend] DEBUG (image:180) console_evtchn = 2
[2006-08-11 10:28:50 xend] DEBUG (image:181) cmdline =
ip=192.168.1.101/24:1.2.3.4:192.168.1.128::ce:eth0:dhcp root=/dev/sda1
fastboot ro
[2006-08-11 10:28:50 xend] DEBUG (image:182) ramdisk =
[2006-08-11 10:28:50 xend] DEBUG (image:183) vcpus = 1
[2006-08-11 10:28:50 xend] DEBUG (image:184) features =
[2006-08-11 10:28:50 xend] DEBUG (blkif:24) exception looking up device
number for sda1: [Errno 2] No such file or directory: '/dev/sda1'
[2006-08-11 10:28:50 xend] DEBUG (DevController:110) DevController:
writing {'virtual-device': '2049', 'backend-id': '0', 'state': '1',
'backend': '/local/domain/0/backend/vbd/2/2049'} to
/local/domain/2/device/vbd/2049.
[2006-08-11 10:28:50 xend] DEBUG (DevController:112) DevController:
writing {'domain': 'ce', 'frontend': '/local/domain/2/device/vbd/2049',
'dev': 'sda1', 'state': '1', 'params': 'vgpool/ce', 'mode': 'w',
'frontend-id': '2', 'type': 'phy'} to /local/domain/0/backend/vbd/2/2049.
[2006-08-11 10:28:50 xend] DEBUG (blkif:24) exception looking up device
number for sda2: [Errno 2] No such file or directory: '/dev/sda2'
[2006-08-11 10:28:50 xend] DEBUG (DevController:110) DevController:
writing {'virtual-device': '2050', 'backend-id': '0', 'state': '1',
'backend': '/local/domain/0/backend/vbd/2/2050'} to
/local/domain/2/device/vbd/2050.
[2006-08-11 10:28:50 xend] DEBUG (DevController:112) DevController:
writing {'domain': 'ce', 'frontend': '/local/domain/2/device/vbd/2050',
'dev': 'sda2', 'state': '1', 'params': 'vgpool/ceswap', 'mode': 'w',
'frontend-id': '2', 'type': 'phy'} to /local/domain/0/backend/vbd/2/2050.
[2006-08-11 10:28:50 xend] DEBUG (DevController:110) DevController:
writing {'backend-id': '0', 'mac': '00:16:3E:47:A1:09', 'handle': '0',
'state': '1', 'backend': '/local/domain/0/backend/vif/2/0'} to
/local/domain/2/device/vif/0.
[2006-08-11 10:28:50 xend] DEBUG (DevController:112) DevController:
writing {'domain': 'ce', 'handle': '0', 'script':
'/etc/xen/scripts/vif-bridge', 'ip': '10.0.9.1/16', 'state': '1',
'frontend': '/local/domain/2/device/vif/0', 'mac': '00:16:3E:47:A1:09',
'vifname': 'cevif', 'frontend-id': '2'} to /local/domain/0/backend/vif/2/0.
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:701)
Storing VM details: {'uuid': '082c2415-8bfc-e14e-22f0-29c452b9be24',
'on_reboot': 'restart', 'start_time': '1155284930.3', 'on_poweroff':
'destroy', 'name': 'ce', 'vcpus': '1', 'vcpu_avail': '1', 'memory':
'256', 'on_crash': 'restart', 'image': "(linux (kernel
/boot/vmlinuz-2.6.17-1.2157_FC5xen) (ip
192.168.1.101/24:1.2.3.4:192.168.1.128::ce:eth0:dhcp) (root /dev/sda1)
(args 'fastboot ro'))", 'maxmem': '256'}
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:736)
Storing domain details: {'console/ring-ref': '69352', 'console/port':
'2', 'name': 'ce', 'console/limit': '1048576', 'vm':
'/vm/082c2415-8bfc-e14e-22f0-29c452b9be24', 'domid': '2',
'cpu/0/availability': 'online', 'memory/target': '262144',
'store/ring-ref': '69353', 'store/port': '1'}
[2006-08-11 10:28:50 xend.XendDomainInfo] DEBUG (XendDomainInfo:919)
XendDomainInfo.handleShutdownWatch
[2006-08-11 10:28:50 xend] DEBUG (DevController:143) Waiting for devices
vif.
[2006-08-11 10:28:50 xend] DEBUG (DevController:149) Waiting for 0.
[2006-08-11 10:28:50 xend] DEBUG (DevController:449)
hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
[2006-08-11 10:29:00 xend.XendDomainInfo] DEBUG (XendDomainInfo:1397)
XendDomainInfo.destroy: domid=2
[2006-08-11 10:29:00 xend.XendDomainInfo] DEBUG (XendDomainInfo:1405)
XendDomainInfo.destroyDomain(2)
/var/log/messages (with udev logging set to info):
Aug 11 10:28:50 fik udevd[477]: udev_event_run: seq 783 forked, pid
[4566], 'add' 'xen-backend', 0 seconds old
Aug 11 10:28:50 fik udevd-event[4566]: wait_for_sysfs: file
'/sys/devices/xen-backend/vbd-2-2049/bus' appeared after 0 loops
Aug 11 10:28:50 fik udevd-event[4566]: pass_env_to_socket: passed -1
bytes to socket '/org/kernel/udev/monitor',
Aug 11 10:28:50 fik udevd-event[4566]: run_program:
'/lib/udev/udev_run_hotplugd'
Aug 11 10:28:50 fik udevd-event[4566]: run_program:
'/lib/udev/udev_run_hotplugd' returned with status 0
Aug 11 10:28:50 fik udevd-event[4566]: run_program:
'/lib/udev/udev_run_devd'
Aug 11 10:28:50 fik udevd-event[4566]: run_program:
'/lib/udev/udev_run_devd' returned with status 0
Aug 11 10:28:50 fik udevd-event[4566]: pass_env_to_socket: passed 240
bytes to socket '/org/freedesktop/hal/udev_event',
Aug 11 10:28:50 fik udevd-event[4566]: run_program:
'/etc/xen/scripts/block add'
Aug 11 10:28:50 fik udevd[477]: udev_event_run: seq 784 forked, pid
[4570], 'add' 'xen-backend', 0 seconds old
Aug 11 10:28:50 fik udevd-event[4570]: wait_for_sysfs: file
'/sys/devices/xen-backend/vbd-2-2050/bus' appeared after 0 loops
Aug 11 10:28:50 fik udevd-event[4570]: pass_env_to_socket: passed -1
bytes to socket '/org/kernel/udev/monitor',
Aug 11 10:28:50 fik udevd-event[4570]: run_program:
'/lib/udev/udev_run_hotplugd'
Aug 11 10:28:50 fik udevd-event[4570]: run_program:
'/lib/udev/udev_run_hotplugd' returned with status 0
Aug 11 10:28:50 fik udevd-event[4570]: run_program:
'/lib/udev/udev_run_devd'
Aug 11 10:28:50 fik udevd-event[4570]: run_program:
'/lib/udev/udev_run_devd' returned with status 0
Aug 11 10:28:50 fik udevd-event[4570]: pass_env_to_socket: passed 240
bytes to socket '/org/freedesktop/hal/udev_event',
Aug 11 10:28:50 fik udevd[477]: udev_event_run: seq 785 forked, pid
[4578], 'add' 'xen-backend', 0 seconds old
Aug 11 10:28:50 fik udevd-event[4578]: wait_for_sysfs: file
'/sys/devices/xen-backend/vif-2-0/bus' appeared after 0 loops
Aug 11 10:28:50 fik udevd-event[4578]: pass_env_to_socket: passed -1
bytes to socket '/org/kernel/udev/monitor',
Aug 11 10:28:50 fik udevd-event[4578]: run_program:
'/lib/udev/udev_run_hotplugd'
Aug 11 10:28:50 fik udevd-event[4578]: run_program:
'/lib/udev/udev_run_hotplugd' returned with status 0
Aug 11 10:28:50 fik udevd-event[4578]: run_program:
'/lib/udev/udev_run_devd'
Aug 11 10:28:50 fik udevd-event[4570]: run_program:
'/etc/xen/scripts/block add'
Aug 11 10:28:50 fik udevd-event[4578]: run_program:
'/lib/udev/udev_run_devd' returned with status 0
Aug 11 10:28:50 fik udevd-event[4578]: pass_env_to_socket: passed 231
bytes to socket '/org/freedesktop/hal/udev_event',
Aug 11 10:28:50 fik udevd-event[4578]: udev_event_run: seq 785 finished
Aug 11 10:28:50 fik udevd[477]: udev_done: seq 785, pid [4578] exit with
0, 0 seconds old
Aug 11 10:28:50 fik udevd-event[4566]: run_program:
'/etc/xen/scripts/block' returned with status 0
Aug 11 10:28:50 fik udevd-event[4566]: udev_event_run: seq 783 finished
Aug 11 10:28:50 fik udevd[477]: udev_done: seq 783, pid [4566] exit with
0, 0 seconds old
Aug 11 10:28:51 fik udevd-event[4570]: run_program:
'/etc/xen/scripts/block' returned with status 0
Aug 11 10:28:51 fik udevd-event[4570]: udev_event_run: seq 784 finished
Aug 11 10:28:51 fik udevd[477]: udev_done: seq 784, pid [4570] exit with
0, 1 seconds old
both xend-debug.log and xen-hotplug.log are empty :(
--
Tomas Kouba
More information about the Fedora-xen
mailing list