syzbot


INFO: task hung in usbdev_open (3)

Status: upstream: reported on 2024/03/31 03:03
Reported-by: syzbot+89264153a792d1f4e809@syzkaller.appspotmail.com
First crash: 80d, last: 80d
Similar bugs (11)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 INFO: task hung in usbdev_open (3) 1 541d 541d 0/1 upstream: reported on 2022/12/25 16:08
upstream INFO: task hung in usbdev_open (2) usb C error 661 1d22h 1267d 0/27 upstream: reported C repro on 2020/12/29 11:48
linux-6.1 INFO: task hung in usbdev_open origin:lts-only C done 3 387d 444d 3/3 fixed on 2023/06/28 17:15
linux-4.19 INFO: task hung in usbdev_open (2) 2 940d 948d 0/1 auto-closed as invalid on 2022/03/21 09:54
linux-5.15 INFO: task hung in usbdev_open origin:lts-only C error 18 52d 444d 0/3 upstream: reported C repro on 2023/04/01 11:58
linux-6.1 INFO: task hung in usbdev_open (2) 1 191d 191d 0/3 auto-obsoleted due to no activity on 2024/03/20 05:29
linux-4.19 INFO: task hung in usbdev_open 6 1240d 1327d 0/1 auto-closed as invalid on 2021/05/25 14:21
linux-4.14 INFO: task hung in usbdev_open 7 1199d 1338d 0/1 auto-closed as invalid on 2021/07/05 05:59
upstream INFO: task hung in usbdev_open usb 2 1729d 1729d 0/27 auto-closed as invalid on 2019/12/23 20:14
linux-4.14 INFO: task hung in usbdev_open (2) 1 491d 491d 0/1 upstream: reported on 2023/02/13 07:50
upstream INFO: task can't die in usbdev_open usb 4 1233d 1346d 0/27 auto-closed as invalid on 2021/04/02 18:49

Sample crash report:
INFO: task syz-executor.1:8876 blocked for more than 143 seconds.
      Not tainted 6.1.83-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.1  state:D stack:27560 pid:8876  ppid:3970   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5245 [inline]
 __schedule+0x142d/0x4550 kernel/sched/core.c:6558
 schedule+0xbf/0x180 kernel/sched/core.c:6634
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6693
 __mutex_lock_common kernel/locking/mutex.c:679 [inline]
 __mutex_lock+0x6b9/0xd80 kernel/locking/mutex.c:747
 device_lock include/linux/device.h:837 [inline]
 usbdev_open+0x152/0x7a0 drivers/usb/core/devio.c:1052
 chrdev_open+0x54a/0x630 fs/char_dev.c:414
 do_dentry_open+0x7f9/0x10f0 fs/open.c:882
 do_open fs/namei.c:3628 [inline]
 path_openat+0x2644/0x2e60 fs/namei.c:3785
 do_filp_open+0x230/0x480 fs/namei.c:3812
 do_sys_openat2+0x13b/0x500 fs/open.c:1318
 do_sys_open fs/open.c:1334 [inline]
 __do_sys_openat fs/open.c:1350 [inline]
 __se_sys_openat fs/open.c:1345 [inline]
 __x64_sys_openat+0x243/0x290 fs/open.c:1345
 do_syscall_x64 arch/x86/entry/common.c:51 [inline]
 do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:81
 entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7f8ff3e7c9a0
RSP: 002b:00007f8ff4c7ac00 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f8ff3e7c9a0
RDX: 0000000000000000 RSI: 00007f8ff4c7aca0 RDI: 00000000ffffff9c
RBP: 00007f8ff4c7aca0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 000000000000000b R14: 00007f8ff3fabf80 R15: 00007ffc950a78e8
 </TASK>

Showing all locks held in the system:
1 lock held by rcu_tasks_kthre/12:
 #0: ffffffff8d12ab10 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x29/0xe30 kernel/rcu/tasks.h:516
1 lock held by rcu_tasks_trace/13:
 #0: ffffffff8d12b310 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x29/0xe30 kernel/rcu/tasks.h:516
1 lock held by khungtaskd/28:
 #0: ffffffff8d12a940 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire include/linux/rcupdate.h:350 [inline]
 #0: ffffffff8d12a940 (rcu_read_lock){....}-{1:2}, at: rcu_read_lock include/linux/rcupdate.h:791 [inline]
 #0: ffffffff8d12a940 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x51/0x290 kernel/locking/lockdep.c:6494
6 locks held by kworker/0:3/2963:
2 locks held by getty/3300:
 #0: ffff888029105098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x21/0x70 drivers/tty/tty_ldisc.c:244
 #1: ffffc900031262f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6a7/0x1db0 drivers/tty/n_tty.c:2188
6 locks held by kworker/1:8/3631:
 #0: 
ffff8881436e4138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x7a9/0x11d0 kernel/workqueue.c:2267
 #1: ffffc9000531fd20 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x7a9/0x11d0 kernel/workqueue.c:2267
 #2: ffff888144f64190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:837 [inline]
 #2: ffff888144f64190 (&dev->mutex){....}-{3:3}, at: hub_event+0x1fe/0x5730 drivers/usb/core/hub.c:5780
 #3: ffff88807e214190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:837 [inline]
 #3: ffff88807e214190 (&dev->mutex){....}-{3:3}, at: __device_attach+0x8a/0x570 drivers/base/dd.c:990
 #4: ffff888023e4e118 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:837 [inline]
 #4: ffff888023e4e118 (&dev->mutex){....}-{3:3}, at: __device_attach+0x8a/0x570 drivers/base/dd.c:990
 #5: ffff88807d204d60 (&dev->phy_mutex){+.+.}-{3:3}, at: __asix_mdio_read drivers/net/usb/asix_common.c:504 [inline]
 #5: ffff88807d204d60 (&dev->phy_mutex){+.+.}-{3:3}, at: asix_mdio_read+0xae/0x610 drivers/net/usb/asix_common.c:529
1 lock held by syz-executor.1/8876:
 #0: ffff888144f3c190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:837 [inline]
 #0: ffff888144f3c190 (&dev->mutex){....}-{3:3}, at: usbdev_open+0x152/0x7a0 drivers/usb/core/devio.c:1052
1 lock held by syz-executor.0/11324:
 #0: ffff888144f64190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:837 [inline]
 #0: ffff888144f64190 (&dev->mutex){....}-{3:3}, at: usbdev_open+0x152/0x7a0 drivers/usb/core/devio.c:1052
1 lock held by syz-executor.4/11494:
 #0: ffff888144f3c190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:837 [inline]
 #0: ffff888144f3c190 (&dev->mutex){....}-{3:3}, at: usbdev_open+0x152/0x7a0 drivers/usb/core/devio.c:1052

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

