> It wouldn't be surprising if the file that cp hung on was the same one
> that generated those usb_bulk/control_msg timeouts in the previous set of
I did a simpler version of what you told me, and it's the file
/sys/devices/pci0/00:09.2/usb5/5-1/product
that hangs. I can still read any other file, but that single file
fails.
Here is a trace for the relevant processes:
> Jun 16 22:49:10 ventus kernel: usb-storage D E8FC4000 4291389936 2093 1
> 2094 2078 (L-TLB)
> Jun 16 22:49:10 ventus kernel: Call Trace:
> Jun 16 22:49:10 ventus kernel: [<c011c935>] wait_for_completion+0x175/0x290
> Jun 16 22:49:10 ventus kernel: [<c011c290>] default_wake_function+0x0/0x20
> Jun 16 22:49:10 ventus kernel: [<c011c290>] default_wake_function+0x0/0x20
> Jun 16 22:49:10 ventus kernel: [<c02b9357>] usb_stor_msg_common+0x137/0x170
> Jun 16 22:49:10 ventus kernel: [<c012263f>] release_console_sem+0x23f/0x250
> Jun 16 22:49:10 ventus kernel: [<c02b98e9>] usb_stor_bulk_transfer_buf+0x79/0xc0
> Jun 16 22:49:10 ventus kernel: [<c02ba27c>] usb_stor_Bulk_transport+0x17c/0x230
> Jun 16 22:49:10 ventus kernel: [<c011c2e3>] __wake_up_common+0x33/0x50
> Jun 16 22:49:10 ventus kernel: [<c02b9aa3>] usb_stor_invoke_transport+0x23/0x390
> Jun 16 22:49:10 ventus kernel: [<c012230f>] printk+0x26f/0x310
> Jun 16 22:49:10 ventus kernel: [<c02b9180>]
> usb_stor_transparent_scsi_command+0xd0/0x110
> Jun 16 22:49:10 ventus kernel: [<c02bad20>] usb_stor_control_thread+0x4d0/0x6f0
> Jun 16 22:49:10 ventus kernel: [<c02ba850>] usb_stor_control_thread+0x0/0x6f0
> Jun 16 22:49:10 ventus kernel: [<c0107331>] kernel_thread_helper+0x5/0x14
>
> Jun 16 22:49:10 ventus kernel: scsi_eh_2 S E8F92000 4291188464 2094 1
> 2093 (L-TLB)
> Jun 16 22:49:10 ventus kernel: Call Trace:
> Jun 16 22:49:10 ventus kernel: [<c011c2a9>] default_wake_function+0x19/0x20
> Jun 16 22:49:10 ventus kernel: [<c01082a5>] __down_interruptible+0x175/0x2c0
> Jun 16 22:49:10 ventus kernel: [<c011c290>] default_wake_function+0x0/0x20
> Jun 16 22:49:10 ventus kernel: [<c010854e>] __down_failed_interruptible+0xa/0x10
> Jun 16 22:49:10 ventus kernel: [<c029f641>] .text.lock.scsi_error+0x41/0x50
> Jun 16 22:49:10 ventus kernel: [<c029f0b0>] scsi_error_handler+0x0/0x310
> Jun 16 22:49:10 ventus kernel: [<c0107331>] kernel_thread_helper+0x5/0x14
>
> Jun 16 22:49:10 ventus kernel: md5sum D E8971E24 4288961712 2174 2131
> (NOTLB)
> Jun 16 22:49:10 ventus kernel: Call Trace:
> Jun 16 22:49:10 ventus kernel: [<c011ddce>] io_schedule+0xe/0x20
> Jun 16 22:49:10 ventus kernel: [<c0140217>] __lock_page+0x87/0xb0
> Jun 16 22:49:10 ventus kernel: [<c011ea70>] autoremove_wake_function+0x0/0x40
> Jun 16 22:49:10 ventus kernel: [<c011ea70>] autoremove_wake_function+0x0/0x40
> Jun 16 22:49:10 ventus kernel: [<c0140cd3>] do_generic_mapping_read+0x183/0x460
> Jun 16 22:49:10 ventus kernel: [<c0141240>] __generic_file_aio_read+0x180/0x1a0
> Jun 16 22:49:10 ventus kernel: [<c0140fb0>] file_read_actor+0x0/0x110
> Jun 16 22:49:10 ventus kernel: [<c014132f>] generic_file_read+0x7f/0xa0
> Jun 16 22:49:10 ventus kernel: [<c0152eb0>] handle_mm_fault+0xf0/0x270
> Jun 16 22:49:10 ventus kernel: [<c011938b>] do_page_fault+0x14b/0x40a
> Jun 16 22:49:10 ventus kernel: [<c011b4b1>] try_to_wake_up+0x1f1/0x200
> Jun 16 22:49:10 ventus kernel: [<c012b0b0>] process_timeout+0x0/0x10
> Jun 16 22:49:10 ventus kernel: [<c011b4cf>] wake_up_process+0xf/0x20
> Jun 16 22:49:10 ventus kernel: [<c012b0bb>] process_timeout+0xb/0x10
> Jun 16 22:49:10 ventus kernel: [<c012ae92>] run_timer_softirq+0x2f2/0x300
> Jun 16 22:49:10 ventus kernel: [<c01649be>] vfs_read+0x9e/0xd0
> Jun 16 22:49:10 ventus kernel: [<c0164ba0>] sys_read+0x30/0x50
> Jun 16 22:49:10 ventus kernel: [<c0109c07>] syscall_call+0x7/0xb
>
> Jun 16 22:49:10 ventus kernel: cp D FFFE7555 4287842736 2176 2009
> (NOTLB)
> Jun 16 22:49:10 ventus kernel: Call Trace:
> Jun 16 22:49:10 ventus kernel: [<c012b168>] schedule_timeout+0xa8/0xd0
> Jun 16 22:49:10 ventus kernel: [<c012b0b0>] process_timeout+0x0/0x10
> Jun 16 22:49:10 ventus kernel: [<c02ae7d7>] usb_start_wait_urb+0xd7/0x190
> Jun 16 22:49:10 ventus kernel: [<c011c290>] default_wake_function+0x0/0x20
> Jun 16 22:49:10 ventus kernel: [<c011c290>] default_wake_function+0x0/0x20
> Jun 16 22:49:10 ventus kernel: [<c02ae913>] usb_internal_control_msg+0x83/0xa0
> Jun 16 22:49:10 ventus kernel: [<c02ae99e>] usb_control_msg+0x6e/0x90
> Jun 16 22:49:10 ventus kernel: [<c02af44f>] usb_get_string+0x3f/0x50
> Jun 16 22:49:10 ventus kernel: [<c02afa69>] usb_string+0xd9/0x160
> Jun 16 22:49:10 ventus kernel: [<c02b1054>] show_product+0x24/0x40
> Jun 16 22:49:10 ventus kernel: [<c027a18d>] dev_attr_show+0x1d/0x30
> Jun 16 22:49:10 ventus kernel: [<c01a7fb5>] fill_read_buffer+0x55/0x70
> Jun 16 22:49:10 ventus kernel: [<c01a808c>] sysfs_read_file+0x1c/0x40
> Jun 16 22:49:10 ventus kernel: [<c01649be>] vfs_read+0x9e/0xd0
> Jun 16 22:49:10 ventus kernel: [<c0164ba0>] sys_read+0x30/0x50
> Jun 16 22:49:10 ventus kernel: [<c0109c07>] syscall_call+0x7/0xb
The cp is the one used to copy the sysfs files.
Furthermore, this is the full log that I get when I unplug the device
(in the hung state). The cp completes successfully on unplugging, and
from then on any new cp also works fine. Its contents are "VIA
Technologies, In USB 2.0".
> Jun 16 23:04:17 ventus kernel: usb-storage: Status code -104; transferred 0/13
> Jun 16 23:04:17 ventus kernel: usb-storage: -- transfer cancelled
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk status result = 3
> Jun 16 23:04:17 ventus kernel: usb-storage: -- command was aborted
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk reset requested
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 16 23:04:17 ventus kernel: ehci_hcd 00:09.2: devpath 1 ep0out 3strikes
> Jun 16 23:04:17 ventus kernel: usb-storage: Soft reset failed: -71
> Jun 16 23:04:17 ventus kernel: usb-storage: scsi command aborted
> Jun 16 23:04:17 ventus kernel: usb-storage: *** thread sleeping.
> Jun 16 23:04:17 ventus kernel: usb-storage: queuecommand() called
> Jun 16 23:04:17 ventus kernel: usb-storage: *** thread awakened.
> Jun 16 23:04:17 ventus kernel: usb-storage: Command TEST_UNIT_READY (6 bytes)
> Jun 16 23:04:17 ventus kernel: usb-storage: 00 00 00 00 00 00
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk command S 0x43425355 T 0x121 Trg 0
> LUN 0 L 0 F 0 CL 6
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
> Jun 16 23:04:17 ventus kernel: ehci_hcd 00:09.2: devpath 1 ep2out 3strikes
> Jun 16 23:04:17 ventus kernel: usb-storage: Status code -71; transferred 0/31
> Jun 16 23:04:17 ventus kernel: usb-storage: -- unknown error
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk command transfer result=3
> Jun 16 23:04:17 ventus kernel: usb-storage: -- transport indicates error, resetting
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk reset requested
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 16 23:04:17 ventus kernel: ehci_hcd 00:09.2: devpath 1 ep0out 3strikes
> Jun 16 23:04:17 ventus kernel: usb-storage: Soft reset failed: -71
> Jun 16 23:04:17 ventus kernel: usb-storage: scsi cmd done, result=0x70000
> Jun 16 23:04:17 ventus kernel: usb-storage: *** thread sleeping.
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_storage_device_reset called
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk reset requested
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 16 23:04:17 ventus kernel: ehci_hcd 00:09.2: devpath 1 ep0out 3strikes
> Jun 16 23:04:17 ventus kernel: usb-storage: Soft reset failed: -71
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_storage_bus_reset called
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_reset_device returns -19
> Jun 16 23:04:17 ventus kernel: scsi: Device offlined - not ready after error
> recovery: host 2 channel 0 id 0 lun 0
> Jun 16 23:04:17 ventus kernel: SCSI error : <2 0 0 0> return code = 0x6070000
> Jun 16 23:04:17 ventus kernel: end_request: I/O error, dev sda, sector 47975616
> Jun 16 23:04:17 ventus kernel: usb-storage: queuecommand() called
> Jun 16 23:04:17 ventus kernel: Buffer I/O error on device sda1, logical block 5996948
> Jun 16 23:04:17 ventus kernel: usb-storage: *** thread awakened.
> Jun 16 23:04:17 ventus kernel: usb-storage: Command WRITE_10 (10 bytes)
> Jun 16 23:04:17 ventus kernel: usb-storage: 2a 00 00 00 59 68 00 00 20 00
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk command S 0x43425355 T 0x122 Trg 0
> LUN 0 L 16384 F 0 CL 10
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
> Jun 16 23:04:17 ventus kernel: usb-storage: Status code -19; transferred 0/31
> Jun 16 23:04:17 ventus kernel: usb-storage: -- unknown error
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk command transfer result=3
> Jun 16 23:04:17 ventus kernel: usb-storage: -- transport indicates error, resetting
> Jun 16 23:04:17 ventus kernel: usb-storage: Bulk reset requested
> Jun 16 23:04:17 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 16 23:04:17 ventus kernel: usb-storage: Soft reset failed: -19
> Jun 16 23:04:17 ventus kernel: usb-storage: scsi cmd done, result=0x70000
> Jun 16 23:04:17 ventus kernel: usb-storage: *** thread sleeping.
> Jun 16 23:04:17 ventus kernel: SCSI error : <2 0 0 0> return code = 0x70000
> Jun 16 23:04:17 ventus kernel: end_request: I/O error, dev sda, sector 22888
> Jun 16 23:04:17 ventus kernel: journal-601, buffer write failed
> Jun 16 23:04:17 ventus kernel: ------------[ cut here ]------------
> Jun 16 23:04:17 ventus kernel: kernel BUG at fs/reiserfs/prints.c:339!
> Jun 16 23:04:17 ventus kernel: invalid operand: 0000 [#1]
> Jun 16 23:04:17 ventus kernel: CPU: 0
> Jun 16 23:04:17 ventus kernel: EIP: 0060:[<c01e9eb9>] Not tainted
> Jun 16 23:04:17 ventus kernel: EFLAGS: 00010246
> Jun 16 23:04:17 ventus kernel: EIP is at reiserfs_panic+0x29/0x60
> Jun 16 23:04:17 ventus kernel: eax: 00000024 ebx: c03615e0 ecx: c03d93c0 edx:
> 000484d1
> Jun 16 23:04:17 ventus kernel: esi: e82d40c4 edi: 00000000 ebp: efcafda0 esp:
> efcafd88
> Jun 16 23:04:17 ventus kernel: ds: 007b es: 007b ss: 0068
> Jun 16 23:04:17 ventus kernel: Process pdflush (pid: 6, threadinfo=efcae000
> task=c17b93c0)
> Jun 16 23:04:17 ventus kernel: Stack: c035ed0e c047d4c0 c03615e0 efcafdb0 e82d40c4
> f0a74208 efcafde8 c01f4133
> Jun 16 23:04:17 ventus kernel: e82d40c4 c03615e0 00000002 00000012 e82d40c4
> 00000000 efcafdec eda107b0
> Jun 16 23:04:17 ventus kernel: e7f0d000 eda107b0 f0a7423c 00000005 00000000
> 00000003 00000004 e795bb84
> Jun 16 23:04:17 ventus kernel: Call Trace:
>
> Message from [EMAIL PROTECTED] at Mon Jun 16 23:04:17 2003 ...
> ventus kernel: journal-601, buffer write failed
> Jun 16 23:04:17 ventus kernel: [<c01f4133>] flush_commit_list+0x323/0x410
> Jun 16 23:04:17 ventus kernel: [<c01f8574>] do_journal_end+0x844/0xba0
> Jun 16 23:04:17 ventus kernel: [<c01f76d9>] flush_old_commits+0x149/0x160
> Jun 16 23:04:17 ventus kernel: [<c01e7020>] reiserfs_write_super+0xd0/0x190
> Jun 16 23:04:17 ventus kernel: [<c016d1a8>] sync_supers+0x1a8/0x260
> Jun 16 23:04:17 ventus kernel: [<c0145c35>] wb_kupdate+0x55/0x130
> Jun 16 23:04:17 ventus kernel: [<c011c012>] schedule+0x3a2/0x5d0
> Jun 16 23:04:17 ventus kernel: [<c01466fc>] __pdflush+0x37c/0x520
> Jun 16 23:04:17 ventus kernel: [<c01468a0>] pdflush+0x0/0x20
> Jun 16 23:04:17 ventus kernel: [<c01468af>] pdflush+0xf/0x20
> Jun 16 23:04:17 ventus kernel: [<c0145be0>] wb_kupdate+0x0/0x130
> Jun 16 23:04:17 ventus kernel: [<c0107331>] kernel_thread_helper+0x5/0x14
> Jun 16 23:04:17 ventus kernel:
> Jun 16 23:04:17 ventus kernel: Code: 0f 0b 53 01 14 ed 35 c0 68 c0 d4 47 c0 b8 f5 e9
> 35 c0 8d 96
Let me know if this makes sense or I have done something very wrong. I
can always reproduce this if you like, with different debugging
methods.
Andras
===========================================================================
Major Andras
e-mail: [EMAIL PROTECTED]
www: http://andras.webhop.org/
===========================================================================
-------------------------------------------------------
This SF.Net email is sponsored by: INetU
Attention Web Developers & Consultants: Become An INetU Hosting Partner.
Refer Dedicated Servers. We Manage Them. You Get 10% Monthly Commission!
INetU Dedicated Managed Hosting http://www.inetu.net/partner/index.php
_______________________________________________
[EMAIL PROTECTED]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel
