syzbot


INFO: task hung in usbdev_release (2)

Status: auto-obsoleted due to no activity on 2023/03/08 04:55
Reported-by: syzbot+88c0f484809731459a08@syzkaller.appspotmail.com
First crash: 1140d, last: 675d
Similar bugs (7)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in usbdev_release (2) usb C error 80 16d 401d 0/28 upstream: reported C repro on 2023/08/09 15:47
upstream INFO: task hung in usbdev_release usb 20 999d 1406d 0/28 closed as invalid on 2022/02/07 19:19
linux-5.15 INFO: task hung in usbdev_release 1 200d 200d 0/3 auto-obsoleted due to no activity on 2024/06/05 22:03
linux-4.19 INFO: task hung in usbdev_release 1 1420d 1420d 0/1 auto-closed as invalid on 2021/02/21 22:17
linux-6.1 INFO: task hung in usbdev_release 1 547d 547d 0/3 auto-obsoleted due to no activity on 2023/07/13 23:24
linux-4.14 INFO: task hung in usbdev_release 2 1355d 1418d 0/1 auto-closed as invalid on 2021/04/27 06:36
linux-6.1 INFO: task hung in usbdev_release (2) origin:upstream C error 6 141d 363d 0/3 upstream: reported C repro on 2023/09/16 19:39

Sample crash report:
Bluetooth: hci8: command 0x0409 tx timeout
ieee802154 phy0 wpan0: encryption failed: -22
Bluetooth: hci8: command 0x041b tx timeout
Bluetooth: hci8: command 0x040f tx timeout
Bluetooth: hci8: command 0x0419 tx timeout
INFO: task syz-executor.5:32259 blocked for more than 140 seconds.
      Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5  D28752 32259  13535 0x80000002
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
 __mutex_lock_common kernel/locking/mutex.c:1016 [inline]
 __mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
 device_lock include/linux/device.h:1174 [inline]
 usbdev_release+0x83/0x420 drivers/usb/core/devio.c:1049
 __fput+0x2ce/0x890 fs/file_table.c:278
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 exit_task_work include/linux/task_work.h:22 [inline]
 do_exit+0xbf3/0x2be0 kernel/exit.c:870
 do_group_exit+0x125/0x310 kernel/exit.c:967
 get_signal+0x3f2/0x1f70 kernel/signal.c:2589
 do_signal+0x8f/0x1670 arch/x86/kernel/signal.c:799
 exit_to_usermode_loop+0x204/0x2a0 arch/x86/entry/common.c:163
 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
 syscall_return_slowpath+0x3f0/0x4a0 arch/x86/entry/common.c:271
 ret_from_fork+0x15/0x30 arch/x86/entry/entry_64.S:407
RIP: 0033:0x7f98505dba61
Code: Bad RIP value.
RSP: 002b:00007f984e9c12f0 EFLAGS: 00000206 ORIG_RAX: 0000000000000038
RAX: 0000000000000000 RBX: 00007f984e9c1700 RCX: 00007f98505dba61
RDX: 00007f984e9c19d0 RSI: 00007f984e9c12f0 RDI: 00000000003d0f00
RBP: 00007fff08962cd0 R08: 00007f984e9c1700 R09: 00007f984e9c1700
R10: 00007f984e9c19d0 R11: 0000000000000206 R12: 00007fff08962b3e
R13: 00007fff08962b3f R14: 00007f984e9c1300 R15: 0000000000022000
INFO: task syz-executor.0:32226 blocked for more than 140 seconds.
      Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0  D28648 32226  13683 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
 __mutex_lock_common kernel/locking/mutex.c:1016 [inline]
 __mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
 device_lock include/linux/device.h:1174 [inline]
 usbdev_release+0x83/0x420 drivers/usb/core/devio.c:1049
 __fput+0x2ce/0x890 fs/file_table.c:278
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:193 [inline]
 exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167
 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
 do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fbc7b55140b
