Nokia USB Mass Storage disconnects randomly

[Alexander Jones]

Binary package hint: linux

Tested on two physically separate 5300s with different memory cards.

Connecting a Nokia 5300 in USB Mass Storage mode works fine in a file browser. When Rhythmbox is running, however, it will attempt to probe the contents of the volume and will eventually stop finding new files. A minute or so later, the connection appears to terminate with a notification reading "Unsafe device removal". It seems to me that this may be a bug in the Linux kernel, or perhaps just an adverse response to something from the phone hardware.

If anyone knows of any way to further debug this, I'd appreciate some help! For now, here is a kernel log detailing various operations with the storage device. I'm sorry it couldn't be more specific, I'm doing this remotely!

Cheers

ProblemType: Bug
Architecture: i386
Date: Wed Jun 6 18:44:45 2007
DistroRelease: Ubuntu 7.04
Uname: Linux infinity 2.6.20-16-386 #2 Wed May 23 01:42:57 UTC 2007 i686 GNU/Linux

[Chuck Short (zulcss)]

Please attach the output of dmesg and lsusb -v.

Thanks
chuck

[Gheorghe Pop (pop-gheorghe)]

I have a nokia 6120 and I have the same problems. When i insert the USB cable into the device evrything works corectly. But if i start to explore with nautilus the connection appears to terminate with a notification reading "Unsafe device removal" and then reconects and then disconects and then reconects again.....
Here is dmesg for connecting the device and disconects right away:
[ 1031.987212] usb 1-2: new full speed USB device using uhci_hcd and address 4
[ 1032.159450] usb 1-2: configuration #1 chosen from 1 choice
[ 1032.304647] usbcore: registered new interface driver libusual
[ 1032.342446] Initializing USB Mass Storage driver...
[ 1032.346716] scsi0 : SCSI emulation for USB Mass Storage devices
[ 1032.348779] usbcore: registered new interface driver usb-storage
[ 1032.348788] USB Mass Storage support registered.
[ 1032.348970] usb-storage: device found at 4
[ 1032.348972] usb-storage: waiting for device to settle before scanning
[ 1032.722886] usb 1-2: USB disconnect, address 4
[ 1034.697923] usb 1-2: new full speed USB device using uhci_hcd and address 5
[ 1034.869565] usb 1-2: configuration #1 chosen from 1 choice
[ 1034.907351] scsi1 : SCSI emulation for USB Mass Storage devices
[ 1034.907511] usb-storage: device found at 5
[ 1034.907514] usb-storage: waiting for device to settle before scanning
[ 1035.449599] usb 1-2: USB disconnect, address 5
[ 1037.448634] usb 1-2: new full speed USB device using uhci_hcd and address 6
[ 1037.624664] usb 1-2: configuration #1 chosen from 1 choice
[ 1037.660989] scsi2 : SCSI emulation for USB Mass Storage devices
[ 1037.661288] usb-storage: device found at 6
[ 1037.661291] usb-storage: waiting for device to settle before scanning
[ 1038.200306] usb 1-2: USB disconnect, address 6
[ 1040.199339] usb 1-2: new full speed USB device using uhci_hcd and address 7
[ 1040.369741] usb 1-2: configuration #1 chosen from 1 choice
[ 1040.407176] scsi3 : SCSI emulation for USB Mass Storage devices
[ 1040.407653] usb-storage: device found at 7
[ 1040.407656] usb-storage: waiting for device to settle before scanning
[ 1040.951009] usb 1-2: USB disconnect, address 7
[ 1042.926054] usb 1-2: new full speed USB device using uhci_hcd and address 8
[ 1043.097847] usb 1-2: configuration #1 chosen from 1 choice
[ 1043.113502] scsi4 : SCSI emulation for USB Mass Storage devices
[ 1043.113930] usb-storage: device found at 8
[ 1043.113933] usb-storage: waiting for device to settle before scanning
[ 1043.677722] usb 1-2: USB disconnect, address 8
[ 1045.676767] usb 1-2: new full speed USB device using uhci_hcd and address 9
[ 1045.845941] usb 1-2: configuration #1 chosen from 1 choice
[ 1045.868681] scsi5 : SCSI emulation for USB Mass Storage devices
[ 1045.869096] usb-storage: device found at 9
[ 1045.869099] usb-storage: waiting for device to settle before scanning
[ 1046.428430] usb 1-2: USB disconnect, address 9
[ 1048.427458] usb 1-2: new full speed USB device using uhci_hcd and address 10
[ 1048.596015] usb 1-2: configuration #1 chosen from 1 choice
[ 1048.624796] scsi6 : SCSI emulation for USB Mass Storage devices
[ 1048.626626] usb-storage: device found at 10
[ 1048.626632] usb-storage: waiting for device to settle b...