NMI backtrace for cpu 0
CPU: 0 PID: 28 Comm: khungtaskd Not tainted 6.1.83-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2cb lib/dump_stack.c:106
 nmi_cpu_backtrace+0x4e1/0x560 lib/nmi_backtrace.c:111
 nmi_trigger_cpumask_backtrace+0x1b0/0x3f0 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:148 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:220 [inline]
 watchdog+0xf88/0xfd0 kernel/hung_task.c:377
 kthread+0x28d/0x320 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:307
 </TASK>
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 3631 Comm: kworker/1:8 Not tainted 6.1.83-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
Workqueue: usb_hub_wq hub_event
RIP: 0010:io_serial_out+0x7a/0xb0 drivers/tty/serial/8250/8250_port.c:468
Code: fc 89 e9 41 d3 e7 48 83 c3 40 48 89 d8 48 c1 e8 03 42 80 3c 20 00 74 08 48 89 df e8 d0 02 31 fd 44 03 3b 44 89 f0 44 89 fa ee <5b> 41 5c 41 5e 41 5f 5d c3 89 e9 80 e1 07 38 c1 7c ab 48 89 ef e8
RSP: 0018:ffffc9000531e0b0 EFLAGS: 00000002
RAX: 000000000000005b RBX: ffffffff920184a0 RCX: 0000000000000000
RDX: 00000000000003f8 RSI: 000000000003ffff RDI: 0000000000040000
RBP: 0000000000000000 R08: ffffffff84b0f767 R09: ffffed1003f70047
R10: 0000000000000000 R11: dffffc0000000001 R12: dffffc0000000000
R13: 0000000000000001 R14: 000000000000005b R15: 00000000000003f8
FS:  0000000000000000(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000562158cad030 CR3: 000000007ec2b000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <TASK>
 serial8250_console_write+0x11d2/0x1750 drivers/tty/serial/8250/8250_port.c:3451
 call_console_driver kernel/printk/printk.c:1971 [inline]
 console_emit_next_record+0xbb4/0x1000 kernel/printk/printk.c:2771
 console_unlock+0x278/0x7c0 kernel/printk/printk.c:2900
 vprintk_emit+0x523/0x740 kernel/printk/printk.c:2297
 dev_vprintk_emit+0x2aa/0x323 drivers/base/core.c:4902
 dev_printk_emit+0xd9/0x118 drivers/base/core.c:4913
 __netdev_printk+0x335/0x410 net/core/dev.c:11257
 netdev_warn+0x11e/0x165 net/core/dev.c:11310
 asix_read_cmd drivers/net/usb/asix_common.c:33 [inline]
 asix_check_host_enable drivers/net/usb/asix_common.c:102 [inline]
 __asix_mdio_read drivers/net/usb/asix_common.c:506 [inline]
 asix_mdio_read+0x2a5/0x610 drivers/net/usb/asix_common.c:529
 asix_phy_reset+0xc6/0x1b0 drivers/net/usb/asix_devices.c:215
 ax88172_bind+0x434/0x5f0 drivers/net/usb/asix_devices.c:275
 usbnet_probe+0xb97/0x27d0 drivers/net/usb/usbnet.c:1745
 usb_probe_interface+0x5c0/0xaf0 drivers/usb/core/driver.c:396
 really_probe+0x2ab/0xcb0 drivers/base/dd.c:639
 __driver_probe_device+0x1a2/0x3d0 drivers/base/dd.c:785
 driver_probe_device+0x50/0x420 drivers/base/dd.c:815
 __device_attach_driver+0x2cf/0x510 drivers/base/dd.c:943
 bus_for_each_drv+0x183/0x200 drivers/base/bus.c:427
 __device_attach+0x359/0x570 drivers/base/dd.c:1015
 bus_probe_device+0xba/0x1e0 drivers/base/bus.c:487
 device_add+0xb48/0xfd0 drivers/base/core.c:3671
 usb_set_configuration+0x19dd/0x2020 drivers/usb/core/message.c:2165
 usb_generic_driver_probe+0x84/0x140 drivers/usb/core/generic.c:238
 usb_probe_device+0x130/0x260 drivers/usb/core/driver.c:293
 really_probe+0x2ab/0xcb0 drivers/base/dd.c:639
 __driver_probe_device+0x1a2/0x3d0 drivers/base/dd.c:785
 driver_probe_device+0x50/0x420 drivers/base/dd.c:815
 __device_attach_driver+0x2cf/0x510 drivers/base/dd.c:943
 bus_for_each_drv+0x183/0x200 drivers/base/bus.c:427
 __device_attach+0x359/0x570 drivers/base/dd.c:1015
 bus_probe_device+0xba/0x1e0 drivers/base/bus.c:487
 device_add+0xb48/0xfd0 drivers/base/core.c:3671
 usb_new_device+0xbdd/0x18f0 drivers/usb/core/hub.c:2605
 hub_port_connect drivers/usb/core/hub.c:5456 [inline]
 hub_port_connect_change drivers/usb/core/hub.c:5596 [inline]
 port_event drivers/usb/core/hub.c:5752 [inline]
 hub_event+0x2efe/0x5730 drivers/usb/core/hub.c:5834
 process_one_work+0x8a9/0x11d0 kernel/workqueue.c:2292
 worker_thread+0xa47/0x1200 kernel/workqueue.c:2439
 kthread+0x28d/0x320 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:307
 </TASK>

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/03/31 03:02 linux-6.1.y e5cd595e23c1 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-6-1-kasan INFO: task hung in usbdev_open
* Struck through repros no longer work on HEAD.