From mboxrd@z Thu Jan 1 00:00:00 1970 From: Chris Bainbridge Subject: Re: Cannot create domU Date: Tue, 27 Sep 2005 16:33:28 +0100 Message-ID: <623652d5050927083376275f75@mail.gmail.com> References: <7d249e777fd88c08faab74751b15fca6@cl.cam.ac.uk> <9f173d1d7dea2ed7b9d458acd070f552@cl.cam.ac.uk> <623652d505092413006f3950b@mail.gmail.com> <20050926124707.GA13414@kroah.com> <20050926141407.GA15384@kroah.com> <22f2b04bae14c3efa4833ce013cc4542@cl.cam.ac.uk> <20050926150135.GA15781@kroah.com> <20050926212200.GB18670@kroah.com> Reply-To: Chris Bainbridge Mime-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: quoted-printable Return-path: In-Reply-To: <20050926212200.GB18670@kroah.com> Content-Disposition: inline List-Unsubscribe: , List-Post: List-Help: List-Subscribe: , Sender: xen-devel-bounces@lists.xensource.com Errors-To: xen-devel-bounces@lists.xensource.com To: Greg KH Cc: xen-devel List-Id: xen-devel@lists.xenproject.org I have enabled debug in udev and hotplug. From the udevsend man page: udevd takes care of the kernel supplied sequence number and arranges the events for execution in the correct order. Missing sequences del= ay the execution of the following events until a timeout is reached. Is it possible that the events aren't being supplied in the correct order by xen, or there is some race condition, that would cause xen to timeout before the event arrives? Here is a log of /sbin/hotplug, which works: Sep 27 15:55:32 xenmaster store_evtchn =3D 19 Sep 27 15:55:32 xenmaster default.hotplug[2352]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/xen-br0 PATH=3D/bin:/sbin:/usr/sbin:/usr/bin ACTION=3Dadd PWD=3D/etc/hotplug HOME= =3D/ SHLVL=3D2 INTERFACE=3Dxen-br0 DEBUG=3Dyes SEQNUM=3D330 _=3D/bin/env) Sep 27 15:55:32 xenmaster default.hotplug[2352]: invoke /etc/hotplug/net.agent () Sep 27 15:55:32 xenmaster net.agent[2352]: add event not handled Sep 27 15:55:32 xenmaster default.hotplug[2376]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/xen-br0 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 UDEVD_EVENT=3D1 SHLVL=3D1 INTERFACE=3Dxen-b= r0 DEBUG=3Dyes SEQNUM=3D330 _=3D/bin/env) Sep 27 15:55:32 xenmaster default.hotplug[2376]: invoke /etc/hotplug/net.agent () Sep 27 15:55:32 xenmaster net.agent[2376]: add event not handled Sep 27 15:57:00 xenmaster default.hotplug[2398]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvbd DEVPATH=3D/devices/xen-backend/vbd-1-769 PATH=3D/bin:/sbin:/usr/sbin:/usr/bin ACTION=3Dadd PWD=3D/etc/hotplug XENBUS_PATH=3Dbackend/vbd/2b4375b1-c81d-45f3-a227-cd2eaa601ff7/769 HOME=3D/ SHLVL=3D2 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D331 _=3D/b= in/env) Sep 27 15:57:00 xenmaster default.hotplug[2398]: invoke /etc/hotplug/xen-backend.agent () Sep 27 15:57:00 xenmaster default.hotplug[2403]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvbd DEVPATH=3D/devices/xen-backend/vbd-1-769 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 XENBUS_PATH=3Dbackend/vbd/2b4375b1-c81d-45f3-a227-cd2eaa601ff7= /769 UDEVD_EVENT=3D1 SHLVL=3D1 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D331 _=3D/bin/env) Sep 27 15:57:00 xenmaster default.hotplug[2403]: invoke /etc/hotplug/xen-backend.agent () Sep 27 15:57:00 xenmaster default.hotplug[2422]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 script=3D/etc/xen/scripts/vif-bridge vif=3Dvif1.1 PATH=3D/bin:/sbin:/usr/sbin:/usr/bin ACTION=3Donline mac=3Daa:00:00:00:00:1= 1 PWD=3D/etc/hotplug domain=3Dubuntu bridge=3Dxen-br0 XENBUS_PATH=3Dbackend/vif/2b4375b1-c81d-45f3-a227-cd2eaa601ff7/1 HOME=3D/ SHLVL=3D2 PHYSDEVDRIVER=3Dvif DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM= =3D334 _=3D/bin/env) Sep 27 15:57:00 xenmaster default.hotplug[2420]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/vif1.1 PATH=3D/bin:/sbin:/usr/sbin:/usr/bin ACTION=3Dadd PWD=3D/etc/hotplug HOME= =3D/ SHLVL=3D2 INTERFACE=3Dvif1.1 DEBUG=3Dyes SEQNUM=3D333 _=3D/bin/env) Sep 27 15:57:00 xenmaster default.hotplug[2420]: invoke /etc/hotplug/net.agent () Sep 27 15:57:00 xenmaster default.hotplug[2422]: invoke /etc/hotplug/xen-backend.agent () Sep 27 15:57:00 xenmaster xen-backend.agent[2422]: in xen-backend.agent (ACTION=3Donline XENBUS_TYPE=3Dvif) Sep 27 15:57:00 xenmaster xen-backend.agent[2403]: in xen-backend.agent (ACTION=3Dadd XENBUS_TYPE=3Dvbd) Sep 27 15:57:00 xenmaster device vif1.1 entered promiscuous mode Sep 27 15:57:00 xenmaster xen-br0: port 1(vif1.1) entering learning state Sep 27 15:57:00 xenmaster xen-br0: topology change detected, propagating Sep 27 15:57:00 xenmaster xen-br0: port 1(vif1.1) entering forwarding state Sep 27 15:57:00 xenmaster default.hotplug[2417]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 PATH=3D/bin:/sbin:/usr/sbin:/usr/bin ACTION=3Dadd PWD=3D/etc/hotplug XENBUS_PATH=3Dbackend/vif/2b4375b1-c81d-45f3-a227-cd2eaa601ff7/1 HOME=3D/ SHLVL=3D2 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D332 _=3D/bin/env) Sep 27 15:57:00 xenmaster default.hotplug[2417]: invoke /etc/hotplug/xen-backend.agent () Sep 27 15:57:00 xenmaster net.agent[2420]: add event not handled Sep 27 15:57:00 xenmaster xen-backend.agent[2417]: in xen-backend.agent (ACTION=3Dadd XENBUS_TYPE=3Dvif) Sep 27 15:57:00 xenmaster default.hotplug[2453]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 XENBUS_PATH=3Dbackend/vif/2b4375b1-c81d-45f3-a227-cd2eaa601ff7= /1 UDEVD_EVENT=3D1 SHLVL=3D1 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D332 _=3D/bin/env) Sep 27 15:57:00 xenmaster xen-backend.agent[2398]: in xen-backend.agent (ACTION=3Dadd XENBUS_TYPE=3Dvbd) Sep 27 15:57:00 xenmaster default.hotplug[2453]: invoke /etc/hotplug/xen-backend.agent () Sep 27 15:57:00 xenmaster xen-backend.agent[2453]: in xen-backend.agent (ACTION=3Dadd XENBUS_TYPE=3Dvif) Sep 27 15:57:00 xenmaster default.hotplug[2430]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/vif1.1 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 UDEVD_EVENT=3D1 SHLVL=3D1 INTERFACE=3Dvif1.= 1 DEBUG=3Dyes SEQNUM=3D333 _=3D/bin/env) Sep 27 15:57:00 xenmaster default.hotplug[2430]: invoke /etc/hotplug/net.agent () Sep 27 15:57:00 xenmaster net.agent[2430]: add event not handled Sep 27 15:57:00 xenmaster default.hotplug[2500]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 script=3D/etc/xen/scripts/vif-bridge vif=3Dvif1.1 ACTION=3Donline PWD=3D/etc/hotplug UDEV_LOG=3D3 mac=3Daa:00:00:00:00:11 domain=3Dubuntu bridge=3Dxen-br0 XENBUS_PATH=3Dbackend/vif/2b4375b1-c81d-45f3-a227-cd2eaa60= 1ff7/1 UDEVD_EVENT=3D1 SHLVL=3D1 PHYSDEVDRIVER=3Dvif DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D334 _=3D/bin/env) And udevsend, which fails: Sep 27 16:08:51 xenmaster store_evtchn =3D 19 Sep 27 16:08:51 xenmaster default.hotplug[2297]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/xen-br0 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 UDEVD_EVENT=3D1 SHLVL=3D1 INTERFACE=3Dxen-b= r0 DEBUG=3Dyes SEQNUM=3D330 _=3D/bin/env) Sep 27 16:08:51 xenmaster default.hotplug[2297]: invoke /etc/hotplug/net.agent () Sep 27 16:08:51 xenmaster net.agent[2297]: add event not handled Sep 27 16:09:17 xenmaster default.hotplug[2332]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvbd DEVPATH=3D/devices/xen-backend/vbd-1-769 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 XENBUS_PATH=3Dbackend/vbd/3e2182e4-998e-4191-863c-a60d0f1d6962= /769 UDEVD_EVENT=3D1 SHLVL=3D1 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D331 _=3D/bin/env) Sep 27 16:09:17 xenmaster default.hotplug[2332]: invoke /etc/hotplug/xen-backend.agent () Sep 27 16:09:17 xenmaster xen-backend.agent[2332]: in xen-backend.agent (ACTION=3Dadd XENBUS_TYPE=3Dvbd) Sep 27 16:09:17 xenmaster default.hotplug[2337]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 XENBUS_PATH=3Dbackend/vif/3e2182e4-998e-4191-863c-a60d0f1d6962= /1 UDEVD_EVENT=3D1 SHLVL=3D1 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D332 _=3D/bin/env) Sep 27 16:09:17 xenmaster default.hotplug[2337]: invoke /etc/hotplug/xen-backend.agent () Sep 27 16:09:17 xenmaster default.hotplug[2344]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/vif1.1 ACTION=3Dadd PWD=3D/etc/hotplug UDEV_LOG=3D3 UDEVD_EVENT=3D1 SHLVL=3D1 INTERFACE=3Dvif1.= 1 DEBUG=3Dyes SEQNUM=3D333 _=3D/bin/env) Sep 27 16:09:17 xenmaster xen-backend.agent[2337]: in xen-backend.agent (ACTION=3Dadd XENBUS_TYPE=3Dvif) Sep 27 16:09:17 xenmaster default.hotplug[2344]: invoke /etc/hotplug/net.agent () Sep 27 16:09:17 xenmaster default.hotplug[2375]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 script=3D/etc/xen/scripts/vif-bridge vif=3Dvif1.1 ACTION=3Donline PWD=3D/etc/hotplug UDEV_LOG=3D3 mac=3Daa:00:00:00:00:11 domain=3Dubuntu bridge=3Dxen-br0 XENBUS_PATH=3Dbackend/vif/3e2182e4-998e-4191-863c-a60d0f1d= 6962/1 UDEVD_EVENT=3D1 SHLVL=3D1 PHYSDEVDRIVER=3Dvif DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D334 _=3D/bin/env) Sep 27 16:09:17 xenmaster net.agent[2344]: add event not handled Sep 27 16:09:17 xenmaster default.hotplug[2375]: invoke /etc/hotplug/xen-backend.agent () Sep 27 16:09:17 xenmaster xen-backend.agent[2375]: in xen-backend.agent (ACTION=3Donline XENBUS_TYPE=3Dvif) Sep 27 16:09:27 xenmaster default.hotplug[2412]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvif DEVPATH=3D/devices/xen-backend/vif-1-1 ACTION=3Dremove PWD=3D/etc/hotplug UDEV_LOG=3D3 XENBUS_PATH=3Dbackend/vif/3e2182e4-998e-4191-863c-a60d0f1d6962= /1 UDEVD_EVENT=3D1 SHLVL=3D1 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D335 _=3D/bin/env) Sep 27 16:09:27 xenmaster default.hotplug[2414]: arguments (xen-backend) env (SUBSYSTEM=3Dxen-backend OLDPWD=3D/ XENBUS_TYPE=3Dvbd DEVPATH=3D/devices/xen-backend/vbd-1-769 ACTION=3Dremove PWD=3D/etc/hotplug UDEV_LOG=3D3 XENBUS_PATH=3Dbackend/vbd/3e2182e4-998e-4191-863c-a60d0f1d6962= /769 UDEVD_EVENT=3D1 SHLVL=3D1 DEBUG=3Dyes PHYSDEVBUS=3Dxen-backend SEQNUM=3D336 _=3D/bin/env) Sep 27 16:09:27 xenmaster default.hotplug[2412]: invoke /etc/hotplug/xen-backend.agent () Sep 27 16:09:28 xenmaster default.hotplug[2414]: invoke /etc/hotplug/xen-backend.agent () Sep 27 16:09:28 xenmaster xen-backend.agent[2412]: in xen-backend.agent (ACTION=3Dremove XENBUS_TYPE=3Dvif) Sep 27 16:09:28 xenmaster xen-backend.agent[2414]: in xen-backend.agent (ACTION=3Dremove XENBUS_TYPE=3Dvbd) Sep 27 16:09:28 xenmaster default.hotplug[2448]: arguments (net) env (SUBSYSTEM=3Dnet OLDPWD=3D/ DEVPATH=3D/class/net/vif1.1 ACTION=3Dremove PWD=3D/etc/hotplug UDEV_LOG=3D3 UDEVD_EVENT=3D1 SHLVL=3D1 INTERFACE=3Dvif1.= 1 DEBUG=3Dyes SEQNUM=3D337 _=3D/bin/env) Sep 27 16:09:28 xenmaster default.hotplug[2448]: invoke /etc/hotplug/net.agent () Sep 27 16:09:28 xenmaster net.agent[2448]: remove event not handled