From: Alexander Holler on
Hello,

Am 17.11.2009 01:19, schrieb Alexander Holler:
> shame on me. Sorry, I wasn't aware the logger (with default
> configuration) of the system in question doesn't log every kernel
> message. I've just started another rsync after I've changed that.

Here it is, I haven't cut anything in between and have choosen some
lines (of the 1gb-log I've got) before and after the first reset-message:

--------------------------------------------------
Nov 17 01:14:50 krabat kernel: [ 1219.910972] usb-storage: Bulk command
transfer result=0
Nov 17 01:14:50 krabat kernel: [ 1219.910975] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 122880 bytes, 18 entries
Nov 17 01:14:50 krabat kernel: [ 1219.919173] ehci_hcd 0000:00:1d.7:
port 1 high speed
Nov 17 01:14:50 krabat kernel: [ 1219.919180] ehci_hcd 0000:00:1d.7:
GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
Nov 17 01:14:50 krabat kernel: [ 1219.937890] usb-storage: Status code
0; transferred 122880/122880
Nov 17 01:14:50 krabat kernel: [ 1219.937893] usb-storage: -- transfer
complete
Nov 17 01:14:50 krabat kernel: [ 1219.937896] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:14:50 krabat kernel: [ 1219.937898] usb-storage: Attempting to
get CSW...
Nov 17 01:14:50 krabat kernel: [ 1219.937900] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:14:55 krabat kernel: [ 1224.973594] usb 3-1: usb-storage timed
out on ep0out len=0/0
Nov 17 01:14:55 krabat kernel: [ 1224.973718] usb-storage: Status code
0; transferred 13/13
Nov 17 01:14:55 krabat kernel: [ 1224.973721] usb-storage: -- transfer
complete
Nov 17 01:14:55 krabat kernel: [ 1224.973723] usb-storage: Bulk status
result = 0
Nov 17 01:14:55 krabat kernel: [ 1224.973726] usb-storage: Bulk Status S
0x53425355 T 0x72e1e R 0 Stat 0x0
Nov 17 01:14:55 krabat kernel: [ 1224.973729] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:14:55 krabat kernel: [ 1224.973733] usb-storage: *** thread
sleeping.
Nov 17 01:14:55 krabat kernel: [ 1224.973753] usb-storage: queuecommand
called
Nov 17 01:14:55 krabat kernel: [ 1224.973766] usb-storage: *** thread
awakened.
Nov 17 01:14:55 krabat kernel: [ 1224.973770] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:14:55 krabat kernel: [ 1224.973772] usb-storage: 28 00 20 c5
40 da 00 00 10 00
Nov 17 01:14:55 krabat kernel: [ 1224.973785] usb-storage: Bulk Command
S 0x43425355 T 0x72e1f L 8192 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:14:55 krabat kernel: [ 1224.973790] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:14:55 krabat kernel: [ 1224.997517] usb-storage: Status code
0; transferred 31/31
Nov 17 01:14:55 krabat kernel: [ 1224.997520] usb-storage: -- transfer
complete
Nov 17 01:14:55 krabat kernel: [ 1224.997522] usb-storage: Bulk command
transfer result=0
Nov 17 01:14:55 krabat kernel: [ 1224.997525] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 8192 bytes, 2 entries
Nov 17 01:14:55 krabat kernel: [ 1225.021720] usb-storage: Status code
0; transferred 8192/8192
Nov 17 01:14:55 krabat kernel: [ 1225.021722] usb-storage: -- transfer
complete
Nov 17 01:14:55 krabat kernel: [ 1225.021725] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:14:55 krabat kernel: [ 1225.021727] usb-storage: Attempting to
get CSW...
Nov 17 01:14:55 krabat kernel: [ 1225.021729] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:15:00 krabat kernel: [ 1230.175844] usb 3-1: usb-storage timed
out on ep0out len=0/0
Nov 17 01:15:00 krabat kernel: [ 1230.175958] usb-storage: Status code
0; transferred 13/13
Nov 17 01:15:00 krabat kernel: [ 1230.175961] usb-storage: -- transfer
complete
Nov 17 01:15:00 krabat kernel: [ 1230.175963] usb-storage: Bulk status
result = 0
Nov 17 01:15:00 krabat kernel: [ 1230.175966] usb-storage: Bulk Status S
0x53425355 T 0x72e1f R 0 Stat 0x0
Nov 17 01:15:00 krabat kernel: [ 1230.175969] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:15:00 krabat kernel: [ 1230.175973] usb-storage: *** thread
sleeping.
Nov 17 01:15:00 krabat kernel: [ 1230.175990] usb-storage: queuecommand
called
Nov 17 01:15:00 krabat kernel: [ 1230.176001] usb-storage: *** thread
awakened.
Nov 17 01:15:00 krabat kernel: [ 1230.176006] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:15:00 krabat kernel: [ 1230.176009] usb-storage: 28 00 20 c5
40 ea 00 00 f0 00
Nov 17 01:15:00 krabat kernel: [ 1230.176021] usb-storage: Bulk Command
S 0x43425355 T 0x72e20 L 122880 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:15:00 krabat kernel: [ 1230.176026] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:15:00 krabat kernel: [ 1230.199896] usb-storage: Status code
0; transferred 31/31
Nov 17 01:15:00 krabat kernel: [ 1230.199899] usb-storage: -- transfer
complete
Nov 17 01:15:00 krabat kernel: [ 1230.199901] usb-storage: Bulk command
transfer result=0
Nov 17 01:15:00 krabat kernel: [ 1230.199904] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 122880 bytes, 14 entries
Nov 17 01:15:00 krabat kernel: [ 1230.226621] usb-storage: Status code
0; transferred 122880/122880
Nov 17 01:15:00 krabat kernel: [ 1230.226624] usb-storage: -- transfer
complete
Nov 17 01:15:00 krabat kernel: [ 1230.226627] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:15:00 krabat kernel: [ 1230.226629] usb-storage: Attempting to
get CSW...
Nov 17 01:15:00 krabat kernel: [ 1230.226631] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:15:01 krabat kernel: [ 1230.379301] usb 3-1: device not
accepting address 4, error -110
Nov 17 01:15:01 krabat kernel: [ 1230.435354] ehci_hcd 0000:00:1d.7:
port 1 high speed
Nov 17 01:15:01 krabat kernel: [ 1230.435361] ehci_hcd 0000:00:1d.7:
GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
Nov 17 01:15:01 krabat kernel: [ 1230.491016] usb 3-1: reset high speed
USB device using ehci_hcd and address 4
Nov 17 01:15:06 krabat kernel: [ 1235.489802] usb 3-1: usb-storage timed
out on ep0in len=0/64
Nov 17 01:15:06 krabat kernel: [ 1235.489907] usb-storage: Status code
0; transferred 13/13
Nov 17 01:15:06 krabat kernel: [ 1235.489909] usb-storage: -- transfer
complete
Nov 17 01:15:06 krabat kernel: [ 1235.489912] usb-storage: Bulk status
result = 0
Nov 17 01:15:06 krabat kernel: [ 1235.489915] usb-storage: Bulk Status S
0x53425355 T 0x72e20 R 0 Stat 0x0
Nov 17 01:15:06 krabat kernel: [ 1235.489918] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:15:06 krabat kernel: [ 1235.489922] usb-storage: *** thread
sleeping.
Nov 17 01:15:06 krabat kernel: [ 1235.489942] usb-storage: queuecommand
called
Nov 17 01:15:06 krabat kernel: [ 1235.489954] usb-storage: *** thread
awakened.
Nov 17 01:15:06 krabat kernel: [ 1235.489958] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:15:06 krabat kernel: [ 1235.489961] usb-storage: 28 00 20 c5
41 da 00 00 10 00
Nov 17 01:15:06 krabat kernel: [ 1235.489974] usb-storage: Bulk Command
S 0x43425355 T 0x72e21 L 8192 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:15:06 krabat kernel: [ 1235.489979] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:15:06 krabat kernel: [ 1235.513844] usb-storage: Status code
0; transferred 31/31
Nov 17 01:15:06 krabat kernel: [ 1235.513847] usb-storage: -- transfer
complete
Nov 17 01:15:06 krabat kernel: [ 1235.513850] usb-storage: Bulk command
transfer result=0
Nov 17 01:15:06 krabat kernel: [ 1235.513853] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 8192 bytes, 1 entries
Nov 17 01:15:06 krabat kernel: [ 1235.515203] usb-storage: Status code
0; transferred 8192/8192
Nov 17 01:15:06 krabat kernel: [ 1235.515205] usb-storage: -- transfer
complete
Nov 17 01:15:06 krabat kernel: [ 1235.515208] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:15:06 krabat kernel: [ 1235.515209] usb-storage: Attempting to
get CSW...
Nov 17 01:15:06 krabat kernel: [ 1235.515212] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:15:06 krabat kernel: [ 1235.537775] usb-storage: Status code
0; transferred 13/13
Nov 17 01:15:06 krabat kernel: [ 1235.537778] usb-storage: -- transfer
complete
Nov 17 01:15:06 krabat kernel: [ 1235.537780] usb-storage: Bulk status
result = 0
Nov 17 01:15:06 krabat kernel: [ 1235.537783] usb-storage: Bulk Status S
0x53425355 T 0x72e21 R 0 Stat 0x0
Nov 17 01:15:06 krabat kernel: [ 1235.537786] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:15:06 krabat kernel: [ 1235.537790] usb-storage: *** thread
sleeping.
Nov 17 01:15:06 krabat kernel: [ 1235.537808] usb-storage: queuecommand
called
Nov 17 01:15:06 krabat kernel: [ 1235.537819] usb-storage: *** thread
awakened.
Nov 17 01:15:06 krabat kernel: [ 1235.537823] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:15:06 krabat kernel: [ 1235.537826] usb-storage: 28 00 20 c5
41 ea 00 00 f0 00
Nov 17 01:15:06 krabat kernel: [ 1235.537839] usb-storage: Bulk Command
S 0x43425355 T 0x72e22 L 122880 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:15:06 krabat kernel: [ 1235.537844] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:15:06 krabat kernel: [ 1235.561711] usb-storage: Status code
0; transferred 31/31
Nov 17 01:15:06 krabat kernel: [ 1235.561714] usb-storage: -- transfer
complete
Nov 17 01:15:06 krabat kernel: [ 1235.561716] usb-storage: Bulk command
transfer result=0
Nov 17 01:15:06 krabat kernel: [ 1235.561719] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 122880 bytes, 19 entries
Nov 17 01:15:06 krabat kernel: [ 1235.569776] ehci_hcd 0000:00:1d.7:
port 1 high speed
Nov 17 01:15:06 krabat kernel: [ 1235.569782] ehci_hcd 0000:00:1d.7:
GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
Nov 17 01:15:06 krabat kernel: [ 1235.588475] usb-storage: Status code
0; transferred 122880/122880
Nov 17 01:15:06 krabat kernel: [ 1235.588478] usb-storage: -- transfer
complete
Nov 17 01:15:06 krabat kernel: [ 1235.588481] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:15:06 krabat kernel: [ 1235.588483] usb-storage: Attempting to
get CSW...
Nov 17 01:15:06 krabat kernel: [ 1235.588486] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:15:11 krabat kernel: [ 1240.624209] usb 3-1: usb-storage timed
out on ep0out len=0/0
Nov 17 01:15:11 krabat kernel: [ 1240.624332] usb-storage: Status code
0; transferred 13/13
Nov 17 01:15:11 krabat kernel: [ 1240.624335] usb-storage: -- transfer
complete
Nov 17 01:15:11 krabat kernel: [ 1240.624337] usb-storage: Bulk status
result = 0
Nov 17 01:15:11 krabat kernel: [ 1240.624340] usb-storage: Bulk Status S
0x53425355 T 0x72e22 R 0 Stat 0x0
Nov 17 01:15:11 krabat kernel: [ 1240.624343] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:15:11 krabat kernel: [ 1240.624347] usb-storage: *** thread
sleeping.
Nov 17 01:15:11 krabat kernel: [ 1240.624368] usb-storage: queuecommand
called
Nov 17 01:15:11 krabat kernel: [ 1240.624382] usb-storage: *** thread
awakened.
Nov 17 01:15:11 krabat kernel: [ 1240.624386] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:15:11 krabat kernel: [ 1240.624388] usb-storage: 28 00 20 c5
42 da 00 00 10 00
Nov 17 01:15:11 krabat kernel: [ 1240.624401] usb-storage: Bulk Command
S 0x43425355 T 0x72e23 L 8192 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:15:11 krabat kernel: [ 1240.624407] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:15:11 krabat kernel: [ 1240.648270] usb-storage: Status code
0; transferred 31/31
Nov 17 01:15:11 krabat kernel: [ 1240.648274] usb-storage: -- transfer
complete
Nov 17 01:15:11 krabat kernel: [ 1240.648276] usb-storage: Bulk command
transfer result=0
Nov 17 01:15:11 krabat kernel: [ 1240.648279] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 8192 bytes, 2 entries
Nov 17 01:15:11 krabat kernel: [ 1240.672332] usb-storage: Status code
0; transferred 8192/8192
Nov 17 01:15:11 krabat kernel: [ 1240.672335] usb-storage: -- transfer
complete
Nov 17 01:15:11 krabat kernel: [ 1240.672338] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:15:11 krabat kernel: [ 1240.672340] usb-storage: Attempting to
get CSW...
Nov 17 01:15:11 krabat kernel: [ 1240.672343] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:15:16 krabat kernel: [ 1245.826495] usb 3-1: usb-storage timed
out on ep0out len=0/0
Nov 17 01:15:16 krabat kernel: [ 1245.826550] usb-storage: Status code
0; transferred 13/13
Nov 17 01:15:16 krabat kernel: [ 1245.826552] usb-storage: -- transfer
complete
Nov 17 01:15:16 krabat kernel: [ 1245.826555] usb-storage: Bulk status
result = 0
Nov 17 01:15:16 krabat kernel: [ 1245.826557] usb-storage: Bulk Status S
0x53425355 T 0x72e23 R 0 Stat 0x0
Nov 17 01:15:16 krabat kernel: [ 1245.826561] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:15:16 krabat kernel: [ 1245.826564] usb-storage: *** thread
sleeping.
Nov 17 01:15:16 krabat kernel: [ 1245.826581] usb-storage: queuecommand
called
Nov 17 01:15:16 krabat kernel: [ 1245.826593] usb-storage: *** thread
awakened.
Nov 17 01:15:16 krabat kernel: [ 1245.826597] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:15:16 krabat kernel: [ 1245.826600] usb-storage: 28 00 20 c5
42 ea 00 00 f0 00
Nov 17 01:15:16 krabat kernel: [ 1245.826613] usb-storage: Bulk Command
S 0x43425355 T 0x72e24 L 122880 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:15:16 krabat kernel: [ 1245.826617] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:15:16 krabat kernel: [ 1245.850498] usb-storage: Status code
0; transferred 31/31
Nov 17 01:15:16 krabat kernel: [ 1245.850501] usb-storage: -- transfer
complete
Nov 17 01:15:16 krabat kernel: [ 1245.850504] usb-storage: Bulk command
transfer result=0
Nov 17 01:15:16 krabat kernel: [ 1245.850507] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 122880 bytes, 23 entries
Nov 17 01:15:16 krabat kernel: [ 1245.877356] usb-storage: Status code
0; transferred 122880/122880
Nov 17 01:15:16 krabat kernel: [ 1245.877360] usb-storage: -- transfer
complete
Nov 17 01:15:16 krabat kernel: [ 1245.877362] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:15:16 krabat kernel: [ 1245.877364] usb-storage: Attempting to
get CSW...
Nov 17 01:15:16 krabat kernel: [ 1245.877367] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:15:16 krabat kernel: [ 1246.029964] usb 3-1: device not
accepting address 4, error -110
Nov 17 01:15:16 krabat kernel: [ 1246.086081] ehci_hcd 0000:00:1d.7:
port 1 high speed
Nov 17 01:15:16 krabat kernel: [ 1246.086088] ehci_hcd 0000:00:1d.7:
GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
--------------------------------------------------

If more infos are needed (or the complete log)...

Regards,

Alexander
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo(a)vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/
From: Alexander Holler on
This isn't my time, :(

Am 17.11.2009 01:52, schrieb Alexander Holler:
> Here it is, I haven't cut anything in between and have choosen some
> lines (of the 1gb-log I've got) before and after the first reset-message:
>
> --------------------------------------------------
> Nov 17 01:14:50 krabat kernel: [ 1219.910972] usb-storage: Bulk command
> transfer result=0

that was the second reset message, here is the first:

------------------------
Nov 17 01:14:14 krabat kernel: [ 1184.253782] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:14:14 krabat kernel: [ 1184.253784] usb-storage: Attempting to
get CSW...
Nov 17 01:14:14 krabat kernel: [ 1184.253786] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:14:14 krabat kernel: [ 1184.253795] usb-storage: Status code
0; transferred 8192/8192
Nov 17 01:14:14 krabat kernel: [ 1184.253797] usb-storage: -- transfer
complete
Nov 17 01:14:14 krabat kernel: [ 1184.253799] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:14:14 krabat kernel: [ 1184.253801] usb-storage: Attempting to
get CSW...
Nov 17 01:14:14 krabat kernel: [ 1184.253804] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:14:45 krabat kernel: [ 1214.716721] usb-storage: command_abort
called
Nov 17 01:14:45 krabat kernel: [ 1214.716725] usb-storage:
usb_stor_stop_transport called
Nov 17 01:14:45 krabat kernel: [ 1214.716727] usb-storage: -- cancelling URB
Nov 17 01:14:45 krabat kernel: [ 1214.728631] usb-storage: Status code
-104; transferred 0/13
Nov 17 01:14:45 krabat kernel: [ 1214.728634] usb-storage: -- transfer
cancelled
Nov 17 01:14:45 krabat kernel: [ 1214.728637] usb-storage: Bulk status
result = 4
Nov 17 01:14:45 krabat kernel: [ 1214.728639] usb-storage: -- command
was aborted
Nov 17 01:14:45 krabat kernel: [ 1214.728643] usb-storage:
usb_stor_pre_reset
Nov 17 01:14:45 krabat kernel: [ 1214.728738] usb-storage: Status code
0; transferred 13/13
Nov 17 01:14:45 krabat kernel: [ 1214.728741] usb-storage: -- transfer
complete
Nov 17 01:14:45 krabat kernel: [ 1214.728743] usb-storage: Bulk status
result = 0
Nov 17 01:14:45 krabat kernel: [ 1214.728746] usb-storage: Bulk Status S
0x53425355 T 0x72e1b R 0 Stat 0x0
Nov 17 01:14:45 krabat kernel: [ 1214.728750] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:14:45 krabat kernel: [ 1214.728754] usb-storage: *** thread
sleeping.
Nov 17 01:14:45 krabat kernel: [ 1214.728772] usb-storage: queuecommand
called
Nov 17 01:14:45 krabat kernel: [ 1214.728784] usb-storage: *** thread
awakened.
Nov 17 01:14:45 krabat kernel: [ 1214.728788] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:14:45 krabat kernel: [ 1214.728790] usb-storage: 28 00 20 c5
3e ea 00 00 f0 00
Nov 17 01:14:45 krabat kernel: [ 1214.728804] usb-storage: Bulk Command
S 0x43425355 T 0x72e1c L 122880 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:14:45 krabat kernel: [ 1214.728809] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:14:45 krabat kernel: [ 1214.752624] usb-storage: Status code
0; transferred 31/31
Nov 17 01:14:45 krabat kernel: [ 1214.752627] usb-storage: -- transfer
complete
Nov 17 01:14:45 krabat kernel: [ 1214.752629] usb-storage: Bulk command
transfer result=0
Nov 17 01:14:45 krabat kernel: [ 1214.752633] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 122880 bytes, 8 entries
Nov 17 01:14:45 krabat kernel: [ 1214.779473] usb-storage: Status code
0; transferred 122880/122880
Nov 17 01:14:45 krabat kernel: [ 1214.779476] usb-storage: -- transfer
complete
Nov 17 01:14:45 krabat kernel: [ 1214.779478] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:14:45 krabat kernel: [ 1214.779480] usb-storage: Attempting to
get CSW...
Nov 17 01:14:45 krabat kernel: [ 1214.779483] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:14:45 krabat kernel: [ 1214.784753] ehci_hcd 0000:00:1d.7:
port 1 high speed
Nov 17 01:14:45 krabat kernel: [ 1214.784760] ehci_hcd 0000:00:1d.7:
GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
Nov 17 01:14:45 krabat kernel: [ 1214.840337] usb 3-1: reset high speed
USB device using ehci_hcd and address 4
Nov 17 01:14:50 krabat kernel: [ 1219.839123] usb 3-1: usb-storage timed
out on ep0in len=0/64
Nov 17 01:14:50 krabat kernel: [ 1219.839213] usb-storage: Status code
0; transferred 13/13
Nov 17 01:14:50 krabat kernel: [ 1219.839216] usb-storage: -- transfer
complete
Nov 17 01:14:50 krabat kernel: [ 1219.839218] usb-storage: Bulk status
result = 0
Nov 17 01:14:50 krabat kernel: [ 1219.839221] usb-storage: Bulk Status S
0x53425355 T 0x72e1c R 0 Stat 0x0
Nov 17 01:14:50 krabat kernel: [ 1219.839225] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:14:50 krabat kernel: [ 1219.839229] usb-storage: *** thread
sleeping.
Nov 17 01:14:50 krabat kernel: [ 1219.839250] usb-storage: queuecommand
called
Nov 17 01:14:50 krabat kernel: [ 1219.839263] usb-storage: *** thread
awakened.
Nov 17 01:14:50 krabat kernel: [ 1219.839267] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:14:50 krabat kernel: [ 1219.839270] usb-storage: 28 00 20 c5
3f da 00 00 10 00
Nov 17 01:14:50 krabat kernel: [ 1219.839283] usb-storage: Bulk Command
S 0x43425355 T 0x72e1d L 8192 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:14:50 krabat kernel: [ 1219.839288] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:14:50 krabat kernel: [ 1219.863238] usb-storage: Status code
0; transferred 31/31
Nov 17 01:14:50 krabat kernel: [ 1219.863241] usb-storage: -- transfer
complete
Nov 17 01:14:50 krabat kernel: [ 1219.863243] usb-storage: Bulk command
transfer result=0
Nov 17 01:14:50 krabat kernel: [ 1219.863246] usb-storage:
usb_stor_bulk_transfer_sglist: xfer 8192 bytes, 2 entries
Nov 17 01:14:50 krabat kernel: [ 1219.864602] usb-storage: Status code
0; transferred 8192/8192
Nov 17 01:14:50 krabat kernel: [ 1219.864605] usb-storage: -- transfer
complete
Nov 17 01:14:50 krabat kernel: [ 1219.864607] usb-storage: Bulk data
transfer result 0x0
Nov 17 01:14:50 krabat kernel: [ 1219.864609] usb-storage: Attempting to
get CSW...
Nov 17 01:14:50 krabat kernel: [ 1219.864612] usb-storage:
usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 17 01:14:50 krabat kernel: [ 1219.887021] usb-storage: Status code
0; transferred 13/13
Nov 17 01:14:50 krabat kernel: [ 1219.887024] usb-storage: -- transfer
complete
Nov 17 01:14:50 krabat kernel: [ 1219.887027] usb-storage: Bulk status
result = 0
Nov 17 01:14:50 krabat kernel: [ 1219.887030] usb-storage: Bulk Status S
0x53425355 T 0x72e1d R 0 Stat 0x0
Nov 17 01:14:50 krabat kernel: [ 1219.887033] usb-storage: scsi cmd
done, result=0x0
Nov 17 01:14:50 krabat kernel: [ 1219.887037] usb-storage: *** thread
sleeping.
Nov 17 01:14:50 krabat kernel: [ 1219.887053] usb-storage: queuecommand
called
Nov 17 01:14:50 krabat kernel: [ 1219.887064] usb-storage: *** thread
awakened.
Nov 17 01:14:50 krabat kernel: [ 1219.887068] usb-storage: Command
READ_10 (10 bytes)
Nov 17 01:14:50 krabat kernel: [ 1219.887071] usb-storage: 28 00 20 c5
3f ea 00 00 f0 00
Nov 17 01:14:50 krabat kernel: [ 1219.887083] usb-storage: Bulk Command
S 0x43425355 T 0x72e1e L 122880 F 128 Trg 0 LUN 0 CL 10
Nov 17 01:14:50 krabat kernel: [ 1219.887089] usb-storage:
usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 17 01:14:50 krabat kernel: [ 1219.910966] usb-storage: Status code
0; transferred 31/31
Nov 17 01:14:50 krabat kernel: [ 1219.910969] usb-storage: -- transfer
complete
Nov 17 01:14:50 krabat kernel: [ 1219.910972] usb-storage: Bulk command
transfer result=0
> --------------------------------------------------
>
> If more infos are needed (or the complete log)...
>
> Regards,
>
> Alexander
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo(a)vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/
From: Alexander Holler on
Hello,

I've done some more tests and have some more findings.

After the problem occured (which looks for me like a timeout during a
wait for a CSW), ehci_hcd doesn't work anymore. Low- and full-speed
devices are still working and are recognized correctly, but no
high-speed device. After unloading ehci_hcd and reloading it, high-speed
devices are recognized correctly again.
As unloading is no option for kernels which have ehci_hcd compiled in
(like the F11-kernel), is there another option to reset the driver?

Regards,

Alexander
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo(a)vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/
From: Alan Stern on
On Wed, 18 Nov 2009, Alexander Holler wrote:

> Hello,
>
> I've done some more tests and have some more findings.
>
> After the problem occured (which looks for me like a timeout during a
> wait for a CSW), ehci_hcd doesn't work anymore. Low- and full-speed
> devices are still working and are recognized correctly, but no
> high-speed device. After unloading ehci_hcd and reloading it, high-speed
> devices are recognized correctly again.
> As unloading is no option for kernels which have ehci_hcd compiled in
> (like the F11-kernel), is there another option to reset the driver?

You can unbind ehci-hcd from the controller by writing the controller's
name to the "unbind" attribute in the appropriate sysfs directory. For
example, on my system:

# ls /sys/bus/pci/drivers/ehci_hcd
0000:00:1d.7 0000:01:00.2 0000:01:01.2 bind module new_id uevent
unbind
# echo -n 0000:00:1d.7 >/sys/bus/pci/drivers/ehci_hcd/unbind

Then to rebind the driver, write the device name to the "bind"
attribute:

# echo -n 0000:00:1d.7 >/sys/bus/pci/drivers/ehci_hcd/bind

Alan Stern

--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo(a)vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/
From: Alexander Holler on
Am 18.11.2009 17:22, schrieb Alan Stern:

> You can unbind ehci-hcd from the controller by writing the controller's
> name to the "unbind" attribute in the appropriate sysfs directory. For
> example, on my system:
>
> # ls /sys/bus/pci/drivers/ehci_hcd
> 0000:00:1d.7 0000:01:00.2 0000:01:01.2 bind module new_id uevent
> unbind
> # echo -n 0000:00:1d.7>/sys/bus/pci/drivers/ehci_hcd/unbind
>
> Then to rebind the driver, write the device name to the "bind"
> attribute:
>
> # echo -n 0000:00:1d.7>/sys/bus/pci/drivers/ehci_hcd/bind

Thanks, that works nicely and takes away the need to reboot when using a
stock F11 kernel and the problem occurs.

I've recently discovered that I can provoke the error too, when using dd
on an usb-dvd writing the output to an usb-hdd. So the topic of this
thread is now certainly wrong.

And because I'm still having the problem using Kernel 2.6.31.7 and
2.6.32, I've written bug #14785. It's easier to attach long logs there.

Kind regards,

Alexander Holler
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo(a)vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/