syzbot


INFO: task hung in proc_bulk

Status: auto-closed as invalid on 2020/08/15 06:49
Subsystems: usb
[Documentation on labels]
First crash: 1438d, last: 1438d

Sample crash report:
INFO: task syz-executor.0:11097 blocked for more than 143 seconds.
      Not tainted 5.7.0-rc5-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0  D28560 11097   7002 0x00004004
Call Trace:
 context_switch kernel/sched/core.c:3367 [inline]
 __schedule+0x805/0xc90 kernel/sched/core.c:4083
 schedule+0x188/0x220 kernel/sched/core.c:4158
 schedule_timeout+0x43/0x250 kernel/time/timer.c:1874
 pv_queued_spin_unlock arch/x86/include/asm/paravirt.h:650 [inline]
 queued_spin_unlock arch/x86/include/asm/qspinlock.h:55 [inline]
 do_raw_spin_unlock+0x134/0x8d0 kernel/locking/spinlock_debug.c:139
 __raw_spin_unlock_irq include/linux/spinlock_api_smp.h:168 [inline]
 _raw_spin_unlock_irq+0x1f/0x80 kernel/locking/spinlock.c:199
 do_wait_for_common+0x232/0x370 kernel/sched/completion.c:85
 console_conditional_schedule+0x30/0x30
 __wait_for_common kernel/sched/completion.c:106 [inline]
 wait_for_common kernel/sched/completion.c:117 [inline]
 wait_for_completion_timeout+0x41/0x60 kernel/sched/completion.c:157
 usb_start_wait_urb+0x10d/0x2a0 drivers/usb/core/message.c:63
 proc_bulk+0xa28/0x1070 drivers/usb/core/devio.c:1225
 usbdev_do_ioctl drivers/usb/core/devio.c:2529 [inline]
 usbdev_ioctl+0xf10/0x6520 drivers/usb/core/devio.c:2695
 do_vfs_ioctl+0x6ea/0x1a00 fs/ioctl.c:749
 usbdev_poll+0x1e0/0x1e0 drivers/usb/core/devio.c:170
 vfs_ioctl fs/ioctl.c:47 [inline]
 ksys_ioctl fs/ioctl.c:771 [inline]
 __do_sys_ioctl fs/ioctl.c:780 [inline]
 __se_sys_ioctl+0xf9/0x160 fs/ioctl.c:778
 do_syscall_64+0xf3/0x1b0 arch/x86/entry/common.c:295
 entry_SYSCALL_64_after_hwframe+0x49/0xb3

Showing all locks held in the system:
3 locks held by kworker/u4:5/390:
 #0: ffff8880ae937518 (&rq->lock){-.-.}-{2:2}, at: newidle_balance+0x664/0xb20 kernel/sched/fair.c:10512
 #1: ffffffff892e8550 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30 net/mptcp/pm_netlink.c:860
 #2: ffff88804ffa56d8 (key#16){+...}-{2:2}, at: spin_lock_bh include/linux/spinlock.h:358 [inline]
 #2: ffff88804ffa56d8 (key#16){+...}-{2:2}, at: batadv_nc_purge_paths+0xe0/0x400 net/batman-adv/network-coding.c:441
1 lock held by khungtaskd/1133:
 #0: ffffffff892e8550 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30 net/mptcp/pm_netlink.c:860
1 lock held by in:imklog/6638:
 #0: ffff88809b43c3b0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x25d/0x2f0 fs/file.c:826

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

NMI backtrace for cpu 0
CPU: 0 PID: 1133 Comm: khungtaskd Not tainted 5.7.0-rc5-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1e9/0x30e lib/dump_stack.c:118
 nmi_cpu_backtrace+0x9f/0x180 lib/nmi_backtrace.c:101
 arch_trigger_cpumask_backtrace+0x10/0x10 arch/x86/kernel/apic/hw_nmi.c:38
 nmi_trigger_cpumask_backtrace+0x16a/0x280 lib/nmi_backtrace.c:62
 check_hung_uninterruptible_tasks kernel/hung_task.c:205 [inline]
 watchdog+0xd2a/0xd40 kernel/hung_task.c:289
 kthread+0x353/0x380 kernel/kthread.c:268
 hungtask_pm_notify+0x50/0x50 kernel/hung_task.c:265
 kthread_blkcg+0xd0/0xd0 kernel/kthread.c:1247
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:351
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 4109 Comm: systemd-journal Not tainted 5.7.0-rc5-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:orc_find arch/x86/kernel/unwind_orc.c:156 [inline]
RIP: 0010:unwind_next_frame+0x1d0/0x1f70 arch/x86/kernel/unwind_orc.c:439
Code: c1 ed 08 48 c7 c0 6c bc 67 8a 48 c7 c1 e4 bc 84 8a 48 29 c1 48 8d 41 03 48 85 c9 48 0f 49 c1 40 8a 35 d4 54 d8 07 48 c1 e8 02 <ff> c8 40 84 f6 b9 ff ff ff ff 0f 45 c8 39 e9 0f 86 74 08 00 00 89
RSP: 0018:ffffc90001677970 EFLAGS: 00000006
RAX: 000000000007401e RBX: ffffc90001677b10 RCX: 00000000001d0078
RDX: ffffc90001677ab8 RSI: ffffc90001677a01 RDI: ffffffff8132abbe
RBP: 0000000000002aaa R08: dffffc0000000000 R09: ffffc90001677b18
R10: fffff520002cef65 R11: 0000000000000000 R12: ffffffff812aaa27
R13: ffffc90001677ac8 R14: dffffc0000000000 R15: 1ffff920002cef5f
FS:  00007f1dd2d388c0(0000) GS:ffff8880ae900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f1dd0355028 CR3: 00000000928da000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 unwind_start arch/x86/include/asm/unwind.h:60 [inline]
 arch_stack_walk+0x78/0xe0 arch/x86/kernel/stacktrace.c:24
 __unwind_start+0x53a/0x630 arch/x86/kernel/unwind_orc.c:690
 stack_trace_save+0x150/0x150 kernel/stacktrace.c:124
 unwind_start arch/x86/include/asm/unwind.h:60 [inline]
 arch_stack_walk+0x78/0xe0 arch/x86/kernel/stacktrace.c:24
 unwind_start arch/x86/include/asm/unwind.h:60 [inline]
 arch_stack_walk+0x78/0xe0 arch/x86/kernel/stacktrace.c:24
 stack_trace_save+0xad/0x150 kernel/stacktrace.c:123
 save_stack mm/kasan/common.c:49 [inline]
 set_track mm/kasan/common.c:57 [inline]
 kasan_set_free_info mm/kasan/common.c:317 [inline]
 __kasan_slab_free+0x125/0x190 mm/kasan/common.c:456
 __kasan_slab_free+0x155/0x190 mm/kasan/common.c:458
 save_stack mm/kasan/common.c:49 [inline]
 set_track mm/kasan/common.c:57 [inline]
 kasan_set_free_info mm/kasan/common.c:317 [inline]
 __kasan_slab_free+0x125/0x190 mm/kasan/common.c:456
 __cache_free mm/slab.c:3426 [inline]
 kmem_cache_free+0x7e/0xf0 mm/slab.c:3694
 do_sys_openat2+0x605/0x770 fs/open.c:1157
 do_sys_open fs/open.c:1164 [inline]
 ksys_open include/linux/syscalls.h:1386 [inline]
 __do_sys_open fs/open.c:1170 [inline]
 __se_sys_open fs/open.c:1168 [inline]
 __x64_sys_open+0x1af/0x1e0 fs/open.c:1168
 do_syscall_64+0xf3/0x1b0 arch/x86/entry/common.c:295
 entry_SYSCALL_64_after_hwframe+0x49/0xb3
 pv_queued_spin_unlock arch/x86/include/asm/paravirt.h:650 [inline]
 queued_spin_unlock arch/x86/include/asm/qspinlock.h:55 [inline]
 do_raw_spin_unlock+0x134/0x8d0 kernel/locking/spinlock_debug.c:139
 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
 _raw_spin_unlock_irqrestore+0xb4/0xd0 kernel/locking/spinlock.c:191
 __debug_check_no_obj_freed lib/debugobjects.c:977 [inline]
 debug_check_no_obj_freed+0x592/0x640 lib/debugobjects.c:998
 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
 _raw_spin_unlock_irqrestore+0xb4/0xd0 kernel/locking/spinlock.c:191
 file_free fs/file_table.c:55 [inline]
 __fput+0x51c/0x750 fs/file_table.c:298
 __cache_free mm/slab.c:3426 [inline]
 kmem_cache_free+0x7e/0xf0 mm/slab.c:3694
 file_free fs/file_table.c:55 [inline]
 __fput+0x51c/0x750 fs/file_table.c:298
 task_work_run+0x147/0x1d0 kernel/task_work.c:123
 tracehook_notify_resume include/linux/tracehook.h:188 [inline]
 exit_to_usermode_loop arch/x86/entry/common.c:165 [inline]
 prepare_exit_to_usermode+0x48e/0x600 arch/x86/entry/common.c:196
 syscall_return_slowpath+0xf9/0x420 arch/x86/entry/common.c:278
 entry_SYSCALL_64_after_hwframe+0x49/0xb3
RIP: 0033:0x7f1dd22c8840
Code: 73 01 c3 48 8b 0d 68 77 20 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 83 3d 89 bb 20 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 1e f6 ff ff 48 89 04 24
RSP: 002b:00007fff439999c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: fffffffffffffffe RBX: 00007fff43999cd0 RCX: 00007f1dd22c8840
RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 00005583decc6350
RBP: 000000000000000d R08: 00000000000001e0 R09: 00000000ffffffff
R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff
R13: 00005583decc2040 R14: 00007fff43999c90 R15: 00005583decccbc0

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2020/05/17 06:49 upstream 3d1c1e5931ce 37bccd4e .config console log report ci-upstream-kasan-gce-smack-root
* Struck through repros no longer work on HEAD.