syzbot


INFO: task hung in sock_ioctl (2)

Status: auto-closed as invalid on 2021/06/05 18:05
Reported-by: syzbot+561d99f0ed65b20ba19e@syzkaller.appspotmail.com
First crash: 504d, last: 504d
similar bugs (4):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in sock_ioctl (2) 1 275d 275d 0/22 auto-closed as invalid on 2021/12/21 20:14
linux-4.14 INFO: task hung in sock_ioctl 1 803d 803d 0/1 auto-closed as invalid on 2020/08/10 12:43
upstream INFO: task hung in sock_ioctl 9 1257d 1614d 0/22 auto-closed as invalid on 2019/07/13 13:17
upstream INFO: task hung in rtnetlink_rcv_msg C inconclusive inconclusive 324 1d12h 1218d 0/22 upstream: reported C repro on 2019/02/22 17:00

Sample crash report:
Bluetooth: hci3 command 0x0406 tx timeout
Bluetooth: hci5 command 0x0406 tx timeout
Bluetooth: hci1 command 0x0406 tx timeout
Bluetooth: hci4 command 0x0406 tx timeout
Bluetooth: hci0 command 0x0406 tx timeout
INFO: task syz-executor.1:17084 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D28912 17084   8002 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f394f514188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c0b0 RCX: 0000000000465b09
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000005
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c0b0
R13: 00007ffdfba3360f R14: 00007f394f514300 R15: 0000000000022000
INFO: task syz-executor.1:17118 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D30344 17118   8002 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f394f4f3188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c158 RCX: 0000000000465b09
RDX: 0000000020000300 RSI: 00000000000089a1 RDI: 0000000000000005
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c158
R13: 00007ffdfba3360f R14: 00007f394f4f3300 R15: 0000000000022000
INFO: task syz-executor.4:17070 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D28128 17070   8004 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f519fda0188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000465b09
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000006
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007ffda54ed7ef R14: 00007f519fda0300 R15: 0000000000022000
INFO: task syz-executor.4:17086 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D28912 17086   8004 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f519fd5e188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c0b0 RCX: 0000000000465b09
RDX: 0000000020000300 RSI: 00000000000089a1 RDI: 0000000000000005
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c0b0
R13: 00007ffda54ed7ef R14: 00007f519fd5e300 R15: 0000000000022000
INFO: task syz-executor.4:17120 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D30344 17120   8004 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f519fd3d188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c158 RCX: 0000000000465b09
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000005
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c158
R13: 00007ffda54ed7ef R14: 00007f519fd3d300 R15: 0000000000022000
INFO: task syz-executor.4:17126 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D30120 17126   8004 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f519fd1c188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c200 RCX: 0000000000465b09
RDX: 0000000020000440 RSI: 00000000000089a0 RDI: 0000000000000006
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c200
R13: 00007ffda54ed7ef R14: 00007f519fd1c300 R15: 0000000000022000
INFO: task syz-executor.4:17127 blocked for more than 140 seconds.
      Not tainted 4.14.218-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D30120 17127   8004 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486
 __mutex_lock_common kernel/locking/mutex.c:833 [inline]
 __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
 sock_ioctl+0x239/0x4c0 net/socket.c:1036
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:500 [inline]
 do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684
 SYSC_ioctl fs/ioctl.c:701 [inline]
 SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x465b09
RSP: 002b:00007f519fcfb188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c2a8 RCX: 0000000000465b09
RDX: 0000000020000300 RSI: 00000000000089a1 RDI: 0000000000000006
RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c2a8
R13: 00007ffda54ed7ef R14: 00007f519fcfb300 R15: 0000000000022000

Showing all locks held in the system:
1 lock held by khungtaskd/1531:
 #0:  (tasklist_lock){.+.?}, at: [<ffffffff86fedc17>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548
1 lock held by syz-executor.1/17084:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
1 lock held by syz-executor.1/17118:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
1 lock held by syz-executor.4/17070:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
2 locks held by syz-executor.4/17080:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
 #1:  (rcu_preempt_state.barrier_mutex){+.+.}, at: [<ffffffff81469226>] _rcu_barrier+0x56/0x3b0 kernel/rcu/tree.c:3546
1 lock held by syz-executor.4/17086:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
1 lock held by syz-executor.4/17120:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
1 lock held by syz-executor.4/17126:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036
1 lock held by syz-executor.4/17127:
 #0:  (br_ioctl_mutex){+.+.}, at: [<ffffffff85ba7509>] sock_ioctl+0x239/0x4c0 net/socket.c:1036

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

NMI backtrace for cpu 1
CPU: 1 PID: 1531 Comm: khungtaskd Not tainted 4.14.218-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:17 [inline]
 dump_stack+0x1b2/0x281 lib/dump_stack.c:58
 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x13a/0x180 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline]
 watchdog+0x5b9/0xb40 kernel/hung_task.c:274
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 16991 Comm: syz-executor.5 Not tainted 4.14.218-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff8880ab7aa480 task.stack: ffff888065468000
RIP: 0010:check_preemption_disabled+0x40/0x240 lib/smp_processor_id.c:53
RSP: 0000:ffff88806546fca8 EFLAGS: 00000297
RAX: 0000000000000000 RBX: ffffffff893cff20 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffffffff87ccd640 RDI: ffffffff87ccd680
RBP: ffff8880ab7aa480 R08: 0000000000000000 R09: ffffed10156f5572
R10: 000000000000000b R11: ffff8880ab7aa480 R12: 0000000000000000
R13: ffff8880ab7aab90 R14: ffff88806546ff58 R15: 0000000000000000
FS:  00007f9782a03700(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fff5bfa8daf CR3: 00000000aab6c000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 rcu_dynticks_curr_cpu_in_eqs kernel/rcu/tree.c:360 [inline]
 rcu_is_watching+0x11/0xb0 kernel/rcu/tree.c:1130
 rcu_read_lock_sched_held+0xc6/0x1d0 kernel/rcu/update.c:111
 trace_x86_fpu_regs_deactivated arch/x86/include/asm/trace/fpu.h:62 [inline]
 fpregs_deactivate arch/x86/include/asm/fpu/internal.h:511 [inline]
 fpu__drop+0x4a2/0x560 arch/x86/kernel/fpu/core.c:349
 fpu__clear+0x33/0x3e0 arch/x86/kernel/fpu/core.c:387
 handle_signal arch/x86/kernel/signal.c:765 [inline]
 do_signal+0xefe/0x1550 arch/x86/kernel/signal.c:816
 exit_to_usermode_loop+0x160/0x200 arch/x86/entry/common.c:160
 prepare_exit_to_usermode+0x1af/0x210 arch/x86/entry/common.c:199
 retint_user+0x8/0x18
RIP: 0033:0x403e00
RSP: 002b:00007f9782a02c78 EFLAGS: 00000206
RAX: 0000000000000000 RBX: 000000000056c010 RCX: 00000000004b0f95
RDX: 00007f9782a02c80 RSI: 00007f9782a02db0 RDI: 000000000000000b
RBP: 000000000056c008 R08: 0000000000000467 R09: 000000000000000e
R10: 0019f38c4f65ed8c R11: 0000000000000001 R12: 000000000056c014
R13: 00007fff5bfa8daf R14: 00007f9782a03300 R15: 0000000000022000
Code: 65 44 8b 25 23 51 e9 7c 65 8b 05 fc ae e9 7c a9 ff ff ff 7f 74 18 e8 80 ee 3c fe e8 7b ee 3c fe 44 89 e0 5b 5d 41 5c 41 5d 41 5e <41> 5f c3 e8 68 ee 3c fe 48 c7 c0 80 92 f0 88 48 ba 00 00 00 00 

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci2-linux-4-14 2021/02/05 18:05 linux-4.14.y 2c8a3fceddf0 23a562df .config log report info INFO: task hung in sock_ioctl