[Gheorghe Pop (pop-gheorghe)]

[ 1091.297148] usb 1-2: new full speed USB device using uhci_hcd and address 4
[ 1092.090637] usb 1-2: USB disconnect, address 4
[ 1093.912190] SCSI subsystem initialized
[ 1094.045277] usb 1-2: new full speed USB device using uhci_hcd and address 5
[ 1094.130665] Initializing USB Mass Storage driver...
[ 1094.184696] scsi0 : SCSI emulation for USB Mass Storage devices
[ 1094.185067] usb-storage: device found at 5
[ 1094.185070] usb-storage: waiting for device to settle before scanning
[ 1094.185399] usbcore: registered new driver usb-storage
[ 1094.185490] USB Mass Storage support registered.
[ 1099.185614] Vendor: Nokia Model: S60 Rev: 1.0
[ 1099.185627] Type: Direct-Access ANSI SCSI revision: 00
[ 1099.190784] usb-storage: device scan complete
[ 1099.217797] Driver 'sd' needs updating - please use bus_type methods
[ 1099.227559] SCSI device sda: 1002263 512-byte hdwr sectors (513 MB)
[ 1099.230555] sda: Write Protect is off
[ 1099.230625] sda: Mode Sense: 03 00 00 00
[ 1099.230692] sda: assuming drive cache: write through
[ 1099.295512] SCSI device sda: 1002263 512-byte hdwr sectors (513 MB)
[ 1099.298505] sda: Write Protect is off
[ 1099.298573] sda: Mode Sense: 03 00 00 00
[ 1099.298642] sda: assuming drive cache: write through
[ 1099.298741] sda:
[ 1099.390472] sd 0:0:0:0: Attached scsi removable disk sda
[ 1099.403378] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 1100.151933] FAT: utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
[ 1104.811962] usb 1-2: reset full speed USB device using uhci_hcd and address 5[ 1104.947580] sd 0:0:0:0: Device not ready.
[ 1104.947587] end_request: I/O error, dev sda, sector 1
[ 1104.947772] FAT: FAT read failed (blocknr 1)
[ 1104.953571] sd 0:0:0:0: Device not ready.
[ 1104.953575] end_request: I/O error, dev sda, sector 1
[ 1104.953739] FAT: FAT read failed (blocknr 1)
[ 1104.959637] sd 0:0:0:0: Device not ready.
[ 1104.959645] end_request: I/O error, dev sda, sector 1
[ 1104.959825] FAT: FAT read failed (blocknr 1)
[ 1104.965562] sd 0:0:0:0: Device not ready.
[ 1104.965566] end_request: I/O error, dev sda, sector 1
[ 1104.965728] FAT: FAT read failed (blocknr 1)
[ 1104.971556] sd 0:0:0:0: Device not ready.
[ 1104.971559] end_request: I/O error, dev sda, sector 1
[ 1104.971703] FAT: FAT read failed (blocknr 1)
[ 1104.977552] sd 0:0:0:0: Device not ready.
[ 1104.977555] end_request: I/O error, dev sda, sector 1
[ 1104.977715] FAT: FAT read failed (blocknr 1)
[ 1104.983548] sd 0:0:0:0: Device not ready.
[ 1104.983552] end_request: I/O error, dev sda, sector 1
[ 1104.983711] FAT: FAT read failed (blocknr 1)
[ 1104.989543] sd 0:0:0:0: Device not ready.
[ 1104.989546] end_request: I/O error, dev sda, sector 2
[ 1104.989702] FAT: FAT read failed (blocknr 2)
[ 1105.097481] sd 0:0:0:0: Device not ready.
[ 1105.097490] end_request: I/O error, dev sda, sector 1
[ 1105.097664] FAT: FAT read failed (blocknr 1)
[ 1105.581451] usb 1-2: USB disconnect, address 5
[ 1106.260058] 0:0:0:0: rejecting I/O to dead device
[ 1106.286955] usb 1-2: new full speed USB device using uhci_hcd and address 6
[ 1106.423920] scsi1 : SCSI emulati...

[yaztromo (tromo)]

I also have the same problem when trying to copy files from my Nokia 6288 in data transfer mode.

I have tried changing max_sectors_kb to 32 and 64 with no luck.

Dmesg output