Code: Bad RIP value.
RSP: 002b:00007ffd55d4ceb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 000000000000001e RCX: 00007fbc7b55140b
RDX: 0000001b31e20000 RSI: 0000000000000000 RDI: 000000000000001d
RBP: 00007fbc7b6c1980 R08: 0000000000000000 R09: 0000011ec0bbc762
R10: 0006f390096b55c5 R11: 0000000000000293 R12: 0000000000110848
R13: 00007ffd55d4cfb0 R14: 00007ffd55d4cfd0 R15: 0000000000000032
INFO: task syz-executor.0:32262 blocked for more than 140 seconds.
      Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0  D29896 32262  13683 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
 __mutex_lock_common kernel/locking/mutex.c:1016 [inline]
 __mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
 device_lock include/linux/device.h:1174 [inline]
 usbdev_do_ioctl+0x193/0x3030 drivers/usb/core/devio.c:2393
 usbdev_ioctl+0x21/0x30 drivers/usb/core/devio.c:2585
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:501 [inline]
 do_vfs_ioctl+0xcdb/0x12e0 fs/ioctl.c:688
 ksys_ioctl+0x9b/0xc0 fs/ioctl.c:705
 __do_sys_ioctl fs/ioctl.c:712 [inline]
 __se_sys_ioctl fs/ioctl.c:710 [inline]
 __x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:710
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fbc7b59f639
Code: Bad RIP value.
RSP: 002b:00007fbc79ad0168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007fbc7b6c0120 RCX: 00007fbc7b59f639
RDX: 0000000000000000 RSI: 0000000080045503 RDI: 0000000000000009
RBP: 00007fbc7b5fa7e1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffd55d4ce4f R14: 00007fbc79ad0300 R15: 0000000000022000
INFO: task syz-executor.2:32312 blocked for more than 140 seconds.
      Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2  D29504 32312   8153 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
 __mutex_lock_common kernel/locking/mutex.c:1016 [inline]
 __mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
 device_lock include/linux/device.h:1174 [inline]
 usbdev_open+0x152/0x7f0 drivers/usb/core/devio.c:1007
 chrdev_open+0x266/0x770 fs/char_dev.c:423
 do_dentry_open+0x4aa/0x1160 fs/open.c:796
 do_last fs/namei.c:3421 [inline]
 path_openat+0x793/0x2df0 fs/namei.c:3537
 do_filp_open+0x18c/0x3f0 fs/namei.c:3567
 do_sys_open+0x3b3/0x520 fs/open.c:1085
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f00a3ef66e4
Code: Bad RIP value.
RSP: 002b:00007f00a24b6ca0 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 00007f00a3ef66e4
RDX: 0000000000000001 RSI: 00007f00a24b6d40 RDI: 00000000ffffff9c
RBP: 00007f00a24b6d40 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000001
R13: 00007ffebcef341f R14: 00007f00a24b7300 R15: 0000000000022000

Showing all locks held in the system:
1 lock held by khungtaskd/1570:
 #0: 00000000a7b8849f (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
5 locks held by kworker/1:3/8365:
 #0: 000000007db21994 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
 #1: 0000000030237ab1 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
 #2: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #2: 0000000061bef097 (&dev->mutex){....}, at: hub_event+0x11e/0x44a0 drivers/usb/core/hub.c:5387
 #3: 0000000041b00ff2 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2983 [inline]
 #3: 0000000041b00ff2 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5069 [inline]
 #3: 0000000041b00ff2 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5253 [inline]
 #3: 0000000041b00ff2 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5361 [inline]
 #3: 0000000041b00ff2 (&port_dev->status_lock){+.+.}, at: hub_event+0x1cb8/0x44a0 drivers/usb/core/hub.c:5441
 #4: 000000001b113ee8 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b9/0x3120 drivers/usb/core/hub.c:4578
2 locks held by kworker/u4:3/6693:
1 lock held by syz-executor.5/32259:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_release+0x83/0x420 drivers/usb/core/devio.c:1049
1 lock held by syz-executor.0/32226:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_release+0x83/0x420 drivers/usb/core/devio.c:1049
1 lock held by syz-executor.0/32262:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_do_ioctl+0x193/0x3030 drivers/usb/core/devio.c:2393
1 lock held by syz-executor.2/32312:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_open+0x152/0x7f0 drivers/usb/core/devio.c:1007
1 lock held by syz-executor.2/788:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_open+0x152/0x7f0 drivers/usb/core/devio.c:1007
1 lock held by syz-executor.0/804:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_open+0x152/0x7f0 drivers/usb/core/devio.c:1007
1 lock held by syz-executor.5/811:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_open+0x152/0x7f0 drivers/usb/core/devio.c:1007
1 lock held by syz-executor.2/1148:
 #0: 0000000061bef097 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline]
 #0: 0000000061bef097 (&dev->mutex){....}, at: usbdev_open+0x152/0x7f0 drivers/usb/core/devio.c:1007

