ifuse refuses to mount iphone using udev rules

dimitrodon · 2012-08-21 05:06:52

Hi,

I'm using ifuse to mount iphone. It does its job nicely if launched from the terminal:

ifuse /home/dmytro/iphone

However, it doesn't want to mount the device automatically form the udev rule found on wiki. Couple of weeks ago it used to randomly work or not work and recently stopped working at all. The rule itself is working (the folder is created and removed when needed), but the /usr/bin/ifuse for some reason doesn't mount the iphone from that rule.

I checked all files in /var/log - everything's clean, no mention of the ifuse, so no clues at all

1) how to debug it? I can't see if ifuse outputs anything when ran from udev's rule
2) I migrated to systemd today. Could it be somehow connected?
3) any other suggestions?

Thanks in advance

Last edited by dimitrodon (2012-08-21 13:31:13)

dimitrodon · 2012-08-21 13:31:35

UPD: Changed udev log-priority to 'debug' and got some ifuse-related output in journalctl:

Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: validate module index
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1915 queued, 'add' 'usb'
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1915 forked new worker [25919]
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: seq 1915 running
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: device 0x1ab0300 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2'
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: no db file to read /run/udev/data/c189:137: No such file or directory
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: RUN '/usr/share/virtualbox/VBoxCreateUSBNode.sh $major $minor $attr{bDeviceClass} vboxusers' /usr/lib/udev/rules.d/10-vboxdrv.rules:3
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: IMPORT builtin 'usb_id' /usr/lib/udev/rules.d/40-gphoto.rules:11
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1916 queued, 'add' 'usb'
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: ID_VENDOR=Apple_Inc.
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: ID_VENDOR_ENC=Apple\x20Inc.
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: ID_VENDOR_ID=05ac
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: ID_MODEL=iPhone
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: ID_MODEL_ENC=iPhone
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: MODE 0664 /usr/lib/udev/rules.d/50-udev-default.rules:45
Aug 22 15:14:43 dmytro-pc systemd-udevd[25919]: IMPORT builtin 'path_id' /usr/lib/udev/rules.d/71-seat.rules:43
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1917 queued, 'remove' 'usb'
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1918 queued, 'add' 'usb'
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1919 queued, 'add' 'usb'
Aug 22 15:14:43 dmytro-pc systemd-udevd[116]: seq 1920 queued, 'add' 'usb'
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1921 queued, 'add' 'net'
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1922 queued, 'add' 'queues'
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1923 queued, 'add' 'queues'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: IMPORT '/usr/lib/udev/iphone-set-info' /usr/lib/udev/rules.d/90-libgpod.rules:19
Aug 22 15:14:45 dmytro-pc systemd-udevd[25920]: starting '/usr/lib/udev/iphone-set-info'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/lib/udev/iphone-set-info' [25920] exit with return code 0
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: RUN '/bin/mkdir -p '/media/%E{dir_name}'' /etc/udev/rules.d/91-iOS-automount.rules:8
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: RUN '/usr/bin/ifuse '/media/%E{dir_name}' -u '%s{serial}' -o sync,allow_other' /etc/udev/rules.d/91-iOS-automount.rules:8
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: handling device node '/dev/bus/usb/002/010', devnum=c189:137, mode=0664, uid=140, gid=0
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: set permissions /dev/bus/usb/002/010, 020664, uid=140, gid=0
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: creating symlink '/dev/char/189:137' to '../bus/usb/002/010'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: created db file '/run/udev/data/c189:137' for '/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25922]: starting '/usr/share/virtualbox/VBoxCreateUSBNode.sh 189 137 00 vboxusers'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/share/virtualbox/VBoxCreateUSBNode.sh 189 137 00 vboxusers' [25922] exit with return code 0
Aug 22 15:14:45 dmytro-pc systemd-udevd[25933]: starting '/bin/mkdir -p '/media/iphone''
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/bin/mkdir -p '/media/iphone'' [25933] exit with return code 0
Aug 22 15:14:45 dmytro-pc systemd-udevd[25934]: starting '/usr/bin/ifuse '/media/iphone' -u 'a8429c1919a1e27c6126c2ac40f269b82ea3bb1b' -o sync,allow_other'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/bin/ifuse '/media/iphone' -u 'a8429c1919a1e27c6126c2ac40f269b82ea3bb1b' -o sync,allow_other'(err) 'usbmuxd_get_device_list: error opening socket!'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/bin/ifuse '/media/iphone' -u 'a8429c1919a1e27c6126c2ac40f269b82ea3bb1b' -o sync,allow_other'(err) 'No device found, is it connected?'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/bin/ifuse '/media/iphone' -u 'a8429c1919a1e27c6126c2ac40f269b82ea3bb1b' -o sync,allow_other'(err) 'If it is make sure that your user has permissions to access the raw usb device.'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/bin/ifuse '/media/iphone' -u 'a8429c1919a1e27c6126c2ac40f269b82ea3bb1b' -o sync,allow_other'(err) 'If you're still having issues try unplugging the device and reconnecting it.'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: '/usr/bin/ifuse '/media/iphone' -u 'a8429c1919a1e27c6126c2ac40f269b82ea3bb1b' -o sync,allow_other' [25934] exit with return code 1
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: passed -1 bytes to netlink monitor 0x1aacc40
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: seq 1915 processed with 0
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1915 done with 0
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: passed 334 bytes to netlink monitor 0x1aa3110
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1918 forked new worker [25937]
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1919 forked new worker [25938]
Aug 22 15:14:45 dmytro-pc systemd-udevd[116]: seq 1920 forked new worker [25939]
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: seq 1916 running
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: device 0x1ac51b0 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: device 0x1ab6800 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2/2-1'
Aug 22 15:14:45 dmytro-pc systemd-udevd[25919]: device 0x1aa3620 has devpath '/devices/pci0000:00/0000:00:1d.0/usb2'
...
Aug 22 15:14:45 dmytro-pc usbmuxd[25936]: [15:14:45.310][3] usbmuxd v1.0.8 starting up
Aug 22 15:14:45 dmytro-pc usbmuxd[25936]: [15:14:45.311][3] Successfully dropped privileges to 'usbmux'
Aug 22 15:14:45 dmytro-pc usbmuxd[25936]: [15:14:45.313][3] Connecting to new device on location 0x2000a as ID 1
Aug 22 15:14:45 dmytro-pc usbmuxd[25936]: [15:14:45.313][3] Initialization complete
Aug 22 15:14:45 dmytro-pc usbmuxd[25936]: [15:14:45.314][3] Connected to v1.0 device 1 on location 0x2000a with serial number a8429c1919a1e27c6126c2ac40f269b82ea3bb1b
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: cleanup idle workers
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: validate module index
Aug 22 15:14:48 dmytro-pc systemd-udevd[25919]: unload module index
Aug 22 15:14:48 dmytro-pc systemd-udevd[25939]: unload module index
Aug 22 15:14:48 dmytro-pc systemd-udevd[25937]: unload module index
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25919] exit
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25919] cleaned up
Aug 22 15:14:48 dmytro-pc systemd-udevd[25938]: unload module index
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25939] exit
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25939] cleaned up
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25937] exit
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25937] cleaned up
Aug 22 15:14:48 dmytro-pc systemd-udevd[116]: worker [25938] exit

So it seems to be unable to find device. But this is weird, since the iphone still mounts manually (amarok also have no trouble mounting it). Only udev can't do it. Is there something wrond with execution order?

PS: installing usbmuxd-git from AUR makes the ifuse command go through, although the mount point is inaccessable: "transport endpoint is not connected"

65kid · 2012-08-21 13:47:48

the udev rule proposed on the wiki is, well, crap. you are not supposed to use udev rules to mount devices, there are not meant to be used for complex tasks like this.

see also https://bbs.archlinux.org/viewtopic.php?id=143023

dimitrodon · 2012-08-21 15:10:05

Sounds fare, thanks for the info. I'll then just go with manual mount, since there's no urgent need for iphone automounting and it's not worth messing with various wrappers. Should the theme be marked as solved?

graysky · 2012-08-21 19:17:24

@65kid - if the info on the wiki is crap, please edit it out if you haven't done so already.

Arch Linux

#1 2012-08-21 05:06:52

ifuse refuses to mount iphone using udev rules

#2 2012-08-21 13:31:35

Re: ifuse refuses to mount iphone using udev rules

#3 2012-08-21 13:47:48

Re: ifuse refuses to mount iphone using udev rules

#4 2012-08-21 15:10:05

Re: ifuse refuses to mount iphone using udev rules

#5 2012-08-21 19:17:24

Re: ifuse refuses to mount iphone using udev rules

Board footer