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 :(
--
Tomas Kouba