=============================================

NMI backtrace for cpu 0
CPU: 0 PID: 1570 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
Call Trace:
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
 watchdog+0x991/0xe60 kernel/hung_task.c:287
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 11288 Comm: kworker/u4:9 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
Workqueue: phy21 ieee80211_iface_work
RIP: 0010:unwind_next_frame+0xf0/0x1400 arch/x86/kernel/unwind_orc.c:398
Code: 81 c4 e8 00 00 00 5b 5d 41 5c 41 5d 41 5e 41 5f c3 4d 8d 66 48 48 b8 00 00 00 00 00 fc ff df 4c 89 e2 48 c1 ea 03 80 3c 02 00 <0f> 85 7b 0f 00 00 49 8b 46 48 48 89 44 24 10 49 8d 46 38 48 89 c2
RSP: 0018:ffff8880521beff0 EFLAGS: 00000246
RAX: dffffc0000000000 RBX: 1ffff1100a437e07 RCX: 0000000000000002
RDX: 1ffff1100a437e2d RSI: ffff8880521bef80 RDI: ffff8880521bf120
RBP: 0000000000000001 R08: 0000000000000001 R09: 0000000000000001
R10: ffff8880521bf17f R11: 0000000000074071 R12: ffff8880521bf168
R13: 0000000000000000 R14: ffff8880521bf120 R15: 0000000000000060
FS:  0000000000000000(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f58a0029d00 CR3: 00000000aaee9000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 __save_stack_trace+0x9f/0x190 arch/x86/kernel/stacktrace.c:44
 save_stack mm/kasan/kasan.c:448 [inline]
 set_track mm/kasan/kasan.c:460 [inline]
 kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:553
 slab_post_alloc_hook mm/slab.h:445 [inline]
 slab_alloc mm/slab.c:3397 [inline]
 __do_kmalloc mm/slab.c:3725 [inline]
 __kmalloc+0x144/0x3c0 mm/slab.c:3736
 kmalloc include/linux/slab.h:520 [inline]
 kzalloc include/linux/slab.h:709 [inline]
 cfg80211_inform_bss_frame_data+0x200/0xeb0 net/wireless/scan.c:1255
 ieee80211_bss_info_update+0x42d/0x1800 net/mac80211/scan.c:105
 ieee80211_rx_bss_info net/mac80211/ibss.c:1125 [inline]
 ieee80211_rx_mgmt_probe_beacon+0xb4f/0x15c0 net/mac80211/ibss.c:1607
 ieee80211_ibss_rx_queued_mgmt+0xe27/0x18b0 net/mac80211/ibss.c:1634
 ieee80211_iface_work+0x5c4/0x8a0 net/mac80211/iface.c:1341
 process_one_work+0x864/0x1570 kernel/workqueue.c:2153
 worker_thread+0x64c/0x1130 kernel/workqueue.c:2296
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415

Crashes (10):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2022/11/08 04:55 linux-4.19.y 3f8a27f9e27b 6feb842b .config console log report info [disk image] [vmlinux] ci2-linux-4-19 INFO: task hung in usbdev_release
2022/07/22 17:27 linux-4.19.y 3f8a27f9e27b 22343af4 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2022/06/14 02:18 linux-4.19.y 3f8a27f9e27b 0f087040 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2022/05/29 01:13 linux-4.19.y 3f8a27f9e27b a46af346 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2022/02/21 06:28 linux-4.19.y 3f8a27f9e27b 3cd800e4 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2021/11/21 19:09 linux-4.19.y 3f8a27f9e27b 4eb20a4e .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2021/11/14 20:56 linux-4.19.y 3f8a27f9e27b 75b04091 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2021/10/23 12:28 linux-4.19.y 3f8a27f9e27b 282f03fb .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2021/09/11 06:25 linux-4.19.y b172b44fcb17 3ce60af8 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
2021/07/31 01:52 linux-4.19.y a89b48fe9308 6c236867 .config console log report info ci2-linux-4-19 INFO: task hung in usbdev_release
* Struck through repros no longer work on HEAD.