Once more udev/hotplug and usb-storage devices

Once more udev/hotplug and usb-storage devices

[Elias Oltmanns]

--=-=-
Hi all,

unfortunately, I haven't quite worked out a reliable way to reproduce
my problem yet but I definitely haven't experienced this problem
before I switched to udev; but then I've changed quite a lot of other
things at the same time (change from debian/sarge to ubuntu).

On linux-2.6.11.7 with udev, hotplug, dbus-1 and hald but no X running,
I plug in my usb stick. If I'm lucky, nothing unexpected happens and I
can mount /dev/sda1 afterwards. Otherwise, a whole bunch of error
messages is generated and there is no way to access the media. In
fact, udev removes the freshly created device nodes even before I
remove the usb stick. In the latter case, removing and reinserting the
usb stick resolves the problem. However, a stale scsi_eh_0 process
remains and cannot be killed but apparently doesn't affect the
system's stability either. The relevant part of /var/log/syslog is
attached below.

As the explicit mentioning of scsi_eh_0 suggests, I haven't managed to
reproduce this problem if the usb stick has been inserted before. In
fact, I did achieve this but it involved unloading usb_storage, sd_mod
and scsi_mod so I ended up with a stale scsi_eh_0 again. So far, it
only appeared when hald wsa running at the time but I didn't try very
thoroughly without dbus-1 and hald and, as I said before, I can't
reproduce this problem reliably.

All this made me wonder whether it might be a timing problem. Please
tell me, if I can be of any help by giving you further information or
testing something else.

Kind regards,

Elias

-------- /var/log/syslog --------

--=-=-Content-Disposition: inline; filename=messages

Apr 25 20:25:12 denkblock kernel: usb 4-3: new high speed USB device using ehci_hcd and address 3
Apr 25 20:25:14 denkblock kernel: SCSI subsystem initialized
Apr 25 20:25:14 denkblock kernel: Initializing USB Mass Storage driver...
Apr 25 20:25:14 denkblock usb.agent[6537]:      usb-storage: loaded successfully
Apr 25 20:25:14 denkblock kernel: scsi0 : SCSI emulation for USB Mass Storage devices
Apr 25 20:25:14 denkblock kernel: usbcore: registered new driver usb-storage
Apr 25 20:25:14 denkblock kernel: USB Mass Storage support registered.
Apr 25 20:25:14 denkblock kernel: usb-storage: device found at 3
Apr 25 20:25:14 denkblock kernel: usb-storage: waiting for device to settle before scanning
Apr 25 20:25:19 denkblock kernel:   Vendor:           Model: USB DISK 12X      Rev: PMAP
Apr 25 20:25:19 denkblock kernel:   Type:   Direct-Access                      ANSI SCSI revision: 00
Apr 25 20:25:19 denkblock kernel: usb-storage: device scan complete
Apr 25 20:25:19 denkblock kernel: SCSI device sda: 507904 512-byte hdwr sectors (260 MB)
Apr 25 20:25:19 denkblock kernel: sda: assuming Write Enabled
Apr 25 20:25:19 denkblock kernel: sda: assuming drive cache: write through
Apr 25 20:25:19 denkblock kernel: SCSI device sda: 507904 512-byte hdwr sectors (260 MB)
Apr 25 20:25:19 denkblock kernel: sda: assuming Write Enabled
Apr 25 20:25:19 denkblock kernel: sda: assuming drive cache: write through
Apr 25 20:25:19 denkblock scsi.agent[6660]:      sd_mod: loaded sucessfully
Apr 25 20:25:19 denkblock kernel:  sda: sda1 sda2
Apr 25 20:25:19 denkblock kernel: Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
Apr 25 20:25:19 denkblock udev[6697]: configured rule in '/etc/udev/rules.d/udev.rules' at line 27 applied, 'sda' becomes '%k'
Apr 25 20:25:19 denkblock udev[6697]: creating device node '/dev/sda'
Apr 25 20:25:19 denkblock udev[6715]: configured rule in '/etc/udev/rules.d/udev.rules' at line 27 applied, 'sda1' becomes '%k'
Apr 25 20:25:19 denkblock udev[6715]: creating device node '/dev/sda1'
Apr 25 20:25:24 denkblock kernel: usb 4-3: udev timed out on ep0in
Apr 25 20:25:29 denkblock udev[6716]: configured rule in '/etc/udev/rules.d/udev.rules' at line 27 applied, 'sda2' becomes '%k'
Apr 25 20:25:29 denkblock udev[6716]: creating device node '/dev/sda2'
Apr 25 20:25:29 denkblock kernel: usb 4-3: udev timed out on ep0in
Apr 25 20:26:59 denkblock kernel: usb 4-3: reset high speed USB device using ehci_hcd and address 3
Apr 25 20:27:00 denkblock kernel: usb 4-3: scsi_eh_0 timed out on ep0in
Apr 25 20:27:00 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:06 denkblock kernel: usb 4-3: scsi_eh_0 timed out on ep0in
Apr 25 20:27:06 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:06 denkblock kernel: usb 4-3: reset high speed USB device using ehci_hcd and address 3
Apr 25 20:27:07 denkblock kernel: usb 4-3: scsi_eh_0 timed out on ep0in
Apr 25 20:27:07 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:12 denkblock kernel: usb 4-3: scsi_eh_0 timed out on ep0in
Apr 25 20:27:12 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:12 denkblock udev[6757]: removing device node '/dev/sda2'
Apr 25 20:27:12 denkblock udev[6767]: removing device node '/dev/sda1'
Apr 25 20:27:12 denkblock kernel: scsi: Device offlined - not ready after error recovery: host 0 channel 0 id 0 lun 0
Apr 25 20:27:12 denkblock kernel: SCSI error : <0 0 0 0> return code = 0x50000
Apr 25 20:27:12 denkblock kernel: end_request: I/O error, dev sda, sector 0
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 0
Apr 25 20:27:12 denkblock kernel: scsi0 (0:0): rejecting I/O to offline device
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 1
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 2
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 3
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 4
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 5
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 6
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 7
Apr 25 20:27:12 denkblock kernel: usb 4-3: USB disconnect, address 3
Apr 25 20:27:12 denkblock kernel: scsi0 (0:0): rejecting I/O to dead device
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 0
Apr 25 20:27:12 denkblock kernel: scsi0 (0:0): rejecting I/O to dead device
Apr 25 20:27:12 denkblock kernel: Buffer I/O error on device sda, logical block 64
Apr 25 20:27:12 denkblock kernel: scsi0 (0:0): rejecting I/O to dead device
Apr 25 20:27:12 denkblock udev[6807]: removing device node '/dev/sda'
Apr 25 20:27:12 denkblock kernel: usb 4-3: new high speed USB device using ehci_hcd and address 4
Apr 25 20:27:13 denkblock kernel: usb 4-3: khubd timed out on ep0in
Apr 25 20:27:13 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:18 denkblock kernel: usb 4-3: khubd timed out on ep0in
Apr 25 20:27:18 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:19 denkblock kernel: usb 4-3: new high speed USB device using ehci_hcd and address 5
Apr 25 20:27:20 denkblock kernel: usb 4-3: khubd timed out on ep0in
Apr 25 20:27:20 denkblock kernel: usb 4-3: device descriptor read/64, error -110
Apr 25 20:27:25 denkblock kernel: usb 4-3: khubd timed out on ep0in
Apr 25 20:27:25 denkblock kernel: usb 4-3: device descriptor read/64, error -110

