[lucid] USB storage disconnects after certain period causing ext3 I/O errors and OOPS

[Matej Kenda]

An embedded system, comprising COM Express module and USB SSD with Ubuntu Linux Server 10.04 (Lucid Lynx), hangs after certain period of activity. Period between the hangs varies from two days to one week.

Symptoms of the hang:

* machine is unreachable over ssh
* machine is pingable
* file system appears to be empty for SSH sessions started before the hang (ls doesn't list anything, unable to start any command)

This issue was not present when using Ubuntu Linux 8.04.

In order to get details about the hang, Ubuntu Linux kernel was compiled with additional options:

CONFIG_USB_STORAGE_DEBUG=y
CONFIG_USB=y
CONFIG_USB_DEBUG=y

In addition to that, netconsole module was used to redirect syslog to another machine to be able to analyse it.

It seems that the problem happens in the following lines and causes ext3 to OOPS a bit later:

Jan 10 10:41:15 hudson-slave [262388.606612] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:24 hudson-slave [262397.557075] hub 1-0:1.0: state 7 ports 8 chg 0000 evt 0040
Jan 10 10:41:24 hudson-slave [262397.558208] ehci_hcd 0000:00:1d.7: GetStatus port 6 status 001002 POWER sig=se0 CSC
Jan 10 10:41:24 hudson-slave [262397.559358] hub 1-0:1.0: port 6, status 0100, change 0001, 12 Mb/s
Jan 10 10:41:24 hudson-slave [262397.560489] usb 1-6: USB disconnect, address 6
Jan 10 10:41:24 hudson-slave [262397.561671] usb 1-6: unregistering device
Jan 10 10:41:24 hudson-slave [262397.562805] usb 1-6: usb_disable_device nuking all URBs
Jan 10 10:41:24 hudson-slave [262397.563983] ehci_hcd 0000:00:1d.7: shutdown urb f27f4100 ep2out-bulk
Jan 10 10:41:24 hudson-slave [262397.566277] usb 1-6: unregistering interface 1-6:1.0
Jan 10 10:41:24 hudson-slave [262397.566321] usb-storage: Status code -108; transferred 31/31
Jan 10 10:41:24 hudson-slave [262397.566325] usb-storage: -- unknown error
Jan 10 10:41:24 hudson-slave [262397.566329] usb-storage: Bulk command transfer result=4
Jan 10 10:41:24 hudson-slave [262397.566332] usb-storage: -- transport indicates error, resetting
...

Jan 10 10:41:26 hudson-slave [262397.572511] Buffer I/O error on device sda6, logical block 0
Jan 10 10:41:26 hudson-slave [262397.572515] lost page write due to I/O error on sda6
Jan 10 10:41:26 hudson-slave [262397.584278] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584297] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.584301] usb-storage: Command WRITE_10 (10 bytes)
Jan 10 10:41:26 hudson-slave [262397.584303] usb-storage: 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584316] usb-storage: Bulk Command S 0x43425355 T 0x4cb0d L 8192 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:26 hudson-slave [262397.584320] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:26 hudson-slave [262397.584325] usb-storage: Status code -19; transferred 0/31
Jan 10 10:41:26 hudson-slave [262397.584328] usb-storage: -- unknown error
Jan 10 10:41:26 hudson-slave [262397.584330] usb-storage: Bulk command transfer result=4
Jan 10 10:41:26 hudson-slave [262397.584333] usb-storage: -- transport indicates error, resetting
Jan 10 10:41:26 hudson-slave [262397.584336] usb-storage: unable to lock device for reset: -19
Jan 10 10:41:26 hudson-slave [262397.584340] usb-storage: usb_stor_Bulk_reset called
Jan 10 10:41:26 hudson-slave [262397.584344] usb-storage: usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:26 hudson-slave [262397.584348] usb-storage: Soft reset failed: -19
Jan 10 10:41:26 hudson-slave [262397.584351] usb-storage: scsi cmd done, result=0x70000
Jan 10 10:41:26 hudson-slave [262397.584356] usb-storage: *** thread sleeping.
Jan 10 10:41:26 hudson-slave [262397.584364] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584372] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.584375] usb-storage: Command WRITE_10 (10 bytes)
Jan 10 10:41:26 hudson-slave [262397.584377] usb-storage: 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584389] usb-storage: Bulk Command S 0x43425355 T 0x4cb0e L 8192 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:26 hudson-slave [262397.584393] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:26 hudson-slave [262397.584397] usb-storage: Status code -19; transferred 0/31
Jan 10 10:41:26 hudson-slave [262397.584399] usb-storage: -- unknown error
Jan 10 10:41:26 hudson-slave [262397.584402] usb-storage: Bulk command transfer result=4
Jan 10 10:41:26 hudson-slave [262397.584405] usb-storage: -- transport indicates error, resetting
Jan 10 10:41:26 hudson-slave [262397.584408] usb-storage: unable to lock device for reset: -19
Jan 10 10:41:26 hudson-slave [262397.584411] usb-storage: usb_stor_Bulk_reset called
Jan 10 10:41:26 hudson-slave [262397.584415] usb-storage: usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:26 hudson-slave [262397.584419] usb-storage: Soft reset failed: -19
Jan 10 10:41:26 hudson-slave [262397.584422] usb-storage: scsi cmd done, result=0x70000
Jan 10 10:41:26 hudson-slave [262397.584427] usb-storage: *** thread sleeping.
Jan 10 10:41:26 hudson-slave [262397.584434] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584441] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.584444] usb-storage: Command WRITE_10 (10 bytes)
Jan 10 10:41:26 hudson-slave [262397.584447] usb-storage: 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584458] usb-storage: Bulk Command S 0x43425355 T 0x4cb0f L 8192 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:26 hudson-slave [262397.584463] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:26 hudson-slave [262397.584467] usb-storage: Status code -19; transferred 0/31
Jan 10 10:41:26 hudson-slave [262397.584469] usb-storage: -- unknown error
Jan 10 10:41:26 hudson-slave [262397.584472] usb-storage: Bulk command transfer result=4
Jan 10 10:41:26 hudson-slave [262397.584475] usb-storage: -- transport indicates error, resetting
Jan 10 10:41:26 hudson-slave [262397.584478] usb-storage: unable to lock device for reset: -19
Jan 10 10:41:26 hudson-slave [262397.584482] usb-storage: usb_stor_Bulk_reset called
Jan 10 10:41:26 hudson-slave [262397.584486] usb-storage: usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:26 hudson-slave [262397.584489] usb-storage: Soft reset failed: -19
Jan 10 10:41:26 hudson-slave [262397.584492] usb-storage: scsi cmd done, result=0x70000
Jan 10 10:41:26 hudson-slave [262397.584497] usb-storage: *** thread sleeping.
Jan 10 10:41:26 hudson-slave [262397.584504] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584510] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.584514] usb-storage: Command WRITE_10 (10 bytes)
Jan 10 10:41:26 hudson-slave [262397.584516] usb-storage: 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584528] usb-storage: Bulk Command S 0x43425355 T 0x4cb10 L 8192 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:26 hudson-slave [262397.584532] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:26 hudson-slave [262397.584536] usb-storage: Status code -19; transferred 0/31
Jan 10 10:41:26 hudson-slave [262397.584539] usb-storage: -- unknown error
Jan 10 10:41:26 hudson-slave [262397.584541] usb-storage: Bulk command transfer result=4
Jan 10 10:41:26 hudson-slave [262397.584544] usb-storage: -- transport indicates error, resetting
Jan 10 10:41:26 hudson-slave [262397.584547] usb-storage: unable to lock device for reset: -19
Jan 10 10:41:26 hudson-slave [262397.584551] usb-storage: usb_stor_Bulk_reset called
Jan 10 10:41:26 hudson-slave [262397.584555] usb-storage: usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:26 hudson-slave [262397.584558] usb-storage: Soft reset failed: -19
Jan 10 10:41:26 hudson-slave [262397.584561] usb-storage: scsi cmd done, result=0x70000
Jan 10 10:41:26 hudson-slave [262397.584566] usb-storage: *** thread sleeping.
Jan 10 10:41:26 hudson-slave [262397.584572] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584579] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.584582] usb-storage: Command WRITE_10 (10 bytes)
Jan 10 10:41:26 hudson-slave [262397.584585] usb-storage: 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584597] usb-storage: Bulk Command S 0x43425355 T 0x4cb11 L 8192 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:26 hudson-slave [262397.584601] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:26 hudson-slave [262397.584605] usb-storage: Status code -19; transferred 0/31
Jan 10 10:41:26 hudson-slave [262397.584608] usb-storage: -- unknown error
Jan 10 10:41:26 hudson-slave [262397.584611] usb-storage: Bulk command transfer result=4
Jan 10 10:41:26 hudson-slave [262397.584613] usb-storage: -- transport indicates error, resetting
Jan 10 10:41:26 hudson-slave [262397.584617] usb-storage: unable to lock device for reset: -19
Jan 10 10:41:26 hudson-slave [262397.584620] usb-storage: usb_stor_Bulk_reset called
Jan 10 10:41:26 hudson-slave [262397.584625] usb-storage: usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:26 hudson-slave [262397.584628] usb-storage: Soft reset failed: -19
Jan 10 10:41:26 hudson-slave [262397.584631] usb-storage: scsi cmd done, result=0x70000
Jan 10 10:41:26 hudson-slave [262397.584636] usb-storage: *** thread sleeping.
Jan 10 10:41:26 hudson-slave [262397.584642] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584649] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.584652] usb-storage: Command WRITE_10 (10 bytes)
Jan 10 10:41:26 hudson-slave [262397.584655] usb-storage: 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584666] usb-storage: Bulk Command S 0x43425355 T 0x4cb12 L 8192 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:26 hudson-slave [262397.584670] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:26 hudson-slave [262397.584675] usb-storage: Status code -19; transferred 0/31
Jan 10 10:41:26 hudson-slave [262397.584677] usb-storage: -- unknown error
Jan 10 10:41:26 hudson-slave [262397.584680] usb-storage: Bulk command transfer result=4
Jan 10 10:41:26 hudson-slave [262397.584683] usb-storage: -- transport indicates error, resetting
Jan 10 10:41:26 hudson-slave [262397.584686] usb-storage: unable to lock device for reset: -19
Jan 10 10:41:26 hudson-slave [262397.584689] usb-storage: usb_stor_Bulk_reset called
Jan 10 10:41:26 hudson-slave [262397.584694] usb-storage: usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:26 hudson-slave [262397.584698] usb-storage: Soft reset failed: -19
Jan 10 10:41:26 hudson-slave [262397.584701] usb-storage: scsi cmd done, result=0x70000
Jan 10 10:41:26 hudson-slave [262397.584706] usb-storage: *** thread sleeping.
Jan 10 10:41:26 hudson-slave [262397.584714] sd 2:0:0:0: [sda] Unhandled error code
Jan 10 10:41:26 hudson-slave [262397.584718] sd 2:0:0:0: [sda] Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK
Jan 10 10:41:26 hudson-slave [262397.584723] sd 2:0:0:0: [sda] CDB: Write(10): 2a 00 00 38 e1 e8 00 00 10 00
Jan 10 10:41:26 hudson-slave [262397.584735] end_request: I/O error, dev sda, sector 3727848
Jan 10 10:41:26 hudson-slave [262397.584757] Aborting journal on device sda6.
Jan 10 10:41:26 hudson-slave [262397.584775] usb-storage: queuecommand called
Jan 10 10:41:26 hudson-slave [262397.584788] EXT3-fs error (device sda6) in ext3_reserve_inode_write: Journal has aborted
Jan 10 10:41:26 hudson-slave [262397.584794] ------------[ cut here ]------------
Jan 10 10:41:26 hudson-slave [262397.584806] WARNING: at /devel/linux-ubuntu-lucid/fs/buffer.c:1159 mark_buffer_dirty+0x70/0x90()
Jan 10 10:41:26 hudson-slave [262397.584810] Modules linked in: libera fbcon tileblit font bitblit softcursor vga16fb vgastate netconsole configfs coretemp lp parport i915 drm_kms_helper drm i2c_algo_bit video serio_raw output snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm snd_timer snd soundcore snd_page_alloc intel_agp agpgart usbhid hid usb_storage e1000e r8169 mii
Jan 10 10:41:26 hudson-slave [262397.584858] Pid: 892, comm: rsyslogd Not tainted 2.6.32-26-generic-pae #47
Jan 10 10:41:26 hudson-slave [262397.584861] Call Trace:
Jan 10 10:41:26 hudson-slave [262397.584871] [<c01543f2>] warn_slowpath_common+0x72/0xa0
Jan 10 10:41:26 hudson-slave [262397.584877] [<c0237140>] ? mark_buffer_dirty+0x70/0x90
Jan 10 10:41:26 hudson-slave [262397.584883] [<c0237140>] ? mark_buffer_dirty+0x70/0x90
Jan 10 10:41:26 hudson-slave [262397.584889] [<c015443a>] warn_slowpath_null+0x1a/0x20
Jan 10 10:41:26 hudson-slave [262397.584894] [<c0237140>] mark_buffer_dirty+0x70/0x90
Jan 10 10:41:26 hudson-slave [262397.584900] [<c027a6fa>] T.1021+0x4a/0x70
Jan 10 10:41:26 hudson-slave [262397.584906] [<c027a78b>] ext3_handle_error+0x6b/0xb0
Jan 10 10:41:26 hudson-slave [262397.584913] [<c05b9ebf>] ? printk+0x1d/0x26
Jan 10 10:41:26 hudson-slave [262397.584918] [<c027a852>] __ext3_std_error+0x82/0x90
Jan 10 10:41:26 hudson-slave [262397.584925] [<c027f210>] ? __ext3_journal_get_write_access+0x40/0x50
Jan 10 10:41:26 hudson-slave [262397.584932] [<c0272382>] ext3_reserve_inode_write+0x42/0x90
Jan 10 10:41:26 hudson-slave [262397.584937] [<c02723fb>] ext3_mark_inode_dirty+0x2b/0x50
Jan 10 10:41:26 hudson-slave [262397.584943] [<c027254e>] ext3_dirty_inode+0x4e/0x80
Jan 10 10:41:26 hudson-slave [262397.584950] [<c02fe85e>] ? cap_inode_need_killpriv+0x2e/0x40
Jan 10 10:41:26 hudson-slave [262397.584957] [<c0231351>] __mark_inode_dirty+0x31/0x180
Jan 10 10:41:26 hudson-slave [262397.584963] [<c01d2a14>] ? file_remove_suid+0x24/0x80
Jan 10 10:41:26 hudson-slave [262397.584970] [<c016fec0>] ? autoremove_wake_function+0x20/0x50
Jan 10 10:41:26 hudson-slave [262397.584977] [<c0226b35>] file_update_time+0xb5/0x130
Jan 10 10:41:26 hudson-slave [262397.584984] [<c01d4a88>] __generic_file_aio_write+0x1b8/0x510
Jan 10 10:41:26 hudson-slave [262397.584991] [<c02c6ef4>] ? journal_stop+0x124/0x2a0
Jan 10 10:41:26 hudson-slave [262397.584997] [<c0271c28>] ? ext3_mark_iloc_dirty+0x18/0x30
Jan 10 10:41:26 hudson-slave [262397.585003] [<c01d4e37>] generic_file_aio_write+0x57/0xc0
Jan 10 10:41:26 hudson-slave [262397.585008] [<c027255a>] ? ext3_dirty_inode+0x5a/0x80
Jan 10 10:41:26 hudson-slave [262397.585016] [<c02126f4>] do_sync_write+0xc4/0x100
Jan 10 10:41:26 hudson-slave [262397.585023] [<c016fea0>] ? autoremove_wake_function+0x0/0x50
Jan 10 10:41:26 hudson-slave [262397.585029] [<c02289f3>] ? notify_change+0x223/0x340
Jan 10 10:41:26 hudson-slave [262397.585035] [<c02749e0>] ? ext3_ioctl+0x0/0x6b0
Jan 10 10:41:26 hudson-slave [262397.585042] [<c02ffa34>] ? security_file_permission+0x14/0x20
Jan 10 10:41:26 hudson-slave [262397.585048] [<c0212894>] ? rw_verify_area+0x64/0xe0
Jan 10 10:41:26 hudson-slave [262397.585054] [<c02129b2>] vfs_write+0xa2/0x1a0
Jan 10 10:41:26 hudson-slave [262397.585059] [<c0221529>] ? do_vfs_ioctl+0x2e9/0x310
Jan 10 10:41:26 hudson-slave [262397.585065] [<c0212630>] ? do_sync_write+0x0/0x100
Jan 10 10:41:26 hudson-slave [262397.585071] [<c02132d2>] sys_write+0x42/0x70
Jan 10 10:41:26 hudson-slave [262397.585078] [<c01096c3>] sysenter_do_call+0x12/0x28
Jan 10 10:41:26 hudson-slave [262397.585083] ---[ end trace 3922152202b7311d ]---

