[solved] USB storage devices problem

wpb · 2012-02-23 21:48:20

Hello everyone. I have MSI Wind U270 and recently I've moved from Ubuntu 11.04 to Arch. I really like Arch so far but I have one quite big problem and I can't find any solution, so I hope someone here could help me.
After I'd installed Arch, I tried to copy some file from/to USB flash drive. After just few seconds, transfer failed with information about I/O errors and USB flash drive disappeared along with any other USB drives connected at the same time but logitech usb mouse and keyboard continue to work.
My first thought was that it's simply broken USB drive, but same thing was happening also when I tried to move some files from/to any other USB storage device that I have.
Here is sample dmesg output after I connect device:

[ 4799.189720] usb 1-2: new high-speed USB device number 4 using ehci_hcd
[ 4799.369911] xhci_hcd 0000:06:00.0: PME# disabled
[ 4799.369935] xhci_hcd 0000:06:00.0: setting latency timer to 64
[ 4799.399707] ehci_hcd 0000:00:13.2: BAR 0: set to [mem 0xfeb48000-0xfeb480ff] (PCI address [0xfeb48000-0xfeb480ff])
[ 4799.399754] ehci_hcd 0000:00:13.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00013)
[ 4799.399800] ehci_hcd 0000:00:13.2: PME# disabled
[ 4799.399819] ehci_hcd 0000:00:13.2: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[ 4799.426731] ohci_hcd 0000:00:12.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 4799.520213] ohci_hcd 0000:00:13.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 4799.690262] usbcore: registered new interface driver uas
[ 4799.704107] Initializing USB Mass Storage driver...
[ 4799.704420] scsi7 : usb-storage 1-2:1.0
[ 4799.704959] usbcore: registered new interface driver usb-storage
[ 4799.704966] USB Mass Storage support registered.
[ 4801.166512] scsi 7:0:0:0: Direct-Access     JetFlash Transcend 8GB    1100 PQ: 0 ANSI: 0 CCS
[ 4801.168550] sd 7:0:0:0: [sdc] 15826944 512-byte logical blocks: (8.10 GB/7.54 GiB)
[ 4801.169401] sd 7:0:0:0: [sdc] Write Protect is off
[ 4801.169411] sd 7:0:0:0: [sdc] Mode Sense: 43 00 00 00
[ 4801.170332] sd 7:0:0:0: [sdc] No Caching mode page present
[ 4801.170340] sd 7:0:0:0: [sdc] Assuming drive cache: write through
[ 4801.175736] sd 7:0:0:0: [sdc] No Caching mode page present
[ 4801.175747] sd 7:0:0:0: [sdc] Assuming drive cache: write through
[ 4801.176962]  sdc: sdc1
[ 4801.181454] sd 7:0:0:0: [sdc] No Caching mode page present
[ 4801.181465] sd 7:0:0:0: [sdc] Assuming drive cache: write through
[ 4801.181471] sd 7:0:0:0: [sdc] Attached SCSI removable disk
[ 4801.388155] xhci_hcd 0000:06:00.0: PME# enabled
[ 4801.439601] ehci_hcd 0000:00:13.2: PCI INT B disabled
[ 4801.439657] ehci_hcd 0000:00:13.2: PME# enabled
[ 4801.519628] ohci_hcd 0000:00:12.0: PCI INT A disabled
[ 4802.346484] ohci_hcd 0000:00:13.0: PCI INT A disabled

and when I start to copy some things to/from USB device this is what's happening:

[ 4981.389262] ehci_hcd 0000:00:12.2: force halt; handshake ffffc90000c1e024 00004000 00000000 -> -110
[ 4981.389271] ehci_hcd 0000:00:12.2: HC died; cleaning up
[ 4981.389377] usb 1-2: USB disconnect, device number 4
[ 4981.399810] sd 7:0:0:0: [sdc] Unhandled error code
[ 4981.399820] sd 7:0:0:0: [sdc]  Result: hostbyte=0x01 driverbyte=0x00
[ 4981.399826] sd 7:0:0:0: [sdc] CDB: cdb[0]=0x2a: 2a 00 00 1f b4 7e 00 00 08 00
[ 4981.399840] end_request: I/O error, dev sdc, sector 2077822
[ 4981.399850] Buffer I/O error on device sdc1, logical block 259720
[ 4981.399854] lost page write due to I/O error on sdc1
[ 4981.404451] sd 7:0:0:0: [sdc] Unhandled error code
[ 4981.404461] sd 7:0:0:0: [sdc]  Result: hostbyte=0x01 driverbyte=0x00
[ 4981.404467] sd 7:0:0:0: [sdc] CDB: cdb[0]=0x2a: 2a 00 00 20 34 7e 00 00 f0 00
[ 4981.404481] end_request: I/O error, dev sdc, sector 2110590
[ 4981.404489] Buffer I/O error on device sdc1, logical block 263816
[ 4981.404493] lost page write due to I/O error on sdc1
[ 4981.404500] Buffer I/O error on device sdc1, logical block 263817
[ 4981.404504] lost page write due to I/O error on sdc1
[ 4981.404509] Buffer I/O error on device sdc1, logical block 263818
[ 4981.404512] lost page write due to I/O error on sdc1
[ 4981.404517] Buffer I/O error on device sdc1, logical block 263819
[ 4981.404521] lost page write due to I/O error on sdc1
[ 4981.404525] Buffer I/O error on device sdc1, logical block 263820
[ 4981.404529] lost page write due to I/O error on sdc1
[ 4981.404534] Buffer I/O error on device sdc1, logical block 263821
[ 4981.404537] lost page write due to I/O error on sdc1
[ 4981.404543] Buffer I/O error on device sdc1, logical block 263822
[ 4981.404546] lost page write due to I/O error on sdc1
[ 4981.404550] Buffer I/O error on device sdc1, logical block 263823
[ 4981.404554] lost page write due to I/O error on sdc1
[ 4981.444899] usb 1-4: USB disconnect, device number 3

