Arch Linux

bufferunderflow

Member

Registered: 2015-04-20

Posts: 5

usb issue: lsusb freezes

First of all: this issue occures on my laptop,  but since I guess it is a driver issue I thought it should be in this part of the forum.

Occurence: So far it seems to be random to me. At some times I plug the use mouse and it does not work. If I then enter lsusb it will freeze.

~ ps ax | grep lsusb
xxxxx pts/2 D+ 0:00 lsusb

I cannot terminate the process by kill -9 or ctrl+c or ctrl+d.

I cannot modprobe -r usbcore because it is in use...

any ideas?

PS: Obviously I searched the web for solutions but non of them seem to be very helpful.
A reboot will fix this issue but is not the optimal way to go about this...

Last edited by bufferunderflow (2015-08-21 09:59:15)

awes

Member

Registered: 2015-03-13

Posts: 26

Re: usb issue: lsusb freezes

Hi,
I've also faced this issue. I've tried to use lsusb, since my laptop didn't want to detect any USB device that I have hot plugged (dmesg shows literally nothing about it) - I think this strange state is related to sleep/suspend; using kernel 4.1.6-1, but experienced the problem some time ago with some older version.

Checking lsusb with strace:

... lots of lines ...
14:12:10.890650 open("/sys/bus/usb/devices/usb3/speed", O_RDONLY) = 7
14:12:10.890669 fstat(7, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
14:12:10.890685 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fd9a330a000
14:12:10.890701 read(7, "480\n", 4096)  = 4
14:12:10.890718 close(7)                = 0
14:12:10.890733 munmap(0x7fd9a330a000, 4096) = 0
14:12:10.890751 open("/sys/bus/usb/devices/usb3/descriptors", O_RDONLY) = 7
14:12:10.890771 read(7, 

If I do "cat /sys/bus/usb/devices/usb3/descriptors", it also hangs forever.
Looks like the USB subsystem doesn't want to provide information....

Last edited by awes (2015-09-02 12:20:42)

awes

Member

Registered: 2015-03-13

Posts: 26

Re: usb issue: lsusb freezes

Hi.

I have experienced this issue again, this time my machine was working for a few hours and did not sleep or hibernate before.
Problem appeared after disconnecting a USB 3.0 HDD.
Here's a dmesg log, maybe you can draw some conclusions.

[pon wrz 14 11:08:15 2015] usb 1-4: reset high-speed USB device number 2 using xhci_hcd
[pon wrz 14 11:08:30 2015] usb 1-4: device descriptor read/64, error -110
[pon wrz 14 11:08:46 2015] usb 1-4: device descriptor read/64, error -110                                                                                                                                            
[pon wrz 14 11:08:46 2015] usb 1-4: reset high-speed USB device number 2 using xhci_hcd
[pon wrz 14 11:09:02 2015] usb 1-4: device descriptor read/64, error -110
[pon wrz 14 11:09:17 2015] usb 1-4: device descriptor read/64, error -110                                                                                                                                            
[pon wrz 14 11:09:18 2015] usb 1-4: reset high-speed USB device number 2 using xhci_hcd
[pon wrz 14 11:09:23 2015] xhci_hcd 0000:00:14.0: Command completion event does not match command
[pon wrz 14 11:09:23 2015] xhci_hcd 0000:00:14.0: Timeout while waiting for setup device command
[pon wrz 14 11:09:28 2015] xhci_hcd 0000:00:14.0: Timeout while waiting for setup device command
[pon wrz 14 11:09:28 2015] usb 1-4: device not accepting address 2, error -62
[pon wrz 14 11:09:28 2015] usb 1-4: reset high-speed USB device number 2 using xhci_hcd
[pon wrz 14 11:09:33 2015] usb 1-4: device descriptor read/8, error -110
[pon wrz 14 11:09:38 2015] usb 1-4: device descriptor read/8, error -110                                                                                                                                             
[pon wrz 14 11:09:39 2015] usb 1-4: USB disconnect, device number 2
[pon wrz 14 11:11:59 2015] INFO: task rtsx_usb_ms_1:400 blocked for more than 120 seconds.
[pon wrz 14 11:11:59 2015]       Not tainted 4.1.6-1-ARCH #1
[pon wrz 14 11:11:59 2015] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[pon wrz 14 11:11:59 2015] rtsx_usb_ms_1   D ffff880446517ce8     0   400      2 0x00000000
[pon wrz 14 11:11:59 2015]  ffff880446517ce8 ffffffff818124c0 ffff88044a863d20 ffff880446517cc8
[pon wrz 14 11:11:59 2015]  ffff880446518000 0000000046517e58 ffff880446517d88 0000000000000000
[pon wrz 14 11:11:59 2015]  0000000000000258 ffff880446517d08 ffffffff81588377 0000000046517e58
[pon wrz 14 11:11:59 2015] Call Trace:
[pon wrz 14 11:11:59 2015]  [<ffffffff81588377>] schedule+0x37/0x90
[pon wrz 14 11:11:59 2015]  [<ffffffffa0036135>] usb_kill_urb.part.4+0x55/0xa0 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffff810bc720>] ? wake_atomic_t_function+0x60/0x60
[pon wrz 14 11:11:59 2015]  [<ffffffffa00361a1>] usb_kill_urb+0x21/0x30 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa00366e8>] usb_start_wait_urb+0xe8/0x180 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffff811c0202>] ? process_slab+0x252/0x410
[pon wrz 14 11:11:59 2015]  [<ffffffffa0036bed>] usb_bulk_msg+0xbd/0x170 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa00102e3>] rtsx_usb_send_cmd+0x63/0x90 [rtsx_usb]
[pon wrz 14 11:11:59 2015]  [<ffffffffa001093c>] rtsx_usb_read_register+0x6c/0xc0 [rtsx_usb]
[pon wrz 14 11:11:59 2015]  [<ffffffffa04813be>] rtsx_usb_detect_ms_card+0x6e/0x110 [rtsx_usb_ms]
[pon wrz 14 11:11:59 2015]  [<ffffffffa0481350>] ? ms_pull_ctl_enable_lqfp48+0x100/0x100 [rtsx_usb_ms]
[pon wrz 14 11:11:59 2015]  [<ffffffff81097868>] kthread+0xd8/0xf0
[pon wrz 14 11:11:59 2015]  [<ffffffff81097790>] ? kthread_worker_fn+0x170/0x170
[pon wrz 14 11:11:59 2015]  [<ffffffff8158c3a2>] ret_from_fork+0x42/0x70
[pon wrz 14 11:11:59 2015]  [<ffffffff81097790>] ? kthread_worker_fn+0x170/0x170
[pon wrz 14 11:11:59 2015] INFO: task kworker/3:2:19573 blocked for more than 120 seconds.
[pon wrz 14 11:11:59 2015]       Not tainted 4.1.6-1-ARCH #1
[pon wrz 14 11:11:59 2015] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[pon wrz 14 11:11:59 2015] kworker/3:2     D ffff880436bab9f8     0 19573      2 0x00000000
[pon wrz 14 11:11:59 2015] Workqueue: usb_hub_wq hub_event [usbcore]
[pon wrz 14 11:11:59 2015]  ffff880436bab9f8 ffff88044cea0a30 ffff880436880000 ffff880436bab9d8
[pon wrz 14 11:11:59 2015]  ffff880436bac000 ffff88044b149210 ffff880097aa83c0 ffff88044b35b508
[pon wrz 14 11:11:59 2015]  ffff88044b1491f0 ffff880436baba18 ffffffff81588377 ffff88044b149210
[pon wrz 14 11:11:59 2015] Call Trace:
[pon wrz 14 11:11:59 2015]  [<ffffffff81588377>] schedule+0x37/0x90
[pon wrz 14 11:11:59 2015]  [<ffffffffa0036135>] usb_kill_urb.part.4+0x55/0xa0 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffff810bc720>] ? wake_atomic_t_function+0x60/0x60
[pon wrz 14 11:11:59 2015]  [<ffffffffa00361a1>] usb_kill_urb+0x21/0x30 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa0034cb5>] usb_hcd_flush_endpoint+0xb5/0x190 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa0037d98>] usb_disable_endpoint+0xa8/0xb0 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa0037de9>] usb_disable_interface+0x49/0x60 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa003a9be>] usb_unbind_interface+0x1de/0x290 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffff813ff5b3>] ? rpm_idle+0x23/0x2a0
[pon wrz 14 11:11:59 2015]  [<ffffffff813f4647>] __device_release_driver+0x87/0x120
[pon wrz 14 11:11:59 2015]  [<ffffffff813f4703>] device_release_driver+0x23/0x30
[pon wrz 14 11:11:59 2015]  [<ffffffff813f3f88>] bus_remove_device+0x108/0x180
[pon wrz 14 11:11:59 2015]  [<ffffffff813f01d1>] device_del+0x141/0x270
[pon wrz 14 11:11:59 2015]  [<ffffffffa0037e91>] usb_disable_device+0x91/0x290 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa002d184>] usb_disconnect+0x94/0x2d0 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffffa002f5ab>] hub_event+0x7ab/0x1620 [usbcore]
[pon wrz 14 11:11:59 2015]  [<ffffffff810919ab>] process_one_work+0x14b/0x470
[pon wrz 14 11:11:59 2015]  [<ffffffff81091d18>] worker_thread+0x48/0x4c0
[pon wrz 14 11:11:59 2015]  [<ffffffff81091cd0>] ? process_one_work+0x470/0x470
[pon wrz 14 11:11:59 2015]  [<ffffffff81097868>] kthread+0xd8/0xf0
[pon wrz 14 11:11:59 2015]  [<ffffffff81097790>] ? kthread_worker_fn+0x170/0x170
[pon wrz 14 11:11:59 2015]  [<ffffffff8158c3a2>] ret_from_fork+0x42/0x70
[pon wrz 14 11:11:59 2015]  [<ffffffff81097790>] ? kthread_worker_fn+0x170/0x170
[pon wrz 14 11:11:59 2015] INFO: task kworker/u16:4:22188 blocked for more than 120 seconds.
[pon wrz 14 11:11:59 2015]       Not tainted 4.1.6-1-ARCH #1
[pon wrz 14 11:11:59 2015] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[pon wrz 14 11:11:59 2015] kworker/u16:4   D ffff880071c4fcc8     0 22188      2 0x00000000
[pon wrz 14 11:11:59 2015] Workqueue: kmmcd mmc_rescan [mmc_core]
[pon wrz 14 11:11:59 2015]  ffff880071c4fcc8 ffff88044cea32f0 ffff8803ff883d20 ffff880071c4fce8
[pon wrz 14 11:11:59 2015]  ffff880071c50000 ffff88044ad47d0c ffff8803ff883d20 00000000ffffffff
[pon wrz 14 11:11:59 2015]  ffff88044ad47d10 ffff880071c4fce8 ffffffff81588377 ffff88044ad47c18
[pon wrz 14 11:11:59 2015] Call Trace:
[pon wrz 14 11:11:59 2015]  [<ffffffff81588377>] schedule+0x37/0x90
[pon wrz 14 11:11:59 2015]  [<ffffffff81588745>] schedule_preempt_disabled+0x15/0x20
[pon wrz 14 11:11:59 2015]  [<ffffffff81589c85>] __mutex_lock_slowpath+0xd5/0x150
[pon wrz 14 11:11:59 2015]  [<ffffffff81589d1b>] mutex_lock+0x1b/0x30
[pon wrz 14 11:11:59 2015]  [<ffffffffa00ade89>] sdmmc_get_cd+0x49/0xb0 [rtsx_usb_sdmmc]
[pon wrz 14 11:11:59 2015]  [<ffffffffa006ab68>] mmc_rescan+0x1e8/0x300 [mmc_core]
[pon wrz 14 11:11:59 2015]  [<ffffffff810919ab>] process_one_work+0x14b/0x470
[pon wrz 14 11:11:59 2015]  [<ffffffff81091d18>] worker_thread+0x48/0x4c0
[pon wrz 14 11:11:59 2015]  [<ffffffff81091cd0>] ? process_one_work+0x470/0x470
[pon wrz 14 11:11:59 2015]  [<ffffffff81091cd0>] ? process_one_work+0x470/0x470
[pon wrz 14 11:11:59 2015]  [<ffffffff81097868>] kthread+0xd8/0xf0
[pon wrz 14 11:11:59 2015]  [<ffffffff81097790>] ? kthread_worker_fn+0x170/0x170
[pon wrz 14 11:11:59 2015]  [<ffffffff8158c3a2>] ret_from_fork+0x42/0x70
[pon wrz 14 11:11:59 2015]  [<ffffffff81097790>] ? kthread_worker_fn+0x170/0x170

odror

Member

Registered: 2015-03-17

Posts: 168

Re: usb issue: lsusb freezes

I have the same issue. Did you find a solution?

awes

Member

Registered: 2015-03-13

Posts: 26

Re: usb issue: lsusb freezes

No, unfortunately I haven't.
I suppose it's a driver bug for a specific hardware.