[ 2744.768828] usb 1-1: new full speed USB device using ohci_hcd and address 15
[ 2744.993654] usb 1-1: configuration #1 chosen from 1 choice
[ 2744.996716] scsi7 : SCSI emulation for USB Mass Storage devices
[ 2744.997048] usb-storage: device found at 15
[ 2744.997052] usb-storage: waiting for device to settle before scanning
[ 2749.997415] usb-storage: device scan complete
[ 2750.004443] scsi 7:0:0:0: Direct-Access Nokia Nokia 6288 0000 PQ: 0 ANSI: 4
[ 2750.015382] sd 7:0:0:0: [sdb] 1000449 512-byte hardware sectors (512 MB)
[ 2750.032379] sd 7:0:0:0: [sdb] Test WP failed, assume Write Enabled
[ 2750.032395] sd 7:0:0:0: [sdb] Assuming drive cache: write through
[ 2750.051368] sd 7:0:0:0: [sdb] 1000449 512-byte hardware sectors (512 MB)
[ 2750.069353] sd 7:0:0:0: [sdb] Test WP failed, assume Write Enabled
[ 2750.069369] sd 7:0:0:0: [sdb] Assuming drive cache: write through
[ 2750.069379] sdb:
[ 2750.258377] sd 7:0:0:0: [sdb] Attached SCSI removable disk
[ 2750.258491] sd 7:0:0:0: Attached scsi generic sg2 type 0
[ 2750.503385] sd 7:0:0:0: [sdb] Sense Key : No Sense [current]
[ 2750.503403] sd 7:0:0:0: [sdb] Add. Sense: No additional sense information
[ 2750.726061] sd 7:0:0:0: [sdb] Sense Key : No Sense [current]
[ 2750.726079] sd 7:0:0:0: [sdb] Add. Sense: No additional sense information
[ 2750.958966] sd 7:0:0:0: [sdb] Sense Key : No Sense [current]
[ 2750.958984] sd 7:0:0:0: [sdb] Add. Sense: No additional sense information
[ 2765.495649] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2795.868037] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2806.261414] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2812.883788] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2843.260159] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2853.637549] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2860.259899] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2890.632276] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2901.009671] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 2907.225003] sd 7:0:0:0: [sdb] Result: hostbyte=DID_ABORT driverbyte=DRIVER_OK,SUGGEST_OK
[ 2907.225023] end_request: I/O error, dev sdb, sector 31931
[ 3032.992603] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3063.364987] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3073.742403] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3080.364735] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3110.737116] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3121.114526] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3127.732848] usb 1-1: reset full speed USB device using ohci_hcd and address 15
[ 3127.912805] usb 1-1: device descriptor read/64, error -62
[ 3128.196752] usb 1-1: devi...

[yaztromo (tromo)]

Ok I solved my problem. I was echoing 32 to max_sectors_kb for sda instead of sdb.

So the command that fixes this for me is (as root): echo 32 > /sys/block/sdb/queue/max_sectors_kb

[Leann Ogasawara (leannogasawara)]

The Hardy Heron Alpha series is currently under development and contains an updated version of the kernel. It would be helpful if you could test the latest Hardy Alpha release: http://www.ubuntu.com/testing . You should be able to then test the new kernel via the LiveCD. If you can, please verify if this bug still exists or not and report back your results. Thanks.

[Leann Ogasawara (leannogasawara)]

The Ubuntu Kernel Team is planning to move to the 2.6.27 kernel for the upcoming Intrepid Ibex 8.10 release. As a result, the kernel team would appreciate it if you could please test this newer 2.6.27 Ubuntu kernel. There are one of two ways you should be able to test:

1) If you are comfortable installing packages on your own, the linux-image-2.6.27-* package is currently available for you to install and test.

--or--

2) The upcoming Alpha5 for Intrepid Ibex 8.10 will contain this newer 2.6.27 Ubuntu kernel. Alpha5 is set to be released Thursday Sept 4. Please watch http://www.ubuntu.com/testing for Alpha5 to be announced. You should then be able to test via a LiveCD.

Please let us know immediately if this newer 2.6.27 kernel resolves the bug reported here or if the issue remains. More importantly, please open a new bug report for each new bug/regression introduced by the 2.6.27 kernel and tag the bug report with 'linux-2.6.27'. Also, please specifically note if the issue does or does not appear in the 2.6.26 kernel. Thanks again, we really appreicate your help and feedback.

[Leann Ogasawara (leannogasawara)]

*This is an automated response*

This bug report is being closed because we received no response to the previous request for information. Please reopen this if it is still an issue in the actively developed pre-release of Jaunty Jackalope 9.04 - http://cdimage.ubuntu.com/releases/jaunty . To reopen the bug report simply change the Status of the "linux" task back to "New".