Next I thought – computer is broken. But it simply couldn't be true because in Ubuntu it's working.
I wanted to test if USB transfer is working in other distros, so I downloaded few and tried:

Ubuntu 11.04 – everything is working
Ubuntu 11.10 – it's not working, exactly same errors as in Arch
Ubuntu 12.04 Alpha 2 – it's not working
Mint 11 – it's working
Mint 12 – it's not working

So one thing in common that I can think of is fact that USB transfer is working in distros using kernel <3.0 and is broken in those using kernel >=3.0.
In dmesg output I can see ehci_hcd errors. When I remove this module before moving files, than everything is working fine apart from very slow transfer but it's obvious.
I really hope that someone will be able to help with my problem. I don't know if it's some bug or it's just some configuration error.

EDIT: problems were caused by rts5139 module (card reader). Blacklisting that module solved all the problems.

Last edited by wpb (2012-07-01 08:37:02)

hadrons123 · 2012-02-28 17:46:31

can you post

/var/log/messages

wpb · 2012-02-28 19:47:25

Ok, so here is part of /var/log/messages.log with failed USB transfer:

Feb 28 20:21:40 localhost kernel: [  117.263584] usb 3-2: new high-speed USB device number 4 using ehci_hcd
Feb 28 20:21:40 localhost mtp-probe: checking bus 3, device 4: "/sys/devices/pci0000:00/0000:00:12.2/usb3/3-2"
Feb 28 20:21:40 localhost kernel: [  117.470130] ehci_hcd 0000:00:13.2: BAR 0: set to [mem 0xfeb48000-0xfeb480ff] (PCI address [0xfeb48000-0xfeb480ff])
Feb 28 20:21:40 localhost kernel: [  117.470251] ehci_hcd 0000:00:13.2: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Feb 28 20:21:40 localhost kernel: [  117.546266] ohci_hcd 0000:00:13.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Feb 28 20:21:40 localhost mtp-probe: bus: 3, device: 4 was not an MTP device
Feb 28 20:21:42 localhost kernel: [  119.687034] usbcore: registered new interface driver uas
Feb 28 20:21:42 localhost kernel: [  119.692675] Initializing USB Mass Storage driver...
Feb 28 20:21:42 localhost kernel: [  119.693029] scsi7 : usb-storage 3-2:1.0
Feb 28 20:21:42 localhost kernel: [  119.694285] usbcore: registered new interface driver usb-storage
Feb 28 20:21:42 localhost kernel: [  119.694294] USB Mass Storage support registered.
Feb 28 20:21:43 localhost kernel: [  120.353657] ohci_hcd 0000:00:13.0: PCI INT A disabled
Feb 28 20:21:43 localhost kernel: [  121.110815] scsi 7:0:0:0: Direct-Access     JetFlash Transcend 8GB    1100 PQ: 0 ANSI: 0 CCS
Feb 28 20:21:43 localhost kernel: [  121.115125] sd 7:0:0:0: [sdc] 15826944 512-byte logical blocks: (8.10 GB/7.54 GiB)
Feb 28 20:21:43 localhost kernel: [  121.117132] sd 7:0:0:0: [sdc] Write Protect is off
Feb 28 20:21:43 localhost kernel: [  121.125243]  sdc: sdc1
Feb 28 20:21:43 localhost kernel: [  121.130244] sd 7:0:0:0: [sdc] Attached SCSI removable disk
Feb 28 20:21:44 localhost kernel: [  121.570240] ehci_hcd 0000:00:13.2: PCI INT B disabled
Feb 28 20:21:44 localhost ntfs-3g[1936]: Version 2012.1.15 external FUSE 28
Feb 28 20:21:44 localhost ntfs-3g[1936]: Mounted /dev/sdc1 (Read-Write, label "", NTFS 3.1)
Feb 28 20:21:44 localhost ntfs-3g[1936]: Cmdline options: rw,nosuid,nodev,uhelper=udisks,uid=1000,gid=1000,dmask=0077,fmask=0177
Feb 28 20:21:44 localhost ntfs-3g[1936]: Mount options: rw,nosuid,nodev,uhelper=udisks,allow_other,nonempty,relatime,fsname=/dev/sdc1,blkdev,blksize=4096,default_permissions
Feb 28 20:21:44 localhost ntfs-3g[1936]: Global ownership and permissions enforced, configuration type 1
Feb 28 20:22:11 localhost kernel: [  148.692914] usb 3-2: USB disconnect, device number 4
Feb 28 20:22:11 localhost kernel: [  148.756937] sd 7:0:0:0: [sdc] Unhandled error code
Feb 28 20:22:11 localhost kernel: [  148.756947] sd 7:0:0:0: [sdc]  Result: hostbyte=0x01 driverbyte=0x00
Feb 28 20:22:11 localhost kernel: [  148.756954] sd 7:0:0:0: [sdc] CDB: cdb[0]=0x2a: 2a 00 00 1e 2f f6 00 00 18 00
Feb 28 20:22:11 localhost kernel: [  148.756983] lost page write due to I/O error on sdc1
Feb 28 20:22:11 localhost kernel: [  148.757000] lost page write due to I/O error on sdc1
Feb 28 20:22:11 localhost kernel: [  148.757016] lost page write due to I/O error on sdc1
Feb 28 20:22:11 localhost kernel: [  148.761894] sd 7:0:0:0: [sdc] Unhandled error code
Feb 28 20:22:11 localhost kernel: [  148.761905] sd 7:0:0:0: [sdc]  Result: hostbyte=0x01 driverbyte=0x00
Feb 28 20:22:11 localhost kernel: [  148.761911] sd 7:0:0:0: [sdc] CDB: cdb[0]=0x2a: 2a 00 00 1e 2f ae 00 00 08 00
Feb 28 20:22:11 localhost kernel: [  148.761939] lost page write due to I/O error on sdc1
Feb 28 20:22:11 localhost kernel: [  148.914173] usb 3-4: USB disconnect, device number 3
Feb 28 20:22:24 localhost ntfs-3g[1936]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error
Feb 28 20:22:24 localhost ntfs-3g[1936]: Reading $BITMAP failed: Input/output error
Feb 28 20:22:24 localhost ntfs-3g[1936]: Failed to allocate clusters: Input/output error
Feb 28 20:22:24 localhost kernel: [  161.862408] quiet_error: 2 callbacks suppressed
Feb 28 20:22:24 localhost kernel: [  161.862424] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862436] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862446] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862457] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862466] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862476] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862485] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862495] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862505] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost kernel: [  161.862514] lost page write due to I/O error on sdc1
Feb 28 20:22:24 localhost ntfs-3g[1936]: Unmounting /dev/sdc1 ()

