INFO: task hung in usb_bulk

INFO: task hung in usb_bulk_msg
Status: upstream: reported C repro on 2018/09/06 16:59
Reported-by: syzbot+7a7613e5ba9ae7bd15f9@syzkaller.appspotmail.com
First crash: 1148d, last: 4d23h

Cause bisection: failed (bisect log)

Fix bisection: fixed by (bisect log) [merge commit]:
commit de925e2fbb44eed8a2a7ce166b485fed5eae01aa
Author: Linus Torvalds <torvalds@linux-foundation.org>
Date: Wed Dec 16 23:44:44 2020 +0000

Merge branch 'for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/hid/hid

similar bugs (2):
Kernel	Title	Repro	Cause bisect	Fix bisect	Count	Last	Reported	Patched	Status
linux-4.14	INFO: task hung in usb_bulk_msg	C			55	7d14h	861d	0/1	upstream: reported C repro on 2019/06/20 04:21
linux-4.19	INFO: task hung in usb_bulk_msg	C			75	1d18h	927d	0/1	upstream: reported C repro on 2019/04/15 07:30

Sample crash report:

INFO: task syz-executor323:6547 blocked for more than 143 seconds.
      Not tainted 5.15.0-rc4-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor323 state:D stack:26760 pid: 6547 ppid:  6546 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4940 [inline]
 __schedule+0x940/0x26f0 kernel/sched/core.c:6287
 schedule+0xd3/0x270 kernel/sched/core.c:6366
 schedule_timeout+0x1db/0x2a0 kernel/time/timer.c:1857
 do_wait_for_common kernel/sched/completion.c:85 [inline]
 __wait_for_common kernel/sched/completion.c:106 [inline]
 wait_for_common kernel/sched/completion.c:117 [inline]
 wait_for_completion_timeout+0x171/0x280 kernel/sched/completion.c:157
 usb_start_wait_urb+0x144/0x4c0 drivers/usb/core/message.c:63
 usb_bulk_msg+0x226/0x550 drivers/usb/core/message.c:387
 do_proc_bulk+0x3a3/0x750 drivers/usb/core/devio.c:1236
 proc_bulk drivers/usb/core/devio.c:1273 [inline]
 usbdev_do_ioctl drivers/usb/core/devio.c:2547 [inline]
 usbdev_ioctl+0x586/0x36c0 drivers/usb/core/devio.c:2713
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:874 [inline]
 __se_sys_ioctl fs/ioctl.c:860 [inline]
 __x64_sys_ioctl+0x193/0x200 fs/ioctl.c:860
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f341de923a9
RSP: 002b:00007ffce9d5ab98 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f341de923a9
RDX: 00000000200002c0 RSI: 00000000c0185502 RDI: 0000000000000003
RBP: 0000000000000000 R08: 00007ffce9d5ad38 R09: 00007ffce9d5ad38
R10: 00007ffce9d5a610 R11: 0000000000000246 R12: 00007f341de516c0
R13: 431bde82d7b634db R14: 0000000000000000 R15: 0000000000000000

Showing all locks held in the system:
1 lock held by khungtaskd/27:
 #0: ffffffff8b97d3e0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6446
1 lock held by in:imklog/6449:
 #0: ffff88801f8eab70 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:990

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

NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: khungtaskd Not tainted 5.15.0-rc4-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
 nmi_cpu_backtrace.cold+0x47/0x144 lib/nmi_backtrace.c:105
 nmi_trigger_cpumask_backtrace+0x1ae/0x220 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
 watchdog+0xc1d/0xf50 kernel/hung_task.c:295
 kthread+0x3e5/0x4d0 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 2963 Comm: systemd-journal Not tainted 5.15.0-rc4-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:preempt_count arch/x86/include/asm/preempt.h:27 [inline]
RIP: 0010:check_preemption_disabled+0x1b/0x170 lib/smp_processor_id.c:16
Code: 0f 1f 44 00 00 0f 0b e9 23 ff ff ff cc cc cc 41 56 41 55 49 89 f5 41 54 55 48 89 fd 53 0f 1f 44 00 00 65 44 8b 25 bd 37 cc 76 <65> 8b 1d 4e 92 cc 76 81 e3 ff ff ff 7f 31 ff 89 de 0f 1f 44 00 00
RSP: 0018:ffffc90002b67a50 EFLAGS: 00000046
RAX: 0000000000000007 RBX: 1ffff9200056cf52 RCX: 0000000000000000
RDX: 0000000000000000 RSI: ffffffff898be600 RDI: ffffffff89e3a2a0
RBP: ffffffff89e3a2a0 R08: 0000000000000000 R09: ffff8880b9d32a8b
R10: ffffed10173a6551 R11: 0000000000086089 R12: 0000000000000001
R13: ffffffff898be600 R14: ffffffff904afe88 R15: 0000000000000000
FS:  00007f3e540f58c0(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f3e514d9000 CR3: 000000007ea5b000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 lockdep_recursion_inc kernel/locking/lockdep.c:433 [inline]
 lock_acquire kernel/locking/lockdep.c:5624 [inline]
 lock_acquire+0x170/0x510 kernel/locking/lockdep.c:5590
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
 _raw_spin_lock_irqsave+0x39/0x50 kernel/locking/spinlock.c:162
 __debug_check_no_obj_freed lib/debugobjects.c:980 [inline]
 debug_check_no_obj_freed+0xc7/0x420 lib/debugobjects.c:1023
 free_pages_prepare mm/page_alloc.c:1343 [inline]
 free_pcp_prepare+0x24e/0x780 mm/page_alloc.c:1389
 free_unref_page_prepare mm/page_alloc.c:3315 [inline]
 free_unref_page+0x19/0x690 mm/page_alloc.c:3394
 slab_destroy mm/slab.c:1627 [inline]
 slabs_destroy+0x89/0xc0 mm/slab.c:1647
 cache_flusharray mm/slab.c:3418 [inline]
 ___cache_free+0x4c6/0x610 mm/slab.c:3480
 qlink_free mm/kasan/quarantine.c:146 [inline]
 qlist_free_all+0x4e/0x110 mm/kasan/quarantine.c:165
 kasan_quarantine_reduce+0x180/0x200 mm/kasan/quarantine.c:272
 __kasan_slab_alloc+0x92/0xa0 mm/kasan/common.c:444
 kasan_slab_alloc include/linux/kasan.h:254 [inline]
 slab_post_alloc_hook mm/slab.h:519 [inline]
 slab_alloc mm/slab.c:3323 [inline]
 kmem_cache_alloc+0x25f/0x540 mm/slab.c:3507
 prepare_creds+0x3f/0x7b0 kernel/cred.c:262
 access_override_creds fs/open.c:351 [inline]
 do_faccessat+0x3f4/0x850 fs/open.c:415
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f3e533b09c7
Code: 83 c4 08 48 3d 01 f0 ff ff 73 01 c3 48 8b 0d c8 d4 2b 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 b8 15 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d a1 d4 2b 00 f7 d8 64 89 01 48
RSP: 002b:00007ffc44340c28 EFLAGS: 00000246 ORIG_RAX: 0000000000000015
RAX: ffffffffffffffda RBX: 00007ffc44343b40 RCX: 00007f3e533b09c7
RDX: 00007f3e53e21a00 RSI: 0000000000000000 RDI: 00005648140059a3
RBP: 00007ffc44340c60 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000069 R11: 0000000000000246 R12: 0000000000000000
R13: 0000000000000000 R14: 00007ffc44343b40 R15: 00007ffc44341150
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.171 msecs
----------------
Code disassembly (best guess):
   0:	0f 1f 44 00 00       	nopl   0x0(%rax,%rax,1)
   5:	0f 0b                	ud2
   7:	e9 23 ff ff ff       	jmpq   0xffffff2f
   c:	cc                   	int3
   d:	cc                   	int3
   e:	cc                   	int3
   f:	41 56                	push   %r14
  11:	41 55                	push   %r13
  13:	49 89 f5             	mov    %rsi,%r13
  16:	41 54                	push   %r12
  18:	55                   	push   %rbp
  19:	48 89 fd             	mov    %rdi,%rbp
  1c:	53                   	push   %rbx
  1d:	0f 1f 44 00 00       	nopl   0x0(%rax,%rax,1)
  22:	65 44 8b 25 bd 37 cc 	mov    %gs:0x76cc37bd(%rip),%r12d        # 0x76cc37e7
  29:	76
* 2a:	65 8b 1d 4e 92 cc 76 	mov    %gs:0x76cc924e(%rip),%ebx        # 0x76cc927f <-- trapping instruction
  31:	81 e3 ff ff ff 7f    	and    $0x7fffffff,%ebx
  37:	31 ff                	xor    %edi,%edi
  39:	89 de                	mov    %ebx,%esi
  3b:	0f 1f 44 00 00       	nopl   0x0(%rax,%rax,1)

Crashes (550):
Manager	Time	Kernel	Commit	Syzkaller	Config	Log	Report	Syz repro	C repro	VM info	Title
ci-upstream-kasan-gce-selinux-root	2021/10/08 14:36	upstream	1da38549dd64	efe0f24d	.config	log	report	syz	C		INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-selinux-root	2020/08/29 09:45	upstream	96d454cd2c16	d5a3ae1f	.config	log	report	syz	C
ci-upstream-kasan-gce-root	2020/08/29 05:36	upstream	96d454cd2c16	d5a3ae1f	.config	log	report	syz	C
ci-upstream-kasan-gce-selinux-root	2020/08/29 04:17	upstream	96d454cd2c16	d5a3ae1f	.config	log	report	syz	C
ci-upstream-kasan-gce	2020/08/28 16:44	upstream	15bc20c6af4c	816e0689	.config	log	report	syz	C
ci-upstream-kasan-gce-root	2020/08/28 12:52	upstream	15bc20c6af4c	816e0689	.config	log	report	syz	C
ci-upstream-kasan-gce-386	2020/08/28 16:12	upstream	15bc20c6af4c	816e0689	.config	log	report	syz	C
ci-upstream-kasan-gce-root	2021/10/23 15:31	upstream	9c0c4d24ac00	282f03fb	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/10/14 08:29	upstream	348949d9a444	5462d470	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/10/06 06:33	upstream	f6274b06e326	0a63fd36	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/10/05 23:56	upstream	f6274b06e326	0a63fd36	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/10/03 04:16	upstream	ab2a7a35c4e7	db0f5787	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/09/30 08:15	upstream	02d5e016800d	be530f6c	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/27 19:07	upstream	5816b3e6577e	78494d16	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/27 16:18	upstream	5816b3e6577e	78494d16	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/26 05:23	upstream	a5e0aceabef6	8cac236e	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/24 13:04	upstream	f9e36107ec70	8cac236e	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/22 14:26	upstream	92477dd1faa6	169724fe	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/17 09:00	upstream	bdb575f87217	5b989942	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/16 10:41	upstream	b7213ffa0e58	07e953c1	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-selinux-root	2021/09/07 05:41	upstream	27151f177827	6ca60148	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/09/06 07:10	upstream	0319b848b155	d236a457	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/09/04 12:17	upstream	7cca308cfdc0	d236a457	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/08/15 10:54	upstream	ba31f97d43be	2489ab88	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-selinux-root	2021/08/09 06:51	upstream	66745863ecde	6972b106	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/08/08 19:09	upstream	85a90500f9a1	6972b106	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/07/27 06:46	upstream	ff1176468d36	fd511809	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/07/04 11:02	upstream	3dbdb38e2869	55aa55c2	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/06/15 04:12	upstream	009c9aa5be65	1ba81399	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/05/31 01:22	upstream	b90e90f40b4f	325a8dab	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2021/04/23 14:39	upstream	18a3c5f7abfd	17f0b706	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/04/14 00:18	upstream	eebe426d32e1	a184b83e	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/04/13 19:51	upstream	89698becf06d	a184b83e	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/04/12 13:50	upstream	d434405aaab7	bfeda1b1	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/04/11 23:49	upstream	7d900724913c	bfeda1b1	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-selinux-root	2021/04/11 06:11	upstream	52e44129fba5	bfeda1b1	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/02/21 03:07	upstream	f40ddce88593	3e5ed8b4	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce	2021/02/06 00:07	upstream	dd86e7fa07a3	23a562df	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-386	2021/09/29 02:32	upstream	d33bec7b3dfa	d82cb927	.config	log	report			info	INFO: task hung in usb_bulk_msg
ci-upstream-kasan-gce-root	2020/09/27 22:51	upstream	eeddbe6841cd	5dd8aee8	.config	log	report			info
ci-upstream-kasan-gce	2020/09/27 20:16	upstream	a1bffa48745a	5dd8aee8	.config	log	report			info
ci-upstream-kasan-gce	2020/09/22 03:24	upstream	ba4f184e126b	9e1fa68e	.config	log	report			info
ci-upstream-kasan-gce	2020/09/12 12:06	upstream	e8878ab82545	79fb24e2	.config	log	report
ci-upstream-kasan-gce	2020/09/10 06:53	upstream	34d4ddd359db	409809d8	.config	log	report
ci-upstream-kasan-gce	2020/09/10 02:10	upstream	34d4ddd359db	409809d8	.config	log	report
ci-upstream-kasan-gce	2020/09/09 06:16	upstream	612ab8ad6414	abf9ba4f	.config	log	report
ci-upstream-kasan-gce	2020/09/08 14:59	upstream	f4d51dffc6c0	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-selinux-root	2020/09/07 09:58	upstream	f4d51dffc6c0	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-root	2020/09/06 20:19	upstream	dd9fb9bb3340	abf9ba4f	.config	log	report
ci-upstream-kasan-gce	2020/09/06 18:43	upstream	dd9fb9bb3340	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-selinux-root	2020/09/06 18:26	upstream	dd9fb9bb3340	abf9ba4f	.config	log	report
ci-upstream-kasan-gce	2020/09/06 02:38	upstream	9322c47b21b9	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-root	2020/09/04 23:41	upstream	59126901f200	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-selinux-root	2020/09/04 20:11	upstream	59126901f200	abf9ba4f	.config	log	report
ci-upstream-kasan-gce	2020/09/04 17:54	upstream	59126901f200	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-root	2020/09/03 16:22	upstream	fc3abb53250a	abf9ba4f	.config	log	report
ci-upstream-kasan-gce	2020/09/03 14:36	upstream	fc3abb53250a	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-smack-root	2020/02/11 01:26	upstream	bb6d3fb354c5	18847f55	.config	log	report
ci-upstream-kasan-gce-selinux-root	2018/09/06 11:20	upstream	b36fdc6853a3	0bb7a7eb	.config	log	report
ci-upstream-kasan-gce-386	2020/09/09 08:47	upstream	6f6a73c8b715	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-386	2020/09/07 19:41	upstream	f4d51dffc6c0	abf9ba4f	.config	log	report
ci-upstream-kasan-gce-386	2020/09/07 02:59	upstream	f4d51dffc6c0	abf9ba4f	.config	log	report
ci-upstream-linux-next-kasan-gce-root	2020/07/16 10:02	linux-next	ca0e494af5ed	f3bec699	.config	log	report