USB Disconnects / resets after commit b963801164618e25fbdc0cd452ce49c3628b46c8

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi,

This message contains additional information that might help resolving
the issue which was discussed on this mailing list two weeks ago:

http://article.gmane.org/gmane.linux.usb.general/44862

https://bugzilla.kernel.org/show_bug.cgi?id=32432

A bug report 701011 in Launchpad contains additional description of
the problem and kernel logs with traces created with
options:

CONFIG_USB_STORAGE_DEBUG=y
CONFIG_USB=y
CONFIG_USB_DEBUG=y

https://bugs.launchpad.net/linux/+bug/701011
https://launchpadlibrarian.net/62011653/hudson-slave-last-part.log

Extract from the log, which shows the problem:

-----

Jan 10 10:41:15 hudson-slave [262388.604525] usb-storage: Bulk Command
S 0x43425355 T 0x4cae3 L 4096 F 0 Trg 0 LUN 0 CL 10
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:24 hudson-slave [262397.566336] usb-storage: unable to
lock device for reset: -19
Jan 10 10:41:24 hudson-slave [262397.566342] usb-storage:
usb_stor_Bulk_reset called
Jan 10 10:41:24 hudson-slave [262397.566347] usb-storage:
usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:24 hudson-slave [262397.566351] usb-storage: Soft reset
failed: -19
Jan 10 10:41:24 hudson-slave [262397.566355] usb-storage: scsi cmd
done, result=0x70000
Jan 10 10:41:24 hudson-slave [262397.566364] usb-storage: *** thread sleeping.
Jan 10 10:41:24 hudson-slave [262397.566384] usb-storage: queuecommand called
Jan 10 10:41:24 hudson-slave [262397.566392] usb-storage: *** thread awakened.
Jan 10 10:41:24 hudson-slave [262397.566396] usb-storage: Command
WRITE_10 (10 bytes)
Jan 10 10:41:24 hudson-slave [262397.566399] usb-storage:  2a 00 00 30
aa d8 00 00 08 00
Jan 10 10:41:24 hudson-slave [262397.566411] usb-storage: Bulk Command
S 0x43425355 T 0x4cae4 L 4096 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:24 hudson-slave [262397.566415] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:24 hudson-slave [262397.566420] usb-storage: Status code
-19; transferred 0/31
Jan 10 10:41:24 hudson-slave [262397.566423] usb-storage: -- unknown error
Jan 10 10:41:24 hudson-slave [262397.566425] usb-storage: Bulk command
transfer result=4
Jan 10 10:41:24 hudson-slave [262397.566428] usb-storage: -- transport
indicates error, resetting
Jan 10 10:41:24 hudson-slave [262397.566432] usb-storage: unable to
lock device for reset: -19
Jan 10 10:41:24 hudson-slave [262397.566435] usb-storage:
usb_stor_Bulk_reset called
Jan 10 10:41:24 hudson-slave [262397.566439] usb-storage:
usb_stor_control_msg: rq=ff rqtype=21 value=0000 index=00 len=0
Jan 10 10:41:24 hudson-slave [262397.566443] usb-storage: Soft reset
failed: -19
Jan 10 10:41:24 hudson-slave [262397.566446] usb-storage: scsi cmd
done, result=0x70000
Jan 10 10:41:24 hudson-slave [262397.566451] usb-storage: *** thread sleeping.
Jan 10 10:41:24 hudson-slave [262397.566458] usb-storage: queuecommand called
Jan 10 10:41:24 hudson-slave [262397.566465] usb-storage: *** thread awakened.
Jan 10 10:41:24 hudson-slave [262397.566469] usb-storage: Command
WRITE_10 (10 bytes)
Jan 10 10:41:24 hudson-slave [262397.566471] usb-storage:  2a 00 00 30
aa d8 00 00 08 00
Jan 10 10:41:24 hudson-slave [262397.566483] usb-storage: Bulk Command
S 0x43425355 T 0x4cae5 L 4096 F 0 Trg 0 LUN 0 CL 10
Jan 10 10:41:24 hudson-slave [262397.566487] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 10 10:41:24 hudson-slave [262397.566491] usb-storage: Status code
-19; transferred 0/31
Jan 10 10:41:24 hudson-slave [262397.566494] usb-storage: -- unknown error

<the sequence repeates several times, then leads to OOPS in ext3>

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 ]---
Jan 10 10:41:26 hudson-slave [262397.585102] usb-storage: *** thread awakened.
Jan 10 10:41:26 hudson-slave [262397.585106] usb-storage: Command
WRITE_10 (10 bytes)

-----

Please see also: https://bugs.launchpad.net/linux/+bug/349767

Regards,

Matej Kenda
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html


[Index of Archives]     [Linux Media]     [Linux Input]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]     [Old Linux USB Devel Archive]

  Powered by Linux