lkml.org 
[lkml]   [2008]   [Nov]   [6]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
/
Date
SubjectRe: [BUG] usb-storage: Error in queuecommand: us->srb = ffff88006a338480
From
On 2008-11-06 at 14:36 -0500, Alan Stern wrote: 
> On Thu, 6 Nov 2008, Andrew Morton wrote:
>
> > > The error message:
> > >
> > > > [ 152.701070] usb-storage: Error in queuecommand: us->srb = ffff88007dfd0680
> > >
> > > means that the SCSI layer has told usb-storage to start a new command
> > > before the old one completed. This could happen, for example, if the
> > > SCSI layer had gotten confused and tried to cancel a command that
> > > wasn't running -- then it would think usb-storage was idle when in fact
> > > it was still busy. (This was the failure mechanism resulting from the
> > > timeout changes.)
> > >
> >
> > OK, thanks. I'll cc linux-scsi, but I have this-wont-be-getting-fixed
> > feelings on this one :(
> >
> > I wonder what's special about Brian's setup.
>
> Brian, if you can use usbmon to monitor what happens during the file
> copy and subsequent error, it might provide a clue. Instructions are
> in the kernel source file Documentation/usb/usbmon.txt.

Since it's a large file, I have posted the content of usbmon here:

http://www.kysela.org/pub/1.mon.out

I also got another kernel bug and system freeze on that copy, after the
copy process hung, when I pulled the drive out:

[ Linux version 2.6.28-rc2-00385-g721d5df ]

[ 698.265008] Code: a8 00 00 00 00 00 00 00 48 c7 86 b0 00 00 00 00 00 00 00 4c 89 c6 48 89 38 e8 a7 0c 01 00 31 c0 41 5b c3 48 8b 16 48 39 f2 75 04 <0f> 0b eb fe 48 83 be 98 00 00 00 00 74 04 0f 0b eb fe 48 8b 46
[ 698.264858] ------------[ cut here ]------------
[ 698.264865] kernel BUG at /home/brian/projects/linux-2.6/block/elevator.c:841!
[ 698.264870] invalid opcode: 0000 [#1] PREEMPT SMP
[ 698.264879] last sysfs file: /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-7/1-7:1.0/host2/target2:0:0/2:0:0:0/block/sdb/size
[ 698.264885] CPU 0
[ 698.264965] Process scsi_eh_2 (pid: 1537, threadinfo ffff880066830000, task ffff880069b26580)
[ 698.264969] Stack:
[ 698.264972] ffffffff80441fb5 ffff880069afe368 ffff880069afe178 ffff88007e3623b0
[ 698.264981] ffff88007e362040 ffff88007e362040 0000000000000286 ffff880069a33148
[ 698.264992] ffff880066831ed0 ffff880066831ee0 ffffffff80373aa9 ffff88007e362040
[ 698.265004] Call Trace:
[ 698.265007] [<ffffffff80441fb5>] ? scsi_request_fn+0x141/0x3ec
[ 698.265008] [<ffffffff80373aa9>] ? blk_invoke_request_fn+0x33/0x60
[ 698.265008] [<ffffffff80373f6b>] ? blk_run_queue+0x21/0x34
[ 698.265008] [<ffffffff80440e73>] ? scsi_queue_insert+0x97/0x9e
[ 698.265008] [<ffffffff8043ec8d>] ? scsi_eh_flush_done_q+0x5d/0x93
[ 698.265008] [<ffffffff8043f9df>] ? scsi_error_handler+0x18c/0x2e7
[ 698.265008] [<ffffffff8043f853>] ? scsi_error_handler+0x0/0x2e7
[ 698.265008] [<ffffffff8024a6b6>] ? kthread+0x47/0x75
[ 698.265008] [<ffffffff805835df>] ? trace_hardirqs_on_thunk+0x3a/0x3f
[ 698.265008] [<ffffffff8020c5c9>] ? child_rip+0xa/0x11
[ 698.265008] [<ffffffff8020bab8>] ? restore_args+0x0/0x30
[ 698.265008] [<ffffffff8024a64a>] ? kthreadd+0x16e/0x193
[ 698.265008] [<ffffffff8024a66f>] ? kthread+0x0/0x75
[ 698.265008] [<ffffffff8020c5bf>] ? child_rip+0x0/0x11
[ 698.265008] Code: a8 00 00 00 00 00 00 00 48 c7 86 b0 00 00 00 00 00 00 00 4c 89 c6 48 89 38 e8 a7 0c 01 00 31 c0 41 5b c3 48 8b 16 48 39 f2 75 04 <0f> 0b eb fe 48 83 be 98 00 00 00 00 74 04 0f 0b eb fe 48 8b 46
[ 698.265008] RIP [<ffffffff8037236c>] elv_dequeue_request+0x8/0x45
[ 698.265008] RSP <ffff880066831dc8>
[ 698.265008] ---[ end trace 60e1b9808d5b9649 ]---


Brian


\
 
 \ /
  Last update: 2008-11-07 01:57    [W:0.092 / U:0.744 seconds]
©2003-2020 Jasper Spaans|hosted at Digital Ocean and TransIP|Read the blog|Advertise on this site