I have a D-Link DWA-140 USB Wi-Fi device which is rt2800 based (5392 chipset). I've been testing it on a BeagleBone Black running an Ubuntu 16.04 image (4.4.6 kernel), with a USB hub.
When I unplug the Wi-Fi device from the USB hub, and it's connected to an access point, and then I unplug it, the OS appears to lock up. I get messages about a soft lockup on the serial console:
[ 9736.136702] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9764.136701] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9792.136701] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9820.136699] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9848.136696] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
This will repeat indefinitely, until I unplug the hub, which resolves the soft lockup and then the system seems to function normally.
I've attached a dmesg log of the soft lockup stack traces. They seem to indicate a lockup in workqueue rt2x00usb_work_rxdone() (specifically in usb_hcd_submit_urb() called from rt2x00usb_kick_rx_entry() called from rt2x00usb_clear_entry()).
I originally found this bug on a 3.14.x kernel built with Yocto for a BeagleBone Black-based product. So it seems this is a bug that has been around for some time.
--
Craig McQueen
[ 9736.136702] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9764.136701] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9792.136701] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9820.136699] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9848.136696] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9852.998151] cpts: unable to obtain a time stamp
craigm@beaglebone-craig:~$ dmesg
...
[ 9366.664797] usb 1-1: new high-speed USB device number 4 using musb-hdrc
[ 9366.796197] usb 1-1: New USB device found, idVendor=2109, idProduct=2812
[ 9366.796248] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 9366.796279] usb 1-1: Product: USB2.0 Hub
[ 9366.796308] usb 1-1: Manufacturer: VIA Labs, Inc.
[ 9366.809649] hub 1-1:1.0: USB hub found
[ 9366.813937] hub 1-1:1.0: 4 ports detected
[ 9371.836876] usb 1-1.1: new high-speed USB device number 5 using musb-hdrc
[ 9371.953209] usb 1-1.1: New USB device found, idVendor=2001, idProduct=3c20
[ 9371.953259] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 9371.953291] usb 1-1.1: Product: 802.11 n WLAN
[ 9371.953319] usb 1-1.1: Manufacturer: Ralink
[ 9371.953348] usb 1-1.1: SerialNumber: 1.0
[ 9373.264832] usb 1-1.1: reset high-speed USB device number 5 using musb-hdrc
[ 9373.374188] ieee80211 phy1: rt2x00_set_rt: Info - RT chipset 5392, rev 0223 detected
[ 9373.391020] ieee80211 phy1: rt2x00_set_rf: Info - RF chipset 5372 detected
[ 9373.402442] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[ 9373.427413] usbcore: registered new interface driver rt2800usb
[ 9373.572855] rt2800usb 1-1.1:1.0 wlx9cd64384611d: renamed from wlan0
[ 9373.920791] ieee80211 phy1: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin'
[ 9373.922515] ieee80211 phy1: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.29
[ 9374.076725] IPv6: ADDRCONF(NETDEV_UP): wlx9cd64384611d: link is not ready
[ 9382.591520] wlx9cd64384611d: authenticate with e8:94:f6:5a:95:9a
[ 9382.629936] wlx9cd64384611d: send auth to e8:94:f6:5a:95:9a (try 1/3)
[ 9382.631970] wlx9cd64384611d: authenticated
[ 9382.644913] wlx9cd64384611d: associate with e8:94:f6:5a:95:9a (try 1/3)
[ 9382.647039] wlx9cd64384611d: RX AssocResp from e8:94:f6:5a:95:9a (capab=0x411 status=0 aid=1)
[ 9382.656390] wlx9cd64384611d: associated
[ 9382.656516] IPv6: ADDRCONF(NETDEV_CHANGE): wlx9cd64384611d: link becomes ready
[ 9708.136709] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9708.144990] Modules linked in: rt2800usb rt2800lib rt2x00usb rt2x00lib crc_ccitt ccm arc4 rtl8192cu rtl_usb rtl8192c_common rtlwifi mac80211 cfg80211 rfkill snd_soc_simple_card omap_sham omap_aes usb_f_ecm g_ether usb_f_rndis u_ether libcomposite omap_rng rng_core snd_soc_davinci_mcasp snd_soc_edma snd_soc_omap spi_omap2_mcspi snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore evdev uio_pdrv_genirq uio tilcdc tda998x
[ 9708.145353] CPU: 0 PID: 1129 Comm: kworker/u2:0 Not tainted 4.4.6-ti-r15 #1
[ 9708.145379] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9708.145477] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9708.145508] task: ddbb5b00 ti: ddf48000 task.ti: ddf48000
[ 9708.145556] PC is at v7_dma_inv_range+0x36/0x40
[ 9708.145585] LR is at __dma_page_cpu_to_dev+0x21/0x74
[ 9708.145614] pc : [<c001c2ac>] lr : [<c001913d>] psr: 800e0033
sp : ddf49d10 ip : ddede04c fp : c0c29e18
[ 9708.145644] r10: 00000000 r9 : 00000f14 r8 : 00000002
[ 9708.145668] r7 : 00000002 r6 : 00000f14 r5 : 0000004c r4 : dfda2738
[ 9708.145692] r3 : 0000003f r2 : 00000040 r1 : ddedef40 r0 : ddededc0
[ 9708.145720] Flags: Nzcv IRQs on FIQs on Mode SVC_32 ISA Thumb Segment none
[ 9708.145746] Control: 50c5387d Table: 9c184019 DAC: 00000051
[ 9708.145775] CPU: 0 PID: 1129 Comm: kworker/u2:0 Not tainted 4.4.6-ti-r15 #1
[ 9708.145796] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9708.145847] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9708.145922] [<c0015b5d>] (unwind_backtrace) from [<c001239d>] (show_stack+0x11/0x14)
[ 9708.145976] [<c001239d>] (show_stack) from [<c03c460b>] (dump_stack+0x67/0x74)
[ 9708.146024] [<c03c460b>] (dump_stack) from [<c00bb3f5>] (watchdog_timer_fn+0x18d/0x1dc)
[ 9708.146075] [<c00bb3f5>] (watchdog_timer_fn) from [<c0085aab>] (__hrtimer_run_queues+0x133/0x288)
[ 9708.146119] [<c0085aab>] (__hrtimer_run_queues) from [<c008629b>] (hrtimer_interrupt+0x9f/0x1ac)
[ 9708.146165] [<c008629b>] (hrtimer_interrupt) from [<c0021d13>] (omap2_gp_timer_interrupt+0x23/0x28)
[ 9708.146219] [<c0021d13>] (omap2_gp_timer_interrupt) from [<c0077203>] (handle_irq_event_percpu+0x67/0x1b4)
[ 9708.146263] [<c0077203>] (handle_irq_event_percpu) from [<c0077389>] (handle_irq_event+0x39/0x50)
[ 9708.146311] [<c0077389>] (handle_irq_event) from [<c00797e9>] (handle_level_irq+0x6d/0xc4)
[ 9708.146354] [<c00797e9>] (handle_level_irq) from [<c0076b19>] (generic_handle_irq+0x1d/0x28)
[ 9708.146398] [<c0076b19>] (generic_handle_irq) from [<c0076d11>] (__handle_domain_irq+0x45/0x84)
[ 9708.146446] [<c0076d11>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9708.146494] [<c06e2b8d>] (__irq_svc) from [<c001c2ac>] (v7_dma_inv_range+0x36/0x40)
[ 9708.146535] [<c001c2ac>] (v7_dma_inv_range) from [<c001913d>] (__dma_page_cpu_to_dev+0x21/0x74)
[ 9708.146573] [<c001913d>] (__dma_page_cpu_to_dev) from [<c001921f>] (arm_dma_map_page+0x57/0x58)
[ 9708.146621] [<c001921f>] (arm_dma_map_page) from [<c04fd5ad>] (usb_hcd_map_urb_for_dma+0x361/0x3c4)
[ 9708.146666] [<c04fd5ad>] (usb_hcd_map_urb_for_dma) from [<c04fda1b>] (usb_hcd_submit_urb+0x23f/0x6f4)
[ 9708.146731] [<c04fda1b>] (usb_hcd_submit_urb) from [<bfadb44b>] (rt2x00usb_kick_rx_entry+0x82/0xac [rt2x00usb])
[ 9708.146855] [<bfadb44b>] (rt2x00usb_kick_rx_entry [rt2x00usb]) from [<bfadb48f>] (rt2x00usb_clear_entry+0x1a/0x1c [rt2x00usb])
[ 9708.147053] [<bfadb48f>] (rt2x00usb_clear_entry [rt2x00usb]) from [<bfac95db>] (rt2x00lib_rxdone+0xe6/0x400 [rt2x00lib])
[ 9708.147197] [<bfac95db>] (rt2x00lib_rxdone [rt2x00lib]) from [<bfadba77>] (rt2x00usb_work_rxdone+0x46/0x6c [rt2x00usb])
[ 9708.147276] [<bfadba77>] (rt2x00usb_work_rxdone [rt2x00usb]) from [<c0043bdb>] (process_one_work+0xef/0x334)
[ 9708.147324] [<c0043bdb>] (process_one_work) from [<c0043f05>] (worker_thread+0xe5/0x380)
[ 9708.147370] [<c0043f05>] (worker_thread) from [<c0047fab>] (kthread+0xab/0xc0)
[ 9708.147420] [<c0047fab>] (kthread) from [<c000ecb5>] (ret_from_fork+0x11/0x1c)
[ 9736.136702] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9736.144972] Modules linked in: rt2800usb rt2800lib rt2x00usb rt2x00lib crc_ccitt ccm arc4 rtl8192cu rtl_usb rtl8192c_common rtlwifi mac80211 cfg80211 rfkill snd_soc_simple_card omap_sham omap_aes usb_f_ecm g_ether usb_f_rndis u_ether libcomposite omap_rng rng_core snd_soc_davinci_mcasp snd_soc_edma snd_soc_omap spi_omap2_mcspi snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore evdev uio_pdrv_genirq uio tilcdc tda998x
[ 9736.145329] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9736.145354] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9736.145445] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9736.145477] task: ddbb5b00 ti: ddf48000 task.ti: ddf48000
[ 9736.145520] PC is at usb_hcd_map_urb_for_dma+0x31a/0x3c4
[ 9736.145548] LR is at usb_hcd_submit_urb+0x23f/0x6f4
[ 9736.145576] pc : [<c04fd566>] lr : [<c04fda1b>] psr: 600e0033
sp : ddf49d48 ip : ddea004c fp : c0c29e18
[ 9736.145606] r10: 00000000 r9 : 00000f14 r8 : 00000002
[ 9736.145630] r7 : 00000000 r6 : 02080020 r5 : dcdec800 r4 : dc150400
[ 9736.145654] r3 : 00000200 r2 : ddea004c r1 : 00000000 r0 : dcdec610
[ 9736.145682] Flags: nZCv IRQs on FIQs on Mode SVC_32 ISA Thumb Segment none
[ 9736.145708] Control: 50c5387d Table: 9c184019 DAC: 00000051
[ 9736.145738] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9736.145760] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9736.145812] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9736.145880] [<c0015b5d>] (unwind_backtrace) from [<c001239d>] (show_stack+0x11/0x14)
[ 9736.145929] [<c001239d>] (show_stack) from [<c03c460b>] (dump_stack+0x67/0x74)
[ 9736.145976] [<c03c460b>] (dump_stack) from [<c00bb3f5>] (watchdog_timer_fn+0x18d/0x1dc)
[ 9736.146024] [<c00bb3f5>] (watchdog_timer_fn) from [<c0085aab>] (__hrtimer_run_queues+0x133/0x288)
[ 9736.146068] [<c0085aab>] (__hrtimer_run_queues) from [<c008629b>] (hrtimer_interrupt+0x9f/0x1ac)
[ 9736.146112] [<c008629b>] (hrtimer_interrupt) from [<c0021d13>] (omap2_gp_timer_interrupt+0x23/0x28)
[ 9736.146165] [<c0021d13>] (omap2_gp_timer_interrupt) from [<c0077203>] (handle_irq_event_percpu+0x67/0x1b4)
[ 9736.146209] [<c0077203>] (handle_irq_event_percpu) from [<c0077389>] (handle_irq_event+0x39/0x50)
[ 9736.146256] [<c0077389>] (handle_irq_event) from [<c00797e9>] (handle_level_irq+0x6d/0xc4)
[ 9736.146299] [<c00797e9>] (handle_level_irq) from [<c0076b19>] (generic_handle_irq+0x1d/0x28)
[ 9736.146341] [<c0076b19>] (generic_handle_irq) from [<c0076d11>] (__handle_domain_irq+0x45/0x84)
[ 9736.146386] [<c0076d11>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9736.146430] [<c06e2b8d>] (__irq_svc) from [<c04fd566>] (usb_hcd_map_urb_for_dma+0x31a/0x3c4)
[ 9736.146472] [<c04fd566>] (usb_hcd_map_urb_for_dma) from [<c04fda1b>] (usb_hcd_submit_urb+0x23f/0x6f4)
[ 9736.146535] [<c04fda1b>] (usb_hcd_submit_urb) from [<bfadb44b>] (rt2x00usb_kick_rx_entry+0x82/0xac [rt2x00usb])
[ 9736.146620] [<bfadb44b>] (rt2x00usb_kick_rx_entry [rt2x00usb]) from [<bfadb48f>] (rt2x00usb_clear_entry+0x1a/0x1c [rt2x00usb])
[ 9736.146793] [<bfadb48f>] (rt2x00usb_clear_entry [rt2x00usb]) from [<bfac95db>] (rt2x00lib_rxdone+0xe6/0x400 [rt2x00lib])
[ 9736.146919] [<bfac95db>] (rt2x00lib_rxdone [rt2x00lib]) from [<bfadba77>] (rt2x00usb_work_rxdone+0x46/0x6c [rt2x00usb])
[ 9736.146995] [<bfadba77>] (rt2x00usb_work_rxdone [rt2x00usb]) from [<c0043bdb>] (process_one_work+0xef/0x334)
[ 9736.147043] [<c0043bdb>] (process_one_work) from [<c0043f05>] (worker_thread+0xe5/0x380)
[ 9736.147086] [<c0043f05>] (worker_thread) from [<c0047fab>] (kthread+0xab/0xc0)
[ 9736.147135] [<c0047fab>] (kthread) from [<c000ecb5>] (ret_from_fork+0x11/0x1c)
[ 9764.136701] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9764.144964] Modules linked in: rt2800usb rt2800lib rt2x00usb rt2x00lib crc_ccitt ccm arc4 rtl8192cu rtl_usb rtl8192c_common rtlwifi mac80211 cfg80211 rfkill snd_soc_simple_card omap_sham omap_aes usb_f_ecm g_ether usb_f_rndis u_ether libcomposite omap_rng rng_core snd_soc_davinci_mcasp snd_soc_edma snd_soc_omap spi_omap2_mcspi snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore evdev uio_pdrv_genirq uio tilcdc tda998x
[ 9764.145315] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9764.145340] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9764.145430] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9764.145462] task: ddbb5b00 ti: ddf48000 task.ti: ddf48000
[ 9764.145504] PC is at usb_unanchor_urb+0x2/0x38
[ 9764.145534] LR is at __usb_hcd_giveback_urb+0x35/0xdc
[ 9764.145562] pc : [<c04ff096>] lr : [<c04fc1bd>] psr: 800e0133
sp : ddf49bc0 ip : 00000000 fp : dc0e1380
[ 9764.145592] r10: c0b5ba88 r9 : dcdec928 r8 : dcdec92c
[ 9764.145615] r7 : 00000000 r6 : dcdec800 r5 : ffffffb9 r4 : dc0e1380
[ 9764.145639] r3 : 00000000 r2 : 00000200 r1 : dc820f40 r0 : dc0e1380
[ 9764.145666] Flags: Nzcv IRQs on FIQs on Mode SVC_32 ISA Thumb Segment none
[ 9764.145692] Control: 50c5387d Table: 9c184019 DAC: 00000051
[ 9764.145722] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9764.145743] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9764.145795] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9764.145865] [<c0015b5d>] (unwind_backtrace) from [<c001239d>] (show_stack+0x11/0x14)
[ 9764.145913] [<c001239d>] (show_stack) from [<c03c460b>] (dump_stack+0x67/0x74)
[ 9764.145961] [<c03c460b>] (dump_stack) from [<c00bb3f5>] (watchdog_timer_fn+0x18d/0x1dc)
[ 9764.146010] [<c00bb3f5>] (watchdog_timer_fn) from [<c0085aab>] (__hrtimer_run_queues+0x133/0x288)
[ 9764.146054] [<c0085aab>] (__hrtimer_run_queues) from [<c008629b>] (hrtimer_interrupt+0x9f/0x1ac)
[ 9764.146099] [<c008629b>] (hrtimer_interrupt) from [<c0021d13>] (omap2_gp_timer_interrupt+0x23/0x28)
[ 9764.146149] [<c0021d13>] (omap2_gp_timer_interrupt) from [<c0077203>] (handle_irq_event_percpu+0x67/0x1b4)
[ 9764.146194] [<c0077203>] (handle_irq_event_percpu) from [<c0077389>] (handle_irq_event+0x39/0x50)
[ 9764.146239] [<c0077389>] (handle_irq_event) from [<c00797e9>] (handle_level_irq+0x6d/0xc4)
[ 9764.146282] [<c00797e9>] (handle_level_irq) from [<c0076b19>] (generic_handle_irq+0x1d/0x28)
[ 9764.146325] [<c0076b19>] (generic_handle_irq) from [<c0076d11>] (__handle_domain_irq+0x45/0x84)
[ 9764.146371] [<c0076d11>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9764.146416] [<c06e2b8d>] (__irq_svc) from [<c04ff096>] (usb_unanchor_urb+0x2/0x38)
[ 9764.146459] [<c04ff096>] (usb_unanchor_urb) from [<c04fc1bd>] (__usb_hcd_giveback_urb+0x35/0xdc)
[ 9764.146500] [<c04fc1bd>] (__usb_hcd_giveback_urb) from [<c04fc2dd>] (usb_giveback_urb_bh+0x79/0xb8)
[ 9764.146544] [<c04fc2dd>] (usb_giveback_urb_bh) from [<c0034acf>] (__tasklet_action+0x5b/0xe8)
[ 9764.146585] [<c0034acf>] (__tasklet_action) from [<c0034c85>] (__do_softirq+0x85/0x234)
[ 9764.146624] [<c0034c85>] (__do_softirq) from [<c0035333>] (irq_exit+0x7f/0xc4)
[ 9764.146663] [<c0035333>] (irq_exit) from [<c0076d15>] (__handle_domain_irq+0x49/0x84)
[ 9764.146703] [<c0076d15>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9764.146753] [<c06e2b8d>] (__irq_svc) from [<c001c2aa>] (v7_dma_inv_range+0x34/0x40)
[ 9764.146794] [<c001c2aa>] (v7_dma_inv_range) from [<c001913d>] (__dma_page_cpu_to_dev+0x21/0x74)
[ 9764.146833] [<c001913d>] (__dma_page_cpu_to_dev) from [<c001921f>] (arm_dma_map_page+0x57/0x58)
[ 9764.146873] [<c001921f>] (arm_dma_map_page) from [<c04fd5ad>] (usb_hcd_map_urb_for_dma+0x361/0x3c4)
[ 9764.146915] [<c04fd5ad>] (usb_hcd_map_urb_for_dma) from [<c04fda1b>] (usb_hcd_submit_urb+0x23f/0x6f4)
[ 9764.146980] [<c04fda1b>] (usb_hcd_submit_urb) from [<bfadb44b>] (rt2x00usb_kick_rx_entry+0x82/0xac [rt2x00usb])
[ 9764.147063] [<bfadb44b>] (rt2x00usb_kick_rx_entry [rt2x00usb]) from [<bfadb48f>] (rt2x00usb_clear_entry+0x1a/0x1c [rt2x00usb])
[ 9764.147237] [<bfadb48f>] (rt2x00usb_clear_entry [rt2x00usb]) from [<bfac95db>] (rt2x00lib_rxdone+0xe6/0x400 [rt2x00lib])
[ 9764.147364] [<bfac95db>] (rt2x00lib_rxdone [rt2x00lib]) from [<bfadba77>] (rt2x00usb_work_rxdone+0x46/0x6c [rt2x00usb])
[ 9764.147441] [<bfadba77>] (rt2x00usb_work_rxdone [rt2x00usb]) from [<c0043bdb>] (process_one_work+0xef/0x334)
[ 9764.147488] [<c0043bdb>] (process_one_work) from [<c0043f05>] (worker_thread+0xe5/0x380)
[ 9764.147531] [<c0043f05>] (worker_thread) from [<c0047fab>] (kthread+0xab/0xc0)
[ 9764.147581] [<c0047fab>] (kthread) from [<c000ecb5>] (ret_from_fork+0x11/0x1c)
[ 9792.136701] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9792.144959] Modules linked in: rt2800usb rt2800lib rt2x00usb rt2x00lib crc_ccitt ccm arc4 rtl8192cu rtl_usb rtl8192c_common rtlwifi mac80211 cfg80211 rfkill snd_soc_simple_card omap_sham omap_aes usb_f_ecm g_ether usb_f_rndis u_ether libcomposite omap_rng rng_core snd_soc_davinci_mcasp snd_soc_edma snd_soc_omap spi_omap2_mcspi snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore evdev uio_pdrv_genirq uio tilcdc tda998x
[ 9792.145317] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9792.145340] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9792.145431] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9792.145461] task: ddbb5b00 ti: ddf48000 task.ti: ddf48000
[ 9792.145501] PC is at __usb_hcd_giveback_urb+0x4e/0xdc
[ 9792.145539] LR is at queue_work_on+0x1d/0x38
[ 9792.145566] pc : [<c04fc1d6>] lr : [<c00420a9>] psr: 600e0133
sp : ddf49bd8 ip : 00000000 fp : dcfc9200
[ 9792.145596] r10: c0b5ba88 r9 : dcdec928 r8 : dcdec92c
[ 9792.145619] r7 : 00000000 r6 : dcdec800 r5 : 800e0113 r4 : dcfc9200
[ 9792.145643] r3 : 00000010 r2 : dcca1907 r1 : ddf27384 r0 : 00000000
[ 9792.145670] Flags: nZCv IRQs on FIQs on Mode SVC_32 ISA Thumb Segment none
[ 9792.145695] Control: 50c5387d Table: 9c184019 DAC: 00000051
[ 9792.145725] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9792.145746] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9792.145797] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9792.145866] [<c0015b5d>] (unwind_backtrace) from [<c001239d>] (show_stack+0x11/0x14)
[ 9792.145914] [<c001239d>] (show_stack) from [<c03c460b>] (dump_stack+0x67/0x74)
[ 9792.145961] [<c03c460b>] (dump_stack) from [<c00bb3f5>] (watchdog_timer_fn+0x18d/0x1dc)
[ 9792.146009] [<c00bb3f5>] (watchdog_timer_fn) from [<c0085aab>] (__hrtimer_run_queues+0x133/0x288)
[ 9792.146053] [<c0085aab>] (__hrtimer_run_queues) from [<c008629b>] (hrtimer_interrupt+0x9f/0x1ac)
[ 9792.146098] [<c008629b>] (hrtimer_interrupt) from [<c0021d13>] (omap2_gp_timer_interrupt+0x23/0x28)
[ 9792.146150] [<c0021d13>] (omap2_gp_timer_interrupt) from [<c0077203>] (handle_irq_event_percpu+0x67/0x1b4)
[ 9792.146195] [<c0077203>] (handle_irq_event_percpu) from [<c0077389>] (handle_irq_event+0x39/0x50)
[ 9792.146241] [<c0077389>] (handle_irq_event) from [<c00797e9>] (handle_level_irq+0x6d/0xc4)
[ 9792.146284] [<c00797e9>] (handle_level_irq) from [<c0076b19>] (generic_handle_irq+0x1d/0x28)
[ 9792.146326] [<c0076b19>] (generic_handle_irq) from [<c0076d11>] (__handle_domain_irq+0x45/0x84)
[ 9792.146372] [<c0076d11>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9792.146415] [<c06e2b8d>] (__irq_svc) from [<c04fc1d6>] (__usb_hcd_giveback_urb+0x4e/0xdc)
[ 9792.146457] [<c04fc1d6>] (__usb_hcd_giveback_urb) from [<c04fc2dd>] (usb_giveback_urb_bh+0x79/0xb8)
[ 9792.146500] [<c04fc2dd>] (usb_giveback_urb_bh) from [<c0034acf>] (__tasklet_action+0x5b/0xe8)
[ 9792.146540] [<c0034acf>] (__tasklet_action) from [<c0034c85>] (__do_softirq+0x85/0x234)
[ 9792.146579] [<c0034c85>] (__do_softirq) from [<c0035333>] (irq_exit+0x7f/0xc4)
[ 9792.146618] [<c0035333>] (irq_exit) from [<c0076d15>] (__handle_domain_irq+0x49/0x84)
[ 9792.146657] [<c0076d15>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9792.146705] [<c06e2b8d>] (__irq_svc) from [<c001c2ac>] (v7_dma_inv_range+0x36/0x40)
[ 9792.146746] [<c001c2ac>] (v7_dma_inv_range) from [<c001913d>] (__dma_page_cpu_to_dev+0x21/0x74)
[ 9792.146785] [<c001913d>] (__dma_page_cpu_to_dev) from [<c001921f>] (arm_dma_map_page+0x57/0x58)
[ 9792.146826] [<c001921f>] (arm_dma_map_page) from [<c04fd5ad>] (usb_hcd_map_urb_for_dma+0x361/0x3c4)
[ 9792.146868] [<c04fd5ad>] (usb_hcd_map_urb_for_dma) from [<c04fda1b>] (usb_hcd_submit_urb+0x23f/0x6f4)
[ 9792.146932] [<c04fda1b>] (usb_hcd_submit_urb) from [<bfadb44b>] (rt2x00usb_kick_rx_entry+0x82/0xac [rt2x00usb])
[ 9792.147017] [<bfadb44b>] (rt2x00usb_kick_rx_entry [rt2x00usb]) from [<bfadb48f>] (rt2x00usb_clear_entry+0x1a/0x1c [rt2x00usb])
[ 9792.147195] [<bfadb48f>] (rt2x00usb_clear_entry [rt2x00usb]) from [<bfac95db>] (rt2x00lib_rxdone+0xe6/0x400 [rt2x00lib])
[ 9792.147322] [<bfac95db>] (rt2x00lib_rxdone [rt2x00lib]) from [<bfadba77>] (rt2x00usb_work_rxdone+0x46/0x6c [rt2x00usb])
[ 9792.147390] [<bfadba77>] (rt2x00usb_work_rxdone [rt2x00usb]) from [<c0043bdb>] (process_one_work+0xef/0x334)
[ 9792.147437] [<c0043bdb>] (process_one_work) from [<c0043f05>] (worker_thread+0xe5/0x380)
[ 9792.147481] [<c0043f05>] (worker_thread) from [<c0047fab>] (kthread+0xab/0xc0)
[ 9792.147529] [<c0047fab>] (kthread) from [<c000ecb5>] (ret_from_fork+0x11/0x1c)
[ 9820.136699] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9820.144961] Modules linked in: rt2800usb rt2800lib rt2x00usb rt2x00lib crc_ccitt ccm arc4 rtl8192cu rtl_usb rtl8192c_common rtlwifi mac80211 cfg80211 rfkill snd_soc_simple_card omap_sham omap_aes usb_f_ecm g_ether usb_f_rndis u_ether libcomposite omap_rng rng_core snd_soc_davinci_mcasp snd_soc_edma snd_soc_omap spi_omap2_mcspi snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore evdev uio_pdrv_genirq uio tilcdc tda998x
[ 9820.145314] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9820.145338] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9820.145428] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9820.145459] task: ddbb5b00 ti: ddf48000 task.ti: ddf48000
[ 9820.145503] PC is at v7_dma_inv_range+0x34/0x40
[ 9820.145531] LR is at __dma_page_dev_to_cpu+0x67/0xe0
[ 9820.145559] pc : [<c001c2aa>] lr : [<c0019287>] psr: 800e0133
sp : ddf49ba8 ip : 00000000 fp : ddf3e900
[ 9820.145589] r10: c0b5ba88 r9 : dcdec928 r8 : 0000004c
[ 9820.145612] r7 : 00000f14 r6 : 00000000 r5 : 00000002 r4 : dfd9e9e8
[ 9820.145636] r3 : 0000003f r2 : 00000040 r1 : ddd2af40 r0 : ddd2aa40
[ 9820.145664] Flags: Nzcv IRQs on FIQs on Mode SVC_32 ISA Thumb Segment none
[ 9820.145689] Control: 50c5387d Table: 9c184019 DAC: 00000051
[ 9820.145719] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9820.145740] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9820.145793] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9820.145858] [<c0015b5d>] (unwind_backtrace) from [<c001239d>] (show_stack+0x11/0x14)
[ 9820.145906] [<c001239d>] (show_stack) from [<c03c460b>] (dump_stack+0x67/0x74)
[ 9820.145953] [<c03c460b>] (dump_stack) from [<c00bb3f5>] (watchdog_timer_fn+0x18d/0x1dc)
[ 9820.146001] [<c00bb3f5>] (watchdog_timer_fn) from [<c0085aab>] (__hrtimer_run_queues+0x133/0x288)
[ 9820.146046] [<c0085aab>] (__hrtimer_run_queues) from [<c008629b>] (hrtimer_interrupt+0x9f/0x1ac)
[ 9820.146090] [<c008629b>] (hrtimer_interrupt) from [<c0021d13>] (omap2_gp_timer_interrupt+0x23/0x28)
[ 9820.146142] [<c0021d13>] (omap2_gp_timer_interrupt) from [<c0077203>] (handle_irq_event_percpu+0x67/0x1b4)
[ 9820.146186] [<c0077203>] (handle_irq_event_percpu) from [<c0077389>] (handle_irq_event+0x39/0x50)
[ 9820.146232] [<c0077389>] (handle_irq_event) from [<c00797e9>] (handle_level_irq+0x6d/0xc4)
[ 9820.146274] [<c00797e9>] (handle_level_irq) from [<c0076b19>] (generic_handle_irq+0x1d/0x28)
[ 9820.146316] [<c0076b19>] (generic_handle_irq) from [<c0076d11>] (__handle_domain_irq+0x45/0x84)
[ 9820.146360] [<c0076d11>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9820.146405] [<c06e2b8d>] (__irq_svc) from [<c001c2aa>] (v7_dma_inv_range+0x34/0x40)
[ 9820.146445] [<c001c2aa>] (v7_dma_inv_range) from [<c0019287>] (__dma_page_dev_to_cpu+0x67/0xe0)
[ 9820.146490] [<c0019287>] (__dma_page_dev_to_cpu) from [<c04fc0f5>] (usb_hcd_unmap_urb_for_dma+0x81/0xdc)
[ 9820.146532] [<c04fc0f5>] (usb_hcd_unmap_urb_for_dma) from [<c04fc1ad>] (__usb_hcd_giveback_urb+0x25/0xdc)
[ 9820.146572] [<c04fc1ad>] (__usb_hcd_giveback_urb) from [<c04fc2dd>] (usb_giveback_urb_bh+0x79/0xb8)
[ 9820.146616] [<c04fc2dd>] (usb_giveback_urb_bh) from [<c0034acf>] (__tasklet_action+0x5b/0xe8)
[ 9820.146656] [<c0034acf>] (__tasklet_action) from [<c0034c85>] (__do_softirq+0x85/0x234)
[ 9820.146695] [<c0034c85>] (__do_softirq) from [<c0035333>] (irq_exit+0x7f/0xc4)
[ 9820.146735] [<c0035333>] (irq_exit) from [<c0076d15>] (__handle_domain_irq+0x49/0x84)
[ 9820.146775] [<c0076d15>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9820.146816] [<c06e2b8d>] (__irq_svc) from [<c06e25dc>] (_raw_spin_unlock_irqrestore+0x1c/0x20)
[ 9820.146875] [<c06e25dc>] (_raw_spin_unlock_irqrestore) from [<c05366ed>] (musb_urb_enqueue+0x5d/0x42c)
[ 9820.146923] [<c05366ed>] (musb_urb_enqueue) from [<c04fd86f>] (usb_hcd_submit_urb+0x93/0x6f4)
[ 9820.146991] [<c04fd86f>] (usb_hcd_submit_urb) from [<bfadb44b>] (rt2x00usb_kick_rx_entry+0x82/0xac [rt2x00usb])
[ 9820.147075] [<bfadb44b>] (rt2x00usb_kick_rx_entry [rt2x00usb]) from [<bfadb48f>] (rt2x00usb_clear_entry+0x1a/0x1c [rt2x00usb])
[ 9820.147247] [<bfadb48f>] (rt2x00usb_clear_entry [rt2x00usb]) from [<bfac95db>] (rt2x00lib_rxdone+0xe6/0x400 [rt2x00lib])
[ 9820.147373] [<bfac95db>] (rt2x00lib_rxdone [rt2x00lib]) from [<bfadba77>] (rt2x00usb_work_rxdone+0x46/0x6c [rt2x00usb])
[ 9820.147448] [<bfadba77>] (rt2x00usb_work_rxdone [rt2x00usb]) from [<c0043bdb>] (process_one_work+0xef/0x334)
[ 9820.147495] [<c0043bdb>] (process_one_work) from [<c0043f05>] (worker_thread+0xe5/0x380)
[ 9820.147540] [<c0043f05>] (worker_thread) from [<c0047fab>] (kthread+0xab/0xc0)
[ 9820.147587] [<c0047fab>] (kthread) from [<c000ecb5>] (ret_from_fork+0x11/0x1c)
[ 9848.136696] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u2:0:1129]
[ 9848.144976] Modules linked in: rt2800usb rt2800lib rt2x00usb rt2x00lib crc_ccitt ccm arc4 rtl8192cu rtl_usb rtl8192c_common rtlwifi mac80211 cfg80211 rfkill snd_soc_simple_card omap_sham omap_aes usb_f_ecm g_ether usb_f_rndis u_ether libcomposite omap_rng rng_core snd_soc_davinci_mcasp snd_soc_edma snd_soc_omap spi_omap2_mcspi snd_soc_hdmi_codec snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore evdev uio_pdrv_genirq uio tilcdc tda998x
[ 9848.145329] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9848.145354] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9848.145442] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9848.145473] task: ddbb5b00 ti: ddf48000 task.ti: ddf48000
[ 9848.145518] PC is at v7_dma_inv_range+0x34/0x40
[ 9848.145546] LR is at __dma_page_cpu_to_dev+0x21/0x74
[ 9848.145575] pc : [<c001c2aa>] lr : [<c001913d>] psr: 800e0033
sp : ddf49d10 ip : dc87404c fp : c0c29e18
[ 9848.145604] r10: 00000000 r9 : 00000f14 r8 : 00000002
[ 9848.145627] r7 : 00000002 r6 : 00000f14 r5 : 0000004c r4 : dfd70050
[ 9848.145651] r3 : 0000003f r2 : 00000040 r1 : dc874f40 r0 : dc874400
[ 9848.145679] Flags: Nzcv IRQs on FIQs on Mode SVC_32 ISA Thumb Segment none
[ 9848.145705] Control: 50c5387d Table: 9c184019 DAC: 00000051
[ 9848.145735] CPU: 0 PID: 1129 Comm: kworker/u2:0 Tainted: G L 4.4.6-ti-r15 #1
[ 9848.145755] Hardware name: Generic AM33XX (Flattened Device Tree)
[ 9848.145808] Workqueue: phy1 rt2x00usb_work_rxdone [rt2x00usb]
[ 9848.145877] [<c0015b5d>] (unwind_backtrace) from [<c001239d>] (show_stack+0x11/0x14)
[ 9848.145926] [<c001239d>] (show_stack) from [<c03c460b>] (dump_stack+0x67/0x74)
[ 9848.145973] [<c03c460b>] (dump_stack) from [<c00bb3f5>] (watchdog_timer_fn+0x18d/0x1dc)
[ 9848.146020] [<c00bb3f5>] (watchdog_timer_fn) from [<c0085aab>] (__hrtimer_run_queues+0x133/0x288)
[ 9848.146063] [<c0085aab>] (__hrtimer_run_queues) from [<c008629b>] (hrtimer_interrupt+0x9f/0x1ac)
[ 9848.146107] [<c008629b>] (hrtimer_interrupt) from [<c0021d13>] (omap2_gp_timer_interrupt+0x23/0x28)
[ 9848.146159] [<c0021d13>] (omap2_gp_timer_interrupt) from [<c0077203>] (handle_irq_event_percpu+0x67/0x1b4)
[ 9848.146203] [<c0077203>] (handle_irq_event_percpu) from [<c0077389>] (handle_irq_event+0x39/0x50)
[ 9848.146249] [<c0077389>] (handle_irq_event) from [<c00797e9>] (handle_level_irq+0x6d/0xc4)
[ 9848.146291] [<c00797e9>] (handle_level_irq) from [<c0076b19>] (generic_handle_irq+0x1d/0x28)
[ 9848.146334] [<c0076b19>] (generic_handle_irq) from [<c0076d11>] (__handle_domain_irq+0x45/0x84)
[ 9848.146377] [<c0076d11>] (__handle_domain_irq) from [<c06e2b8d>] (__irq_svc+0x4d/0x74)
[ 9848.146423] [<c06e2b8d>] (__irq_svc) from [<c001c2aa>] (v7_dma_inv_range+0x34/0x40)
[ 9848.146464] [<c001c2aa>] (v7_dma_inv_range) from [<c001913d>] (__dma_page_cpu_to_dev+0x21/0x74)
[ 9848.146502] [<c001913d>] (__dma_page_cpu_to_dev) from [<c001921f>] (arm_dma_map_page+0x57/0x58)
[ 9848.146546] [<c001921f>] (arm_dma_map_page) from [<c04fd5ad>] (usb_hcd_map_urb_for_dma+0x361/0x3c4)
[ 9848.146591] [<c04fd5ad>] (usb_hcd_map_urb_for_dma) from [<c04fda1b>] (usb_hcd_submit_urb+0x23f/0x6f4)
[ 9848.146654] [<c04fda1b>] (usb_hcd_submit_urb) from [<bfadb44b>] (rt2x00usb_kick_rx_entry+0x82/0xac [rt2x00usb])
[ 9848.146738] [<bfadb44b>] (rt2x00usb_kick_rx_entry [rt2x00usb]) from [<bfadb48f>] (rt2x00usb_clear_entry+0x1a/0x1c [rt2x00usb])
[ 9848.146911] [<bfadb48f>] (rt2x00usb_clear_entry [rt2x00usb]) from [<bfac95db>] (rt2x00lib_rxdone+0xe6/0x400 [rt2x00lib])
[ 9848.147038] [<bfac95db>] (rt2x00lib_rxdone [rt2x00lib]) from [<bfadba77>] (rt2x00usb_work_rxdone+0x46/0x6c [rt2x00usb])
[ 9848.147113] [<bfadba77>] (rt2x00usb_work_rxdone [rt2x00usb]) from [<c0043bdb>] (process_one_work+0xef/0x334)
[ 9848.147160] [<c0043bdb>] (process_one_work) from [<c0043f05>] (worker_thread+0xe5/0x380)
[ 9848.147204] [<c0043f05>] (worker_thread) from [<c0047fab>] (kthread+0xab/0xc0)
[ 9848.147253] [<c0047fab>] (kthread) from [<c000ecb5>] (ret_from_fork+0x11/0x1c)
[ 9852.998151] cpts: unable to obtain a time stamp
[ 9853.053867] usb 1-1: USB disconnect, device number 4
[ 9853.053921] usb 1-1.1: USB disconnect, device number 5
[ 9853.063945] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT
[ 9853.092068] systemd[1]: systemd-journald.service: Unit entered failed state.
[ 9853.101994] systemd[1]: systemd-journald.service: Failed with result 'signal'.
[ 9853.256592] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart.
[ 9853.302887] systemd[1]: Stopped Flush Journal to Persistent Storage.
[ 9853.303172] systemd[1]: Stopping Flush Journal to Persistent Storage...
[ 9853.303262] systemd[1]: Stopped Journal Service.
[ 9853.311989] systemd[1]: Starting Journal Service...
[ 9853.386076] systemd-journald[1583]: File /run/log/journal/5fb844b6e9bd4ae7b61d1b1d7871cdee/system.journal corrupted or uncleanly shut down, renaming and replacing.
[ 9853.457851] systemd[1]: Started Journal Service.
