[Xen-users] Error: Device 0 (vif) could not be connected. Hotplug scripts not working.

["Radhesh Muralidhar" <radheshkamath@xxxxxxxxx>]

hi,

i am running an fc6 kernel compiled from the src rpm. ACM security is enabled. when i try an xm create -c i get the error:

Error: Device 0 (vif) could not be connected. Hotplug scripts not working.

my udevmonitor does not show any "online" messages. 

tail of xen-hotplug.log:

+ '[' '' ']'
+ '[' /dev/loop109 == '' ']'
+ for dev in '/dev/loop*'
+ '[' '!' -b /dev/loop98 ']'
++ losetup /dev/loop98
+ f=
+ f=
+ '[' '' ']'
+ '[' /dev/loop109 == '' ']'
+ for dev in '/dev/loop*'
+ '[' '!' -b /dev/loop99 ']'
++ losetup /dev/loop99
+ f=
+ f=
+ '[' '' ']'
+ '[' /dev/loop109 == '' ']'
+ '[' /dev/loop109 == '' ']'
+ do_or_die losetup /dev/loop109 /root/xenGuests/xabi-vm1.img
+ losetup /dev/loop109 /root/xenGuests/xabi-vm1.img
+ xenstore_write backend/vbd/13/2049/node /dev/loop109
+ log debug 'Writing backend/vbd/13/2049/node' '/dev/loop109 to xenstore.'
+ local level=debug
+ shift
+ logger -p daemon.debug -- /etc/xen/scripts/block: 'Writing backend/vbd/13/2049/node' '/dev/loop109 to xenstore.'
+ xenstore-write backend/vbd/13/2049/node /dev/loop109
+ write_dev /dev/loop109
+ local mm
++ device_major_minor /dev/loop109
++ stat -L -c %t:%T /dev/loop109
+ mm=7:6d
+ '[' -z 7:6d ']'
+ xenstore_write backend/vbd/13/2049/physical-device 7:6d
+ log debug 'Writing backend/vbd/13/2049/physical-device' '7:6d to xenstore.'
+ local level=debug
+ shift
+ logger -p daemon.debug -- /etc/xen/scripts/block: 'Writing backend/vbd/13/2049/physical-device' '7:6d to xenstore.'
+ xenstore-write backend/vbd/13/2049/physical-device 7:6d
+ success
+ xenstore_write backend/vbd/13/2049/hotplug-status connected
+ log debug 'Writing backend/vbd/13/2049/hotplug-status' 'connected to xenstore.'
+ local level=debug
+ shift
+ logger -p daemon.debug -- /etc/xen/scripts/block: 'Writing backend/vbd/13/2049/hotplug-status' 'connected to xenstore.'
+ xenstore-write backend/vbd/13/2049/hotplug-status connected
+ release_lock block
+ _release_lock /var/run/xen-hotplug/block
+ trap sigerr ERR
+ rm -rf /var/run/xen-hotplug/block
+ exit 0

my xend.log
 :