and here is complete /var/log/messages.log - I just started system and then tried to copy some files to USB drive:

pyther · 2012-02-28 23:09:34

What kernel is mint 11 and ubuntu 11.04 using? How about ubuntu 11.10 and mint 12? I'm going to bet the newer kernel introduce a regression with your hardware.

wpb · 2012-03-01 18:45:04

Ubuntu 11.04 and Mint 11 are using kernel 2.6.38 and Ubuntu 11.10 and Mint 12 are using kernel 3.0.

pyther · 2012-03-01 23:29:21

It seems that it is very likely a kernel regression. You can search the kernel bug tracker (https://bugzilla.kernel.org/). If don't find anything you can post a bug report. You can do a bisect to determine what caused things to do go south. http://www.reactivated.net/weblog/archi … l-patches/

neildarlow · 2012-03-02 20:47:03

Do a search for disabling ehci_hcd and implement the suggestion. I think you'll find things more stable using uhci_hcd.

wpb · 2012-03-03 23:18:23

I've already tried disabling ehci_hcd and as I said before - it helps, and all devices work fine but it's not very usable - devices work then with usb 1.1 speed. When transferring small files it's not a problem but when I want move bigger files it's very annoying - I'm booting than some live distro with older kernel just to move some files.

pyther · 2012-03-03 23:26:08

That is because ehci is for usb 2.0 support. uhci and ohci is for usb 1.1.

You'll have to use an older kernel or do I git bisect, file a bug, etc...

Arch Linux

#1 2012-02-23 21:48:20

[solved] USB storage devices problem

#2 2012-02-28 17:46:31

Re: [solved] USB storage devices problem

#3 2012-02-28 19:47:25

Re: [solved] USB storage devices problem

#4 2012-02-28 23:09:34

Re: [solved] USB storage devices problem

#5 2012-03-01 18:45:04

Re: [solved] USB storage devices problem

#6 2012-03-01 23:29:21

Re: [solved] USB storage devices problem

#7 2012-03-02 20:47:03

Re: [solved] USB storage devices problem

#8 2012-03-03 23:18:23

Re: [solved] USB storage devices problem

#9 2012-03-03 23:26:08

Re: [solved] USB storage devices problem

Board footer