Hi,
I am using linux kernel 4.1.15Here is the "lsusb -tv² output (Just shown
related devices)
# lsusb -tv
/: Bus 04.Port 1: Dev 1, Class=root_hub, Driver=ohci-pci/3p, 12M
|__ Port 1: Dev 2, If 0, Class=Communications, Driver=cdc_acm, 12M
|__ Port 1: Dev 2, If 1, Class=CDC Data, Driver=cdc_acm, 12M
|__ Port 3: Dev 3, If 0, Class=Vendor Specific Class, Driver=ftdi_sio,
12Mw
We have /dev/ttyUSB0 and /dev/ttyACM0 devices, system works for few
minutes when we use /dev/ttyACM0 device to communicate, but after running
couple of minutes /dev/ttyACM0 device doesn't respond and stuck
infinitly, by looking at the process stack trace it shows following. what
could be the reason? I also tried to backport drivers/usb/core/urb.c,
drivers/usb/core/hcd.c, drivers/usb/class/cdc-acm.c from v4.7-rc4 to
v4.1.15, still getting the same issue.
# cat /proc/45194/stack
[<ffffffff81491985>] usb_kill_urb+0x85/0xc0
[<ffffffff814c2960>] acm_port_shutdown+0xb0/0xd0
[<ffffffff8131e2a4>] tty_port_shutdown+0x74/0xa0
[<ffffffff8131ece1>] tty_port_close+0x21/0x50
[<ffffffff814c307d>] acm_tty_close+0x1d/0x20
[<ffffffff81314c8b>] tty_release+0x12b/0x5e0
[<ffffffff81144b47>] __fput+0x97/0x1d0
[<ffffffff81144cc9>] ____fput+0x9/0x10
[<ffffffff8106f0a7>] task_work_run+0xb7/0xf0
[<ffffffff81003e05>] do_notify_resume+0x65/0x70
[<ffffffff8165133c>] int_signal+0x12/0x17
# dmesg
[ 845.334978] tty ttyACM0: acm_tty_install
[ 845.334986] tty ttyACM0: acm_tty_open
[ 845.334988] cdc_acm 4-1:1.0: acm_port_activate
[ 845.334997] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 0
[ 845.335000] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 1
[ 845.335002] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 2
[ 845.335004] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 3
[ 845.335006] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 4
[ 845.335009] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 5
[ 845.335010] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 6
[ 845.335012] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 7
[ 845.335014] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 8
[ 845.335017] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 9
[ 845.335019] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 10
[ 845.335021] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 11
[ 845.335023] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 12
[ 845.335026] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 13
[ 845.335028] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 14
[ 845.335030] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 15
[ 845.336100] cdc_acm 4-1:1.0: acm_ctrl_msg - rq 0x22, val 0x3, len 0x0,
result 0
[ 845.337117] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 0, len 1
[ 845.337152] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 0
[ 845.337256] cdc_acm 4-1:1.1: acm_tty_write - count 3
[ 845.337259] cdc_acm 4-1:1.1: acm_tty_write - write 3
[ 845.338100] cdc_acm 4-1:1.1: acm_softint
[ 845.339089] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 1, len 12
[ 845.339094] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 1
[ 845.339126] cdc_acm 4-1:1.0: acm_tty_close
[ 845.340103] cdc_acm 4-1:1.0: acm_ctrl_msg - rq 0x22, val 0x0, len 0x0,
result 0
[ 845.340105] cdc_acm 4-1:1.0: acm_port_shutdown
[ 845.341176] cdc_acm 4-1:1.0: acm_ctrl_irq - urb shutting down with
status: -2
[ 845.342160] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 0, len 0
[ 845.342225] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.342313] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 1, len 0
[ 845.342377] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.342465] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 2, len 0
[ 845.342530] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.342618] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 3, len 0
[ 845.342622] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.343095] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 4, len 0
[ 845.343098] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.344122] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 5, len 0
[ 845.344137] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.345104] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 6, len 0
[ 845.345106] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.346110] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 7, len 0
[ 845.346114] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.347099] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 8, len 0
[ 845.347102] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.348100] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 9, len 0
[ 845.348103] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.349103] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 10, len 0
[ 845.349106] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.350103] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 11, len 0
[ 845.350106] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.351107] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 12, len 0
[ 845.351110] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.352105] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 13, len 0
[ 845.352108] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.353112] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 14, len 0
[ 845.353115] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.354107] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 15, len 0
[ 845.354110] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.354150] cdc_acm 4-1:1.0: acm_tty_cleanup
[ 845.354345] tty ttyACM0: acm_tty_install
[ 845.354354] tty ttyACM0: acm_tty_open
[ 845.354356] cdc_acm 4-1:1.0: acm_port_activate
[ 845.354367] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 0
[ 845.354370] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 1
[ 845.354372] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 2
[ 845.354374] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 3
[ 845.354377] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 4
[ 845.354380] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 5
[ 845.354383] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 6
[ 845.354385] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 7
[ 845.354388] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 8
[ 845.354391] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 9
[ 845.354394] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 10
[ 845.354397] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 11
[ 845.354400] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 12
[ 845.354402] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 13
[ 845.354405] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 14
[ 845.354408] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 15
[ 845.355118] cdc_acm 4-1:1.0: acm_ctrl_msg - rq 0x22, val 0x3, len 0x0,
result 0
[ 845.356116] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 0, len 1
[ 845.356124] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 0
[ 845.356165] cdc_acm 4-1:1.1: acm_tty_write - count 3
[ 845.356169] cdc_acm 4-1:1.1: acm_tty_write - write 3
[ 845.357118] cdc_acm 4-1:1.1: acm_softint
[ 845.358109] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 1, len 12
[ 845.358118] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 1
[ 845.358147] cdc_acm 4-1:1.0: acm_tty_close
[ 845.359116] cdc_acm 4-1:1.0: acm_ctrl_msg - rq 0x22, val 0x0, len 0x0,
result 0
[ 845.359120] cdc_acm 4-1:1.0: acm_port_shutdown
[ 845.360116] cdc_acm 4-1:1.0: acm_ctrl_irq - urb shutting down with
status: -2
[ 845.361115] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 0, len 0
[ 845.361118] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.362120] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 1, len 0
[ 845.362123] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.363117] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 2, len 0
[ 845.363120] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.364120] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 3, len 0
[ 845.364123] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.365125] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 4, len 0
[ 845.365129] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.366122] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 5, len 0
[ 845.366126] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.367120] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 6, len 0
[ 845.367125] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.368121] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 7, len 0
[ 845.368126] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.369126] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 8, len 0
[ 845.369129] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.370156] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 9, len 0
[ 845.370160] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.371125] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 10, len 0
[ 845.371128] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.372126] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 11, len 0
[ 845.372129] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.373126] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 12, len 0
[ 845.373129] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.374318] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 13, len 0
[ 845.374411] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.374694] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 14, len 0
[ 845.374772] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.375281] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 15, len 0
[ 845.375353] cdc_acm 4-1:1.1: acm_read_bulk_callback - non-zero urb
status: -2
[ 845.375452] cdc_acm 4-1:1.0: acm_tty_cleanup
[ 845.375543] tty ttyACM0: acm_tty_install
[ 845.375552] tty ttyACM0: acm_tty_open
[ 845.375555] cdc_acm 4-1:1.0: acm_port_activate
[ 845.375563] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 0
[ 845.375566] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 1
[ 845.375568] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 2
[ 845.375570] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 3
[ 845.375572] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 4
[ 845.375574] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 5
[ 845.375577] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 6
[ 845.375578] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 7
[ 845.375580] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 8
[ 845.375583] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 9
[ 845.375585] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 10
[ 845.375587] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 11
[ 845.375589] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 12
[ 845.375591] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 13
[ 845.375594] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 14
[ 845.375596] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 15
[ 845.376296] cdc_acm 4-1:1.0: acm_ctrl_msg - rq 0x22, val 0x3, len 0x0,
result 0
[ 845.377286] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 0, len 1
[ 845.377433] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 0
[ 845.378076] cdc_acm 4-1:1.1: acm_tty_write - count 3
[ 845.378079] cdc_acm 4-1:1.1: acm_tty_write - write 3
[ 845.379496] cdc_acm 4-1:1.1: acm_softint
[ 845.380542] cdc_acm 4-1:1.1: acm_read_bulk_callback - urb 1, len 12
[ 845.380757] cdc_acm 4-1:1.1: acm_submit_read_urb - urb 1
[ 845.381274] cdc_acm 4-1:1.0: acm_tty_close
[ 845.382305] cdc_acm 4-1:1.0: acm_ctrl_msg - rq 0x22, val 0x0, len 0x0,
result 0
[ 845.382312] cdc_acm 4-1:1.0: acm_port_shutdown
[ 845.383323] cdc_acm 4-1:1.0: acm_ctrl_irq - urb shutting down with
status: -2
[ 858.944068] hrtimer: interrupt took 10038 ns
[ 918.021136] show_signal_msg: 255 callbacks suppressed
[ 1082.757928] INFO: task clconfig:20008 blocked for more than 120 seconds.
[ 1082.763444] Tainted: P O 4.1.15 #8
[ 1082.767335] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 1082.774031] clconfig D ffff8826ab9afcc8 0 20008 493
0x00000000
[ 1082.774039] ffff8826ab9afcc8 ffff8826ab9afc98 ffff8827208d2410
0000000000000286
[ 1082.774042] ffff8826ab9b0000 ffff880807487448 ffff880807487018
ffff88202444fe20
[ 1082.774045] ffff88182447c3d8 ffff8826ab9afce8 ffffffff81668ff2
ffff880807487448
[ 1082.774050] Call Trace:
[ 1082.774063] [<ffffffff81668ff2>] schedule+0x32/0x80
[ 1082.774067] [<ffffffff8149cb4e>] usb_kill_urb+0x8e/0xd0
[ 1082.774073] [<ffffffff8108d9a0>] ? wait_woken+0x80/0x80
[ 1082.774077] [<ffffffff814d79a0>] acm_port_shutdown+0xc0/0x100
[ 1082.774081] [<ffffffff81323a84>] tty_port_shutdown+0x74/0xa0
[ 1082.774084] [<ffffffff813244c1>] tty_port_close+0x21/0x50
[ 1082.774086] [<ffffffff814d8313>] acm_tty_close+0x33/0x70
[ 1082.774090] [<ffffffff8131a46b>] tty_release+0x12b/0x5e0
[ 1082.774095] [<ffffffff811468e7>] __fput+0x97/0x1d0
[ 1082.774097] [<ffffffff81146a69>] ____fput+0x9/0x10
[ 1082.774100] [<ffffffff81070147>] task_work_run+0xb7/0xf0
[ 1082.774104] [<ffffffff81003e05>] do_notify_resume+0x65/0x70
[ 1082.774107] [<ffffffff8166cafc>] int_signal+0x12/0x17
[ 1106.265033] nr_pdflush_threads exported in /proc is scheduled for
removal
[ 1202.895561] INFO: task clconfig:20008 blocked for more than 120 seconds.
[ 1202.901495] Tainted: P O 4.1.15 #8
[ 1202.908194] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 1202.915005] clconfig D ffff8826ab9afcc8 0 20008 493
0x00000000
[ 1202.915014] ffff8826ab9afcc8 ffff8826ab9afc98 ffff8827208d2410
0000000000000286
[ 1202.915017] ffff8826ab9b0000 ffff880807487448 ffff880807487018
ffff88202444fe20
[ 1202.915020] ffff88182447c3d8 ffff8826ab9afce8 ffffffff81668ff2
ffff880807487448
[ 1202.915023] Call Trace:
[ 1202.915037] [<ffffffff81668ff2>] schedule+0x32/0x80
[ 1202.915044] [<ffffffff8149cb4e>] usb_kill_urb+0x8e/0xd0
[ 1202.915051] [<ffffffff8108d9a0>] ? wait_woken+0x80/0x80
[ 1202.915056] [<ffffffff814d79a0>] acm_port_shutdown+0xc0/0x100
[ 1202.915060] [<ffffffff81323a84>] tty_port_shutdown+0x74/0xa0
[ 1202.915063] [<ffffffff813244c1>] tty_port_close+0x21/0x50
[ 1202.915065] [<ffffffff814d8313>] acm_tty_close+0x33/0x70
[ 1202.915070] [<ffffffff8131a46b>] tty_release+0x12b/0x5e0
[ 1202.915075] [<ffffffff811468e7>] __fput+0x97/0x1d0
[ 1202.915078] [<ffffffff81146a69>] ____fput+0x9/0x10
[ 1202.915081] [<ffffffff81070147>] task_work_run+0xb7/0xf0
[ 1202.915087] [<ffffffff81003e05>] do_notify_resume+0x65/0x70
[ 1202.915090] [<ffffffff8166cafc>] int_signal+0x12/0x17
[ 3291.429716] perf interrupt took too long (2508 > 2500), lowering
kernel.perf_event_max_sample_rate to 50000
#
This email and any attachments thereto may contain private, confidential, and/or privileged material for the sole use of the intended recipient. Any review, copying, or distribution of this email (or any attachments thereto) by others is strictly prohibited. If you are not the intended recipient, please contact the sender immediately and permanently delete the original and any copies of this email and any attachments thereto.
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