[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:190) XendDomainInfo.create(['vm', ['name', 'bada-vm1'], ['memory', 64], ['vcpus', 1], ['image', ['linux', ['k
ernel', '/boot/vmlinuz-2.6.18-prepDomU
'], ['root', '/dev/sda1'], ['args', 'ro selinux=0 3'], ['vncunused', 1], ['xauthority', '/root/.Xauthority']]], ['security', [['access_contr
ol', ['policy', 'example.ste.client_v1'], ['label', 'dom_SystemManagement']], ['ssidref', 1]]], ['device', ['vbd', ['uname', 'file:/root/xenGuests/xabi-
vm1.img'], ['dev', 'sda1']
, ['mode', 'w']]], ['device', ['vif']]])
[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:296) parseConfig: config is ['vm', ['name', 'bada-vm1'], ['memory', 64], ['vcpus', 1], ['image', ['linux', ['
kernel', '/boot/vmlinuz-2.6.18-prepDomU'], ['root', '/dev/sda1'], ['args', 'ro selinux=0 3'], ['vncunused', 1], ['xauthority', '/root/.Xauthority']]], ['security', [['access_cont
rol', ['policy', 'example.ste.client_v1'
], ['label', 'dom_SystemManagement']], ['ssidref', 1]]], ['device', ['vbd', ['uname', 'file:/root/xenGuests/xabi-vm1.img'], ['dev', 'sda1'
], ['mode', 'w']]], ['device', ['vif']]]
[2006-11-22 13:00:32 xend.XendDomainInfo
 6199] DEBUG (XendDomainInfo:395) parseConfig: result is {'shadow_memory': None, 'uuid': None, 'on_crash': None, 'on_reboot': None, 'local
time': None, 'image': ['linux', ['kernel', '/boot/vmlinuz-2.6.18-prepDomU'], ['root', '/dev/sda1'], ['args', 'ro selinux=0 3'], ['vncunused', 1], ['xauthority', '/root/.Xauthorit
y']], 'on_poweroff': None, 'bootloader_args': None, 'cpus': None, 'name': 'bada-vm1', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory
': 64, 'device': [('vbd', ['vbd', ['uname', 'file:/root/xenGuests/xabi-
vm1.img'], ['dev', 'sda1'], ['mode', 'w']]), ('vif', ['vif'])], 'security': [['access_control', ['policy', 
'example.ste.client_v1'], ['label', 'dom_SystemManagement']], ['ssidref', 1]], 'bootloader': None, 'cpu': None, 'maxmem': None}
[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:1253) XendDomainInfo.construct: None
[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:1285) XendDomainInfo.initDomain: 13 1.0
[2006-11-22 13:00:32 xend 6199] INFO (image:214) configuring linux guest
[2006-11-22 13:00:32 xend 6199] DEBUG (balloon:127) Balloon: 66072 KiB free; need 65536; done.
[2006-11-22 13:00:32 xend 6199] INFO (image:138) buildDomain os=linux dom=13 vcpus=1
[2006-11-22 13:00:32 xend 6199] DEBUG (image:193) dom            = 13
[2006-11-22 13:00:32 xend 6199] DEBUG (image:194) image          = /boot/vmlinuz-2.6.18-prepDomU
[2006-11-22 13:00:32 xend 6199] DEBUG (image:195) store_evtchn   = 1
[2006-11-22 13:00:32 xend 6199] DEBUG (image:196) console_evtchn = 2
[2006-11-22 13:00:32 xend 6199] DEBUG (image:197) cmdline        =  root=/dev/sda1 ro selinux=0 3
[2006-11-22 13:00:32 xend 6199] DEBUG (image:198) ramdisk        = 
[2006-11-22 13:00:32 xend 6199] DEBUG (image:199) vcpus          = 1
[2006-11-22 13:00:32 xend 6199] DEBUG (image:200) features       = 
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'virtual-device': '2049', 'device-type': 'disk', 'state': '1', 'backend': '/l
ocal/domain/0/backend/vbd/13/2049'} to /local/domain/13/device/vbd/2049.
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:112) DevController: writing {'domain': 'bada-vm1', 'frontend': '/local/domain/13/device/vbd/2049', 'type': 'file', 'online': 
'1', 'dev': 'sda1', 'state': '1', 'params': '/root/xenGuests/xabi-vm1.img', 'mode': 'w', 'acm_ssidref': '9', 'frontend-id': '13', 'acm_label': 'res_LogicalDiskPartition1(hda1)', 
'acm_policy': 'example.ste.client_v1'
} to /local/domain/0/backend/vbd/13/2049.
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:2b:70:4d', 'handle': '0', 'state': '1', 'backend': '/local/d
omain/0/backend/vif/13/0'} to /local/domain/13/device/vif/0.
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:112) DevController: writing {'domain': 'bada-vm1', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'fro
ntend': '/local/domain/13/device/vif/0', 'mac': '00:16:3e:2b:70:4d', 'online': '1', 'frontend-id': '13'} to /local/domain/0/backend/vif/13/0.
[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:712) Storing VM details: {'security/access_control': '((policy 
example.ste.client_v1) (label dom_SystemManage
ment))', 'shadow_memory': '0', 'uuid': 'a73364f3-2152-b20f-a612-f7f325230e3e', 'security/access_control/label': 'dom_SystemManagement', 'on_reboot': 'restart', 'start_time': '116
4218432.24', 'on_poweroff': 'destroy', 'name': 'bada-vm1', 'security/access_control/policy': 'example.ste.client_v1', 'xend/restart_count': '0', 'vcpus': '1', 'security/ssidref':
 '1', 'vcpu_avail': '1', 'memory': '64', 'security': '((access_control (policy 
example.ste.client_v1) (label dom_SystemManagement)) (ssidref 1))', 'on_crash': 'restart', 'image':
 "(linux (kernel /boot/vmlinuz-2.6.18-prepDomU) (root /dev/sda1) (args 'ro selinux=0 3') (vncunused 1) (xauthority /root/.Xauthority))", 'maxmem': '64'}
[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:747) Storing domain details: {'console/ring-ref': '145620', 'console/port': '2', 'name': 'bada-vm1', 'console
/limit': '1048576', 'vm': '/vm/a73364f3-2152-b20f-a612-f7f325230e3e', 'domid': '13', 'cpu/0/availability': 'online', 'memory/target': '65536', 'store/ring-ref': '145621', 'store/
port': '1'}
[2006-11-22 13:00:32 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:933) XendDomainInfo.handleShutdownWatch
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:143) Waiting for devices vif.
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:149) Waiting for 0.
[2006-11-22 13:00:32 xend 6199] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/13/0/hotplug-status.
[2006-11-22 13:02:12 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:1449) XendDomainInfo.destroy
: domid=13
[2006-11-22 13:02:12 xend.XendDomainInfo 6199] DEBUG (XendDomainInfo:1457) XendDomainInfo.destroyDomain(13)

my xend-debug.log:

error: (32, 'Broken pipe')
Traceback (most recent call last):
  File "/usr/lib/python2.4/SocketServer.py", line 463, in process_request_thread
    self.finish_request(request, client_address)
  File "/usr/lib/python2.4/SocketServer.py", line 254, in finish_request
    self.RequestHandlerClass(request, client_address, self)
  File "/usr/lib/python2.4/SocketServer.py", line 521, in __init__
    self.handle()
  File "/usr/lib/python2.4/BaseHTTPServer.py", line 316, in handle
    self.handle_one_request()
  File "/usr/lib/python2.4/BaseHTTPServer.py", line 310, in handle_one_request
    method()
  File "/usr/lib/python2.4/site-packages/xen/util/xmlrpclib2.py", line 66, in do_POST
    self.send_response(200)
  File "/usr/lib/python2.4/BaseHTTPServer.py", line 367, in send_response
    self.wfile.write("%s %d %s\r\n" %
  File "/usr/lib/python2.4/socket.py", line 256, in write
    self.flush()
  File "/usr/lib/python2.4/socket.py", line 243, in flush
    self._sock.sendall(buffer)
error: (32, 'Broken pipe')



any help would be appreciated.


regards,
radhesh


_______________________________________________
Xen-users mailing list
Xen-users@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-users