--=-=-
-------- /var/log/syslog --------

--=-=-=--



-------------------------------------------------------
SF email is sponsored by - The IT Product Guide
Read honest & candid reviews on hundreds of IT Products from real users.
Discover which products truly live up to the hype. Start reading now.
http://ads.osdn.com/?ad_ide95&alloc_id\x14396&op=click
_______________________________________________
Linux-hotplug-devel mailing list  http://linux-hotplug.sourceforge.net
Linux-hotplug-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/linux-hotplug-devel

Re: Once more udev/hotplug and usb-storage devices

[E. Oltmanns]

Elias Oltmanns <oltmanns@uni-bonn.de> wrote:
[...]
> On linux-2.6.11.7 with udev, hotplug, dbus-1 and hald but no X running,
> I plug in my usb stick. If I'm lucky, nothing unexpected happens and I
> can mount /dev/sda1 afterwards. Otherwise, a whole bunch of error
> messages is generated and there is no way to access the media. In
> fact, udev removes the freshly created device nodes even before I
> remove the usb stick. In the latter case, removing and reinserting the
> usb stick resolves the problem. However, a stale scsi_eh_0 process
> remains and cannot be killed but apparently doesn't affect the
> system's stability either. The relevant part of /var/log/syslog is
> attached below.
>
> As the explicit mentioning of scsi_eh_0 suggests, I haven't managed to
> reproduce this problem if the usb stick has been inserted before. In
> fact, I did achieve this but it involved unloading usb_storage, sd_mod
> and scsi_mod so I ended up with a stale scsi_eh_0 again. So far, it
> only appeared when hald wsa running at the time but I didn't try very
> thoroughly without dbus-1 and hald and, as I said before, I can't
> reproduce this problem reliably.

Just for the record:
While I managed to reproduce the described problem on reinsertion of
the usb stick (without unloading the kernel modules first), the whole
problem never appeared when hald wasn't running at the time.
Naturally, I thought that hal was the problem. However, after an
upgrade of udev from 0.050 to 0.056 the problem didn't occur anymore -
even with hald running! Sorry that I didn't think about upgrading
earlier. Hopefully, this really is the reason.

Regards,

Elias


-------------------------------------------------------
This SF.Net email is sponsored by: NEC IT Guy Games.
Get your fingers limbered up and give it your best shot. 4 great events, 4
opportunities to win big! Highest score wins.NEC IT Guy Games. Play to
win an NEC 61 plasma display. Visit http://www.necitguy.com/?r 
_______________________________________________
Linux-hotplug-devel mailing list  http://linux-hotplug.sourceforge.net
Linux-hotplug-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/linux-hotplug-devel