Comment 4 for bug 330573

Unfortunately this isn't the end of the matter. Udev doesn't appear to wait for the device to be created before it forks the RUN command and so you get random 'no such file or directory' errors.

Does somebody who understands udev better than me know if there is a way to get udev to serialise properly? I'd rather not do it the hard way unless I have to.

Debug trace below:

May 16 19:53:37 neil-laptop kernel: [ 1938.016172] usb 2-1: new full speed USB device using uhci_hcd and address 9
May 16 19:53:37 neil-laptop udevd[859]: seq 2497 queued, 'add' 'usb'
May 16 19:53:37 neil-laptop udevd[859]: seq 2497 forked, pid [7630], 'add' 'usb', 0 seconds old
May 16 19:53:37 neil-laptop udevd-event[7630]: device 0x7f0e8f5e00a0 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2/2-1'
May 16 19:53:37 neil-laptop udevd-event[7630]: device 0x7f0e8f5e0310 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2'
May 16 19:53:37 neil-laptop udevd-event[7630]: device 0x7f0e8f5e0ae0 has devpath '/devices/pci0000:00/0000:00:1d.0'
May 16 19:53:37 neil-laptop udevd-event[7630]: device 0x7f0e8f5e0d90 has devpath '/devices/pci0000:00'
May 16 19:53:37 neil-laptop udevd-event[7630]: RUN '/usr/sbin/madfuload -l -3 -f /usr/share/usb/maudio/ma006100.bin -D $root/$name' /lib/udev/rules.d/42-madfuload.rules:13
May 16 19:53:37 neil-laptop udevd-event[7630]: LINK 'char/189:136' /lib/udev/rules.d/50-udev-default.rules:5
May 16 19:53:37 neil-laptop udevd-event[7630]: MODE 0664 /lib/udev/rules.d/50-udev-default.rules:53
May 16 19:53:37 neil-laptop udevd-event[7630]: NAME 'bus/usb/002/009' /lib/udev/rules.d/50-udev-default.rules:53
May 16 19:53:37 neil-laptop udevd-event[7630]: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
May 16 19:53:37 neil-laptop udevd-event[7630]: create db link (bus/usb/002/009 char/189:136)
May 16 19:53:37 neil-laptop udevd-event[7630]: creating device node '/dev/bus/usb/002/009', devnum=189:136, mode=0664, uid=0, gid=0
May 16 19:53:37 neil-laptop udevd-event[7630]: mknod(/dev/bus/usb/002/009, 020664, (189,136))
May 16 19:53:37 neil-laptop udevd-event[7630]: chmod(/dev/bus/usb/002/009, 020664)
May 16 19:53:37 neil-laptop udevd-event[7630]: chown(/dev/bus/usb/002/009, 0, 0)
May 16 19:53:37 neil-laptop udevd-event[7630]: '/dev/char/189:136' with target '/dev/bus/usb/002/009' has the highest priority 0, create it
May 16 19:53:37 neil-laptop udevd[859]: seq 2498 queued, 'add' 'usb'
May 16 19:53:37 neil-laptop udevd-event[7630]: creating symlink '/dev/char/189:136' to '../bus/usb/002/009'
May 16 19:53:37 neil-laptop udevd-event[7630]: '/usr/sbin/madfuload -l -3 -f /usr/share/usb/maudio/ma006100.bin -D /dev/bus/usb/002/009'
May 16 19:53:37 neil-laptop kernel: [ 1938.168717] usb 2-1: configuration #1 chosen from 1 choice
May 16 19:53:37 neil-laptop madfuload: cannot open /dev/bus/usb/002/009: No such file or directory
May 16 19:53:37 neil-laptop udevd[859]: seq 2499 queued, 'add' 'usb_endpoint'
May 16 19:53:37 neil-laptop udevd-event[7630]: '/usr/sbin/madfuload' returned with status 1
May 16 19:53:37 neil-laptop udevd-event[7630]: passed 324 bytes to monitor 0x7f0e8f5e00a0
May 16 19:53:37 neil-laptop udevd-event[7630]: passed -1 bytes to monitor 0x7f0e8f5cf670
May 16 19:53:37 neil-laptop udevd-event[7630]: seq 2497 exit with 0
May 16 19:53:37 neil-laptop udevd[859]: seq 2497 cleanup, pid [7630], status 0, 0 seconds old
May 16 19:53:37 neil-laptop udevd-event[7632]: RUN '/sbin/modprobe -b $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:5
May 16 19:53:37 neil-laptop udevd-event[7632]: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
May 16 19:53:37 neil-laptop udevd-event[7632]: '/sbin/modprobe -b usb:v0763p2806d0100dcFEdsc01dp00icFEisc01ip00'
May 16 19:53:37 neil-laptop udevd[859]: seq 2498 forked, pid [7632], 'add' 'usb', 0 seconds old
May 16 19:53:37 neil-laptop udevd-event[7634]: device 0x7f0e8f5cf720 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2/2-1/usb_endpoint/usbdev2.9_ep00'
May 16 19:53:37 neil-laptop udevd-event[7634]: device 0x7f0e8f5e0540 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2/2-1'
May 16 19:53:37 neil-laptop udevd-event[7634]: device 0x7f0e8f5e07b0 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2'
May 16 19:53:37 neil-laptop udevd-event[7634]: device 0x7f0e8f5dd590 has devpath '/devices/pci0000:00/0000:00:1d.0'
May 16 19:53:37 neil-laptop udevd-event[7634]: device 0x7f0e8f5dd7e0 has devpath '/devices/pci0000:00'
May 16 19:53:37 neil-laptop udevd-event[7634]: LINK 'char/252:10' /lib/udev/rules.d/50-udev-default.rules:5
May 16 19:53:37 neil-laptop udevd-event[7634]: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
May 16 19:53:37 neil-laptop udevd-event[7634]: no node name set, will use kernel name 'usbdev2.9_ep00'
May 16 19:53:37 neil-laptop udevd-event[7634]: create db link (usbdev2.9_ep00 char/252:10)
May 16 19:53:37 neil-laptop udevd-event[7634]: creating device node '/dev/usbdev2.9_ep00', devnum=252:10, mode=0660, uid=0, gid=0
May 16 19:53:37 neil-laptop udevd-event[7634]: mknod(/dev/usbdev2.9_ep00, 020660, (252,10))
May 16 19:53:37 neil-laptop udevd-event[7634]: chmod(/dev/usbdev2.9_ep00, 020660)
May 16 19:53:37 neil-laptop udevd-event[7634]: chown(/dev/usbdev2.9_ep00, 0, 0)
May 16 19:53:37 neil-laptop udevd-event[7634]: '/dev/char/252:10' with target '/dev/usbdev2.9_ep00' has the highest priority 0, create it
May 16 19:53:37 neil-laptop udevd-event[7634]: creating symlink '/dev/char/252:10' to '../usbdev2.9_ep00'
May 16 19:53:37 neil-laptop udevd-event[7634]: passed 282 bytes to monitor 0x7f0e8f5cf720
May 16 19:53:37 neil-laptop udevd-event[7634]: passed -1 bytes to monitor 0x7f0e8f5cf670
May 16 19:53:37 neil-laptop udevd-event[7634]: seq 2499 exit with 0
May 16 19:53:37 neil-laptop udevd-event[7632]: '/sbin/modprobe' (stderr) 'FATAL: Module usb:v0763p2806d0100dcFEdsc01dp00icFEisc01ip00 not found.'
May 16 19:53:37 neil-laptop udevd-event[7632]: '/sbin/modprobe' returned with status 1
May 16 19:53:37 neil-laptop udevd-event[7632]: passed 318 bytes to monitor 0x7f0e8f5cf720
May 16 19:53:37 neil-laptop udevd-event[7632]: passed -1 bytes to monitor 0x7f0e8f5cf670
May 16 19:53:37 neil-laptop udevd-event[7632]: seq 2498 exit with 0
May 16 19:53:37 neil-laptop udevd[859]: seq 2499 forked, pid [7634], 'add' 'usb_endpoint', 0 seconds old
May 16 19:53:37 neil-laptop udevd[859]: seq 2498 cleanup, pid [7632], status 0, 0 seconds old
May 16 19:53:37 neil-laptop udevd[859]: seq 2499 cleanup, pid [7634], status 0, 0 seconds old