Re: USB subsystem (part of it) hangs

Oliver Neukum escreveu:
> Am Dienstag 14 August 2007 schrieb Paulo da Silva:
>   
>> Aug 14 02:40:01 H2 cron[7925]: (root) CMD (test -x /usr/sbin/run-crons
>> && /usr/sbin/run-crons )
>> Aug 14 02:41:43 H2 kernel: hub 3-0:1.0: state 7 ports 4 chg 0000 evt 0008
>> Aug 14 02:41:43 H2 kernel: ehci_hcd 0000:00:0b.2: GetStatus port 3
>> status 00100a POWER sig=se0 PEC CSC
>> Aug 14 02:41:43 H2 kernel: hub 3-0:1.0: port 3, status 0100, change
>> 0003, 12 Mb/s
>> Aug 14 02:41:43 H2 kernel: usb 3-3: USB disconnect, address 2
>> Aug 14 02:41:43 H2 kernel: usb 3-3: unregistering device
>> Aug 14 02:41:43 H2 kernel: usb 3-3: usb_disable_device nuking all URBs
>> Aug 14 02:41:43 H2 kernel: ehci_hcd 0000:00:0b.2: shutdown urb d7b94dc0
>>     
>
> Did you disconnect the device?
>
>   
No.
This may happen wether or not I was working, i.e. it may happen if the
PC is left unattended, for ex.
So, the hipothesis of disconnect it accidentally is absolutely excluded.

I tryed the following:

1. Removed the card while working normally.
2. The keyboard gets blocked.
3. I could use the mouse to copy/paste some commands I previously wrote
on the console.
- lsmod shows rt2570 loaded and in use (1).
- iwconfig and lsusb hangs (never returns any output or the prompt).
- rmmod rt2570 refuses to remove the module. It is in use.
I'm posting below part of the log I got.
4. Reinserting the card does not change anything.

If you still think a card disconnection can be the reason for this
problem I can advance the following possibility:
This card seems to drain a significative amount of current from the
laptop. I noticed that because there is a significative increase in the
fan speed when I activate the card which ceases as soon as I deactivate
it. Could this somehow cause a poor electrical connection that fails
from time to time?
Unfortunately I know nothing about USB electronics.

That's all I can say so far.

Regards.
Paulo

Aug 15 01:00:01 HAL0002 cron[7564]: (root) CMD (rm -f
/var/spool/cron/lastrun/cron.hourly)
Aug 15 01:00:15 HAL0002 kernel: ehci_hcd 0000:00:0b.2: devpath 3 ep1in
3strikes
Aug 15 01:00:15 HAL0002 kernel: hub 3-0:1.0: state 7 ports 4 chg 0000
evt 0008
Aug 15 01:00:15 HAL0002 kernel: ehci_hcd 0000:00:0b.2: GetStatus port 3
status 001002 POWER sig=se0 CSC
Aug 15 01:00:15 HAL0002 kernel: hub 3-0:1.0: port 3, status 0100, change
0001, 12 Mb/s
Aug 15 01:00:15 HAL0002 kernel: usb 3-3: USB disconnect, address 2
Aug 15 01:00:15 HAL0002 kernel: usb 3-3: unregistering device
Aug 15 01:00:15 HAL0002 kernel: usb 3-3: usb_disable_device nuking all URBs
Aug 15 01:00:15 HAL0002 kernel: usb 3-3: unregistering interface 3-3:1.0
Aug 15 01:00:15 HAL0002 kernel: usb_endpoint usbdev3.2_ep81:
ep_device_release called for usbdev3.2_ep81
Aug 15 01:00:15 HAL0002 kernel: usb_endpoint usbdev3.2_ep01:
ep_device_release called for usbdev3.2_ep01
Aug 15 01:00:15 HAL0002 kernel: unregister_netdev( )
Aug 15 01:02:57 HAL0002 kernel: _queue+0x33/0x4d
Aug 15 01:02:57 HAL0002 kernel:  [unix_poll+23/139] unix_poll+0x17/0x8b
Aug 15 01:02:57 HAL0002 kernel:  [<c02e49fb>] unix_poll+0x17/0x8b
Aug 15 01:02:57 HAL0002 kernel:  [do_select+916/993] do_select+0x394/0x3e1
Aug 15 01:02:57 HAL0002 kernel:  [<c01679a1>] do_select+0x394/0x3e1
Aug 15 01:02:57 HAL0002 kernel:  [__pollwait+0/169] __pollwait+0x0/0xa9
Aug 15 01:02:57 HAL0002 kernel:  [<c0167f3c>] __pollwait+0x0/0xa9
Aug 15 01:02:57 HAL0002 kernel:  [default_wake_function+0/12]
default_wake_function+0x0/0xc
Aug 15 01:02:57 HAL0002 kernel:  [<c01153b4>] default_wake_function+0x0/0xc
Aug 15 01:02:57 HAL0002 kernel:  [bio_add_page+49/55] bio_add_page+0x31/0x37
Aug 15 01:02:57 HAL0002 kernel:  [<c017b43e>] bio_add_page+0x31/0x37
Aug 15 01:02:57 HAL0002 kernel:  [do_mpage_readpage+960/1128]
do_mpage_readpage+0x3c0/0x468
Aug 15 01:02:57 HAL0002 kernel:  [<c017eabb>] do_mpage_readpage+0x3c0/0x468
Aug 15 01:02:57 HAL0002 kernel:  [ide_map_sg+38/113] ide_map_sg+0x26/0x71
Aug 15 01:02:57 HAL0002 kernel:  [<c026e91a>] ide_map_sg+0x26/0x71
Aug 15 01:02:57 HAL0002 kernel:  [ide_build_sglist+54/190]
ide_build_sglist+0x36/0xbe
Aug 15 01:02:57 HAL0002 kernel:  [<c02758fd>] ide_build_sglist+0x36/0xbe
Aug 15 01:02:57 HAL0002 kernel:  [lock_timer_base+33/82]
lock_timer_base+0x21/0x52
Aug 15 01:02:57 HAL0002 kernel:  [<c011f17e>] lock_timer_base+0x21/0x52
Aug 15 01:02:57 HAL0002 kernel:  [__mod_timer+154/181] __mod_timer+0x9a/0xb5
Aug 15 01:02:57 HAL0002 kernel:  [<c011f2a5>] __mod_timer+0x9a/0xb5
Aug 15 01:02:57 HAL0002 kernel:  [dma_timer_expiry+0/102]
dma_timer_expiry+0x0/0x66
Aug 15 01:02:57 HAL0002 kernel:  [<c02751ce>] dma_timer_expiry+0x0/0x66
Aug 15 01:02:57 HAL0002 kernel:  [ide_execute_command+161/195]
ide_execute_command+0xa1/0xc3
Aug 15 01:02:57 HAL0002 kernel:  [<c0270a46>] ide_execute_command+0xa1/0xc3
Aug 15 01:02:57 HAL0002 kernel:  [ide_dma_intr+0/153] ide_dma_intr+0x0/0x99
Aug 15 01:02:57 HAL0002 kernel:  [<c0275b51>] ide_dma_intr+0x0/0x99
Aug 15 01:02:57 HAL0002 kernel:  [ide_dma_exec_cmd+31/34]
ide_dma_exec_cmd+0x1f/0x22
Aug 15 01:02:57 HAL0002 kernel:  [<c02752dd>] ide_dma_exec_cmd+0x1f/0x22
Aug 15 01:02:57 HAL0002 kernel:  [ide_dma_start+33/45]
ide_dma_start+0x21/0x2d
Aug 15 01:02:57 HAL0002 kernel:  [<c02750c3>] ide_dma_start+0x21/0x2d
Aug 15 01:02:57 HAL0002 kernel:  [ide_do_rw_disk+769/987]
ide_do_rw_disk+0x301/0x3db
Aug 15 01:02:57 HAL0002 kernel:  [<c0278687>] ide_do_rw_disk+0x301/0x3db
Aug 15 01:02:57 HAL0002 kernel:  [__delay+6/7] __delay+0x6/0x7
Aug 15 01:02:57 HAL0002 kernel:  [<c0215fdd>] __delay+0x6/0x7
Aug 15 01:02:57 HAL0002 kernel:  [ide_wait_stat+182/239]
ide_wait_stat+0xb6/0xef
Aug 15 01:02:57 HAL0002 kernel:  [<c0270b82>] ide_wait_stat+0xb6/0xef
Aug 15 01:02:57 HAL0002 kernel:  [ide_do_request+2133/2250]
ide_do_request+0x855/0x8ca
Aug 15 01:02:57 HAL0002 kernel:  [<c026f393>] ide_do_request+0x855/0x8ca
Aug 15 01:02:57 HAL0002 kernel:  [__switch_to+25/345] __switch_to+0x19/0x159
Aug 15 01:02:57 HAL0002 kernel:  [<c0102c31>] __switch_to+0x19/0x159
Aug 15 01:02:57 HAL0002 kernel:  [schedule+1165/1344]
__sched_text_start+0x48d/0x540
Aug 15 01:02:57 HAL0002 kernel:  [<c0300cfd>] __sched_text_start+0x48d/0x540
Aug 15 01:02:57 HAL0002 kernel:  [schedule+1318/1344]
__sched_text_start+0x526/0x540
Aug 15 01:02:57 HAL0002 kernel:  [<c0300d96>] __sched_text_start+0x526/0x540
Aug 15 01:02:57 HAL0002 kernel:  [lock_timer_base+33/82]
lock_timer_base+0x21/0x52
Aug 15 01:02:57 HAL0002 kernel:  [<c011f17e>] lock_timer_base+0x21/0x52
Aug 15 01:02:57 HAL0002 kernel:  [sync_page+0/59] sync_page+0x0/0x3b
Aug 15 01:02:57 HAL0002 kernel:  [<c0141a3d>] sync_page+0x0/0x3b
Aug 15 01:02:57 HAL0002 kernel:  [sync_page+0/59] sync_page+0x0/0x3b
Aug 15 01:02:57 HAL0002 kernel:  [<c0141a3d>] sync_page+0x0/0x3b
Aug 15 01:02:57 HAL0002 kernel:  [io_schedule+14/22] io_schedule+0xe/0x16
Aug 15 01:02:57 HAL0002 kernel:  [<c0300e80>] io_schedule+0xe/0x16
Aug 15 01:02:57 HAL0002 kernel:  [__alloc_skb+73/245] __alloc_skb+0x49/0xf5
Aug 15 01:02:57 HAL0002 kernel:  [<c029c7fc>] __alloc_skb+0x49/0xf5
Aug 15 01:02:57 HAL0002 kernel:  [sock_alloc_send_skb+110/398]
sock_alloc_send_skb+0x6e/0x18e
Aug 15 01:02:57 HAL0002 kernel:  [<c0299392>] sock_alloc_send_skb+0x6e/0x18e
Aug 15 01:02:57 HAL0002 kernel:  [sock_def_readable+90/114]
sock_def_readable+0x5a/0x72
Aug 15 01:02:57 HAL0002 kernel:  [<c029a8a5>] sock_def_readable+0x5a/0x72
Aug 15 01:02:57 HAL0002 kernel:  [core_sys_select+682/711]
core_sys_select+0x2aa/0x2c7
Aug 15 01:02:57 HAL0002 kernel:  [<c0167c98>] core_sys_select+0x2aa/0x2c7
Aug 15 01:02:57 HAL0002 kernel:  [sock_aio_write+200/212]
sock_aio_write+0xc8/0xd4
Aug 15 01:02:57 HAL0002 kernel:  [<c0296aeb>] sock_aio_write+0xc8/0xd4
Aug 15 01:02:57 HAL0002 kernel:  [generic_file_aio_read+367/407]
generic_file_aio_read+0x16f/0x197
Aug 15 01:02:57 HAL0002 kernel:  [<c0144044>]
generic_file_aio_read+0x16f/0x197
Aug 15 01:02:57 HAL0002 kernel:  [do_sync_write+198/265]
do_sync_write+0xc6/0x109
Aug 15 01:02:57 HAL0002 kernel:  [<c015c68a>] do_sync_write+0xc6/0x109
Aug 15 01:02:57 HAL0002 kernel:  [autoremove_wake_function+0/53]
autoremove_wake_function+0x0/0x35
Aug 15 01:02:57 HAL0002 kernel:  [<c0127853>]
autoremove_wake_function+0x0/0x35
Aug 15 01:02:57 HAL0002 kernel:  [sys_select+213/391] sys_select+0xd5/0x187
Aug 15 01:02:57 HAL0002 kernel:  [<c01680ba>] sys_select+0xd5/0x187
Aug 15 01:02:57 HAL0002 kernel:  [vfs_write+252/268] vfs_write+0xfc/0x10c
Aug 15 01:02:57 HAL0002 kernel:  [<c015ce5e>] vfs_write+0xfc/0x10c
Aug 15 01:02:57 HAL0002 kernel:  [sys_write+65/103] sys_write+0x41/0x67
Aug 15 01:02:57 HAL0002 kernel:  [<c015d2c7>] sys_write+0x41/0x67
Aug 15 01:02:57 HAL0002 kernel:  [sysenter_past_esp+95/133]
sysenter_past_esp+0x5f/0x85
Aug 15 01:02:57 HAL0002 kernel:  [<c0103c7a>] sysenter_past_esp+0x5f/0x85
Aug 15 01:02:57 HAL0002 kernel:  =======================


-------------------------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >>  http://get.splunk.com/
_______________________________________________
[email protected]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel