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@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 :(