Complete log is attached.
---
AlsaDevices:
 total 0
 crw-rw---- 1 root audio 116, 2 2011-01-10 13:32 timer
AlsaVersion: Advanced Linux Sound Architecture Driver Version 1.0.21.
AplayDevices: Error: [Errno 2] No such file or directory
Architecture: i386
ArecordDevices: Error: [Errno 2] No such file or directory
AudioDevicesInUse: Error: command ['fuser', '-v', '/dev/snd/timer'] failed with exit code 1:
CRDA: Error: [Errno 2] No such file or directory
DistroRelease: Ubuntu 10.04
InstallationMedia: Ubuntu-Server 10.04.1 LTS "Lucid Lynx" - Release i386 (20100816.2)
IwConfig:
 lo no wireless extensions.

 eth1 no wireless extensions.

 eth2 no wireless extensions.
NonfreeKernelModules: libera
Package: linux (not installed)
ProcCmdLine: BOOT_IMAGE=/vmlinuz-2.6.32-26-generic-pae root=UUID=d11a4ce2-8a93-46f6-9374-cdaba6e7d5f2 ro loglevel=8 acpi=force ipv6.disable=1 vmalloc=200M apparmor=0
ProcEnviron:
 PATH=(custom, no user)
 LANG=en_US.UTF-8
 SHELL=/bin/bash
ProcVersionSignature: Ubuntu 2.6.32-26.47-generic-pae 2.6.32.24+drm33.11
Regression: Yes
RelatedPackageVersions: linux-firmware 1.34.1
Reproducible: Yes
RfKill:

Tags: lucid regression-release needs-upstream-testing
Uname: Linux 2.6.32-26-generic-pae i686
UserGroups:

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

apport information

[Matej Kenda (matejken)]

I think that file WifiSyslog.txt can be removed, because it contains irrelevant information. Log containing the error was attached in the comment #1.

[Matej Kenda (matejken)]

The model of the COM Express module is Congatec BA945.

Low Power COM Express Modul with Intel® Atom™ processor N270 and Mobile Intel® 945GME Express Chipset

http://www.congatec.com/ba945.html?&L=0

[Matej Kenda (matejken)]

Embedded USB disk, which is used is 4GB Micron MTFDCAE004SAJ-1N1.

http://www.micron.com/products/ProductDetails.html?product=products/solid_state_storage/eusb//MTFDCAE004SAJ-1N1

[Matej Kenda (matejken)]

In the embedded setup as we have it an USB error like this causes the whole system to become unusable, because the complete OS is running on the USB disk which was disconnected.

[Matej Kenda (matejken)]

This bug could be related to bug 349767.

[Matej Kenda (matejken)]

On the machine where USB disc gets disconnected generally after two days
uptime, we have disabled the USB power management and this problem didn't
happen for cca 6 days uptime.

Following script has been added to the file /etc/rc.local

for i in /sys/bus/usb/devices/*/power/level; do echo on > $i; done

On following machines:
  10.0.3.57
  10.0.3.58
  10.0.3.40
  10.0.3.39

All these machines will be monitored, if usb disconnect problem occurs

This is the command for checking usb power management setting:
for i in /sys/bus/usb/devices/*/power/level; do echo $i; cat $i; done

auto - means that power management is enabled
on - means that usb will be always on (enabled)

We don't know why this helps.

[Matthew Gatto (mattgatto)]

I have the same general problem of my external drive disconnecting after some time, with an error log similar to the one in this bug report. After the disconnect, I have to power-cycle the drive to get it to show up again in My Computer. This is on a Lenovo T61 laptop running Ubuntu 10.10 attached to a LaCie 1GB external ext3 hard drive attached via USB. Here is an excerpt of the log during the last crash/disconnect:

Feb 17 00:41:31 gatto-laptop kernel: [541292.182536] usb 2-2: reset high speed USB device using ehci_hcd and address 3
Feb 17 00:41:31 gatto-laptop kernel: [541292.252949] usb 2-2: USB disconnect, address 3
Feb 17 00:41:31 gatto-laptop kernel: [541292.254078] sd 6:0:0:0: Device offlined - not ready after error recovery
Feb 17 00:41:31 gatto-laptop kernel: [541292.254104] sd 6:0:0:0: [sdb] Unhandled error code
Feb 17 00:41:31 gatto-laptop kernel: [541292.254106] sd 6:0:0:0: [sdb] Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Feb 17 00:41:31 gatto-laptop kernel: [541292.254110] sd 6:0:0:0: [sdb] CDB: Read(10): 28 00 04 90 df 7f 00 00 28 00
Feb 17 00:41:31 gatto-laptop kernel: [541292.254139] sd 6:0:0:0: [sdb] Unhandled error code
Feb 17 00:41:31 gatto-laptop kernel: [541292.254141] sd 6:0:0:0: [sdb] Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Feb 17 00:41:31 gatto-laptop kernel: [541292.254144] sd 6:0:0:0: [sdb] CDB: Read(10): 28 00 0a 9b 3b a7 00 00 20 00
Feb 17 00:41:43 gatto-laptop kernel: [541303.962679] ------------[ cut here ]------------
Feb 17 00:41:43 gatto-laptop kernel: [541303.962686] WARNING: at /build/buildd/linux-2.6.35/fs/buffer.c:1151 mark_buffer_dirty+0x7f/0xa0()
Feb 17 00:41:43 gatto-laptop kernel: [541303.962688] Hardware name: 6465CTO
Feb 17 00:41:43 gatto-laptop kernel: [541303.962690] Modules linked in: usb_storage cryptd aes_x86_64 aes_generic binfmt_misc parport_pc ppdev thinkpad_acpi snd_hda_codec_analog arc4 pcmcia joydev snd_seq_midi iwl3945 i915 iwlcore drm_kms_helper snd_hda_intel snd_hda_codec r852 sm_common drm snd_rawmidi snd_hwdep snd_seq_midi_event yenta_socket nand mac80211 psmouse nand_ids nand_ecc snd_pcm snd_seq mtd i2c_algo_bit cfg80211 intel_agp pcmcia_rsrc snd_timer pcmcia_core video snd_seq_device serio_raw snd_page_alloc tpm_tis tpm tpm_bios output snd soundcore nvram lp parport ahci sdhci_pci sdhci firewire_ohci firewire_core crc_itu_t led_class e1000e libahci
Feb 17 00:41:43 gatto-laptop kernel: [541303.962737] Pid: 13900, comm: transmission-gt Tainted: G W 2.6.35-25-generic #44-Ubuntu
Feb 17 00:41:43 gatto-laptop kernel: [541303.962740] Call Trace:
Feb 17 00:41:43 gatto-laptop kernel: [541303.962746] [<ffffffff8106091f>] warn_slowpath_common+0x7f/0xc0
Feb 17 00:41:43 gatto-laptop kernel: [541303.962749] [<ffffffff8106097a>] warn_slowpath_null+0x1a/0x20
Feb 17 00:41:43 gatto-laptop kernel: [541303.962752] [<ffffffff8117d65f>] mark_buffer_dirty+0x7f/0xa0
Feb 17 00:41:43 gatto-laptop kernel: [541303.962756] [<ffffffff811d21e7>] T.941+0x57/0x80
Feb 17 00:41:43 gatto-laptop kernel: [541303.962758] [<ffffffff811d228f>] ext3_handle_error+0x7f/0xe0
Feb 17 00:41:43 gatto-laptop kernel: [541303.962761] [<ffffffff811d234e>] __ext3_std_error+0x5e/0xb0
Feb 17 00:41:4...

[Matej Kenda (matejken)]

There is a patch available in bug 349767 that solves this problem.

[Matej Kenda (matejken)]

The patch which solves the problem is available in the Linus' git:

http://git.kernel.org/?p=linux/kernel/git/torvalds/linux-2.6.git;a=commit;h=004c19682884d4f40000ce1ded53f4a1d0b18206

[Wayde Nie (niew)]

The patch doesn't appear to fix this (at least not in all cases...)

I have disconnects every day on 12.04LTS, 3.2.0-24-generic with all fixes/updates applied as of this morning with an iomega 2TB 'eGo' external USB drive.

[Mark Vartanyan (kolypto)]

Nov 26 22:56:46 wtf kernel: [ 1112.330563] usb 2-1.1: reset high-speed USB device number 16 using ehci_hcd
Nov 26 22:56:46 wtf kernel: [ 1112.493985] sd 10:0:0:0: [sdc] Unhandled error code
Nov 26 22:56:46 wtf kernel: [ 1112.493991] sd 10:0:0:0: [sdc] Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK
Nov 26 22:56:46 wtf kernel: [ 1112.493997] sd 10:0:0:0: [sdc] CDB: Read(10): 28 00 33 00 0c 00 00 00 08 00
Nov 26 22:56:46 wtf kernel: [ 1112.494011] end_request: I/O error, dev sdc, sector 855641088

this is still an issue