USB Error (Errno 110) Timeout Issue, Kernel issue?

[Casey Deyle]

I have an issue with the USB timing out that I have narrowed down to something with the Beagle Bone, possible the USB drivers on the kernel?  I have tested the all the libraries, python version, etc. in an identical virtual environment on my PC, everything works fine.  90% of code works on the BeagleBone, only select parts where I am sending a specfic command through the USB does this problem occur.  I did some digging and it looks like something similar to what was happening a few years ago on of the earlier kernels. I have tried many of the older and newer kernels am currently running Kernel 4.9.36-ti-r45.  All the kernels I have tested still had this problem.  Below is the log:

Jul 11 15:33:35 beaglebone kernel: [  518.768865] WARNING: CPU: 0 PID: 2150 at drivers/usb/musb/musb_host.c:140 musb_h_tx_flush_fifo+0xf0/0xf4

Jul 11 15:33:35 beaglebone kernel: [  518.768878] musb-hdrc musb-hdrc.1: Could not flush host TX2 fifo: csr: 2003

Jul 11 15:33:35 beaglebone kernel: [  518.768886] Modules linked in: c_can_platform c_can can_dev spi_omap2_mcspi pvrsrvkm(O) omap_sham omap_aes_driver snd_soc_simple_card crypto_engine snd_soc_simple_card_utils pruss_soc_bus omap_rng rng_core snd_soc_davinci_mcasp snd_soc_omap snd_soc_edma snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm evdev snd_timer snd soundcore tps65217_charger uio_pdrv_genirq uio usb_f_mass_storage 8021q garp mrp stp llc usb_f_acm u_serial usb_f_ecm usb_f_rndis u_ether cpufreq_powersave cpufreq_userspace cpufreq_conservative libcomposite cpufreq_ondemand spidev pru_rproc pruss_intc pruss tieqep

Jul 11 15:33:35 beaglebone kernel: [  518.769179] CPU: 0 PID: 2150 Comm: python Tainted: G           O    4.9.36-ti-r45 #1

Jul 11 15:33:35 beaglebone kernel: [  518.769186] Hardware name: Generic AM33XX (Flattened Device Tree)

Jul 11 15:33:35 beaglebone kernel: [  518.769238] [<c0114738>] (unwind_backtrace) from [<c010de60>] (show_stack+0x20/0x24)

Jul 11 15:33:35 beaglebone kernel: [  518.769259] [<c010de60>] (show_stack) from [<c073aadc>] (dump_stack+0x90/0xa4)

Jul 11 15:33:35 beaglebone kernel: [  518.769281] [<c073aadc>] (dump_stack) from [<c013e2c8>] (__warn+0xf8/0x110)

Jul 11 15:33:35 beaglebone kernel: [  518.769300] [<c013e2c8>] (__warn) from [<c013e338>] (warn_slowpath_fmt+0x58/0x74)

Jul 11 15:33:35 beaglebone kernel: [  518.769318] [<c013e338>] (warn_slowpath_fmt) from [<c09e3880>] (musb_h_tx_flush_fifo+0xf0/0xf4)

Jul 11 15:33:35 beaglebone kernel: [  518.769337] [<c09e3880>] (musb_h_tx_flush_fifo) from [<c09e4940>] (musb_cleanup_urb+0x60/0xf0)

Jul 11 15:33:35 beaglebone kernel: [  518.769354] [<c09e4940>] (musb_cleanup_urb) from [<c09e5004>] (musb_urb_dequeue+0x1b4/0x1d4)

Jul 11 15:33:35 beaglebone kernel: [  518.769384] [<c09e5004>] (musb_urb_dequeue) from [<c098c950>] (unlink1+0x3c/0x124)

Jul 11 15:33:35 beaglebone kernel: [  518.769404] [<c098c950>] (unlink1) from [<c098dda4>] (usb_hcd_unlink_urb+0x64/0xdc)

Jul 11 15:33:35 beaglebone kernel: [  518.769422] [<c098dda4>] (usb_hcd_unlink_urb) from [<c098f474>] (usb_kill_urb+0x6c/0xf4)

Jul 11 15:33:35 beaglebone kernel: [  518.769451] [<c098f474>] (usb_kill_urb) from [<c099cea8>] (usbdev_ioctl+0x1460/0x1d94)

Jul 11 15:33:35 beaglebone kernel: [  518.769480] [<c099cea8>] (usbdev_ioctl) from [<c02f0158>] (do_vfs_ioctl+0xbc/0x9c8)

Jul 11 15:33:35 beaglebone kernel: [  518.769501] [<c02f0158>] (do_vfs_ioctl) from [<c02f0ae0>] (SyS_ioctl+0x7c/0x8c)

Jul 11 15:33:35 beaglebone kernel: [  518.769528] [<c02f0ae0>] (SyS_ioctl) from [<c0109100>] (ret_fast_syscall+0x0/0x3c)

Jul 11 15:33:35 beaglebone kernel: [  518.769540] ---[ end trace f94fcd49ef949ddc ]---

Let me know what other data I can post that would be relevant.

-Casey

[Stephen Agate]

I'm afraid that this is only a "me too" post. I have a USB sound card, which works fine on other systems, but produces the warning from the USB driver when it is asked to play audio (100% reproducible).

I've tried various kernels & all exhibit the fault:
    -   Vanilla 4.11.5
    -   4.9 LTS from Robert Nelson: https://github.com/beagleboard/linux/tree/4.9
    -   TI's processor-sdk-linux-04.00.00
               
I've tried turning off DMA, but that seems to make no difference.               
              
[   15.205213] ------------[ cut here ]------------
[   15.210086] WARNING: CPU: 0 PID: 314 at drivers/usb/musb/musb_host.c:140 musb_h_tx_flush_fifo+0xe0/0xe4
[   15.219905] musb-hdrc musb-hdrc.1: Could not flush host TX10 fifo: csr: 2003
[   15.227269] Modules linked in: bmp280_i2c bmp280 rtc_ds1307 at24 rtl8192cu rtl_usb rtl8192c_common rtlwifi omap_wdt uio_pdrv_genirq hid_generic rtl8xxxu mac80211 cfg80211 rfkill
[   15.243866] CPU: 0 PID: 314 Comm: MP3Player Not tainted 4.11.5 #1
[   15.250231] Hardware name: Generic AM33XX (Flattened Device Tree)
[   15.256630] [<c010df50>] (unwind_backtrace) from [<c010b334>] (show_stack+0x10/0x14)
[   15.264736] [<c010b334>] (show_stack) from [<c033305c>] (dump_stack+0x78/0x8c)
[   15.272295] [<c033305c>] (dump_stack) from [<c01263ac>] (__warn+0xe8/0x100)
[   15.279574] [<c01263ac>] (__warn) from [<c01263fc>] (warn_slowpath_fmt+0x38/0x48)
[   15.287400] [<c01263fc>] (warn_slowpath_fmt) from [<c053b398>] (musb_h_tx_flush_fifo+0xe0/0xe4)
[   15.296498] [<c053b398>] (musb_h_tx_flush_fifo) from [<c053c960>] (musb_cleanup_urb+0x138/0x204)
[   15.305682] [<c053c960>] (musb_cleanup_urb) from [<c053cb00>] (musb_urb_dequeue+0xd4/0x120)
[   15.314424] [<c053cb00>] (musb_urb_dequeue) from [<c04b6f4c>] (usb_hcd_unlink_urb+0x68/0x84)
[   15.323251] [<c04b6f4c>] (usb_hcd_unlink_urb) from [<c0620ab8>] (deactivate_urbs+0xc8/0xe4)
[   15.331984] [<c0620ab8>] (deactivate_urbs) from [<c0621ac4>] (snd_usb_endpoint_stop+0x30/0x40)
[   15.340995] [<c0621ac4>] (snd_usb_endpoint_stop) from [<c062b4ec>] (stop_endpoints+0x5c/0x74)
[   15.349911] [<c062b4ec>] (stop_endpoints) from [<c062b658>] (snd_usb_substream_playback_trigger+0xa8/0xc4)
[   15.360015] [<c062b658>] (snd_usb_substream_playback_trigger) from [<c060a648>] (snd_pcm_do_stop+0x3c/0x5c)
[   15.370201] [<c060a648>] (snd_pcm_do_stop) from [<c0608edc>] (snd_pcm_action_single+0x38/0x78)
[   15.379206] [<c0608edc>] (snd_pcm_action_single) from [<c060aef8>] (snd_pcm_drop+0x6c/0xc4)
[   15.387938] [<c060aef8>] (snd_pcm_drop) from [<c060b514>] (snd_pcm_release_substream.part.10+0xc/0xa8)
[   15.397668] [<c060b514>] (snd_pcm_release_substream.part.10) from [<c060b650>] (snd_pcm_release+0x88/0x94)
[   15.407770] [<c060b650>] (snd_pcm_release) from [<c0200e8c>] (__fput+0x84/0x1cc)
[   15.415510] [<c0200e8c>] (__fput) from [<c013fcd4>] (task_work_run+0xb8/0xd8)
[   15.422972] [<c013fcd4>] (task_work_run) from [<c010ae9c>] (do_work_pending+0x8c/0xb4)
[   15.431251] [<c010ae9c>] (do_work_pending) from [<c0107834>] (slow_work_pending+0xc/0x20)
[   15.439797] ---[ end trace bef2c01b51321ace ]---