INFO: task syz-executor.4:12038 can't die for more than 143 seconds.
task:syz-executor.4 state:D stack:28112 pid:12038 ppid: 10132 flags:0x00000004
Call Trace:
context_switch kernel/sched/core.c:4986 [inline]
__schedule+0xab2/0x4e90 kernel/sched/core.c:6296
schedule+0xd2/0x260 kernel/sched/core.c:6369
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6428
__mutex_lock_common kernel/locking/mutex.c:673 [inline]
__mutex_lock+0xa32/0x12f0 kernel/locking/mutex.c:733
vhost_net_stop_vq drivers/vhost/net.c:1359 [inline]
vhost_net_stop+0x142/0x270 drivers/vhost/net.c:1373
vhost_net_release+0x9c/0x2d0 drivers/vhost/net.c:1405
__fput+0x286/0x9f0 fs/file_table.c:311
task_work_run+0xdd/0x1a0 kernel/task_work.c:164
tracehook_notify_resume include/linux/tracehook.h:189 [inline]
exit_to_user_mode_loop kernel/entry/common.c:175 [inline]
exit_to_user_mode_prepare+0x27e/0x290 kernel/entry/common.c:207
__syscall_exit_to_user_mode_work kernel/entry/common.c:289 [inline]
syscall_exit_to_user_mode+0x19/0x60 kernel/entry/common.c:300
do_syscall_64+0x42/0xb0 arch/x86/entry/common.c:86
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f431165eadb
RSP: 002b:00007fffbd8c2680 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 00007f431165eadb
RDX: 00007f43117c3690 RSI: ffffffffffffffff RDI: 0000000000000003
RBP: 00007f43117c0960 R08: 0000000000000000 R09: 00007f43117c3698
R10: 00007fffbd8c2780 R11: 0000000000000293 R12: 000000000007b196
R13: 00007fffbd8c2780 R14: 00007f43117bef60 R15: 0000000000000032
INFO: task syz-executor.4:12038 blocked for more than 143 seconds.
Not tainted 5.16.0-rc8-next-20220110-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.4 state:D stack:28112 pid:12038 ppid: 10132 flags:0x00000004
Call Trace:
context_switch kernel/sched/core.c:4986 [inline]
__schedule+0xab2/0x4e90 kernel/sched/core.c:6296
schedule+0xd2/0x260 kernel/sched/core.c:6369
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6428
__mutex_lock_common kernel/locking/mutex.c:673 [inline]
__mutex_lock+0xa32/0x12f0 kernel/locking/mutex.c:733
vhost_net_stop_vq drivers/vhost/net.c:1359 [inline]
vhost_net_stop+0x142/0x270 drivers/vhost/net.c:1373
vhost_net_release+0x9c/0x2d0 drivers/vhost/net.c:1405
__fput+0x286/0x9f0 fs/file_table.c:311
task_work_run+0xdd/0x1a0 kernel/task_work.c:164
tracehook_notify_resume include/linux/tracehook.h:189 [inline]
exit_to_user_mode_loop kernel/entry/common.c:175 [inline]
exit_to_user_mode_prepare+0x27e/0x290 kernel/entry/common.c:207
__syscall_exit_to_user_mode_work kernel/entry/common.c:289 [inline]
syscall_exit_to_user_mode+0x19/0x60 kernel/entry/common.c:300
do_syscall_64+0x42/0xb0 arch/x86/entry/common.c:86
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f431165eadb
RSP: 002b:00007fffbd8c2680 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 00007f431165eadb
RDX: 00007f43117c3690 RSI: ffffffffffffffff RDI: 0000000000000003
RBP: 00007f43117c0960 R08: 0000000000000000 R09: 00007f43117c3698
R10: 00007fffbd8c2780 R11: 0000000000000293 R12: 000000000007b196
R13: 00007fffbd8c2780 R14: 00007f43117bef60 R15: 0000000000000032
Showing all locks held in the system:
1 lock held by khungtaskd/27:
#0: ffffffff8bb83a20 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6460
2 locks held by getty/3283:
#0: ffff88807f97c098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:244
#1: ffffc90002b962e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xcf0/0x1230 drivers/tty/n_tty.c:2077
1 lock held by syz-executor.4/12038:
#0: ffff88804ecb0218 (&vq->mutex){+.+.}-{3:3}, at: vhost_net_stop_vq drivers/vhost/net.c:1359 [inline]
#0: ffff88804ecb0218 (&vq->mutex){+.+.}-{3:3}, at: vhost_net_stop+0x142/0x270 drivers/vhost/net.c:1373
1 lock held by vhost-12039/12042:
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: khungtaskd Not tainted 5.16.0-rc8-next-20220110-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:111
nmi_trigger_cpumask_backtrace+0x1b3/0x230 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:256 [inline]
watchdog+0xcb7/0xed0 kernel/hung_task.c:413
kthread+0x2e9/0x3a0 kernel/kthread.c:377
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: 2964 Comm: klogd Not tainted 5.16.0-rc8-next-20220110-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:format_decode+0x146/0xad0 lib/vsprintf.c:2541
Code: 07 00 00 45 0f b6 7d 00 31 ff 44 89 fe e8 42 69 69 fd 45 84 ff 0f 84 46 08 00 00 49 bc 00 00 00 00 00 fc ff df 4c 89 eb eb 43 45 65 69 fd 48 83 c3 01 48 89 d8 48 89 5c 24 38 48 89 da 48 c1
RSP: 0018:ffffc90001aaf818 EFLAGS: 00000206
RAX: 0000000000000000 RBX: ffffffff89ace860 RCX: ffff88807dde57c0
RDX: 0000000000000000 RSI: ffff88807dde57c0 RDI: 0000000000000003
RBP: ffffc90001aaf930 R08: 000000007fffffff R09: 000000000000003c
R10: ffffffff840f054b R11: 0000000000000025 R12: dffffc0000000000
R13: ffffffff89ace860 R14: 1ffff92000355f06 R15: 000000000000003c
FS: 00007f44349a8800(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f62783ea680 CR3: 000000007f951000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
vsnprintf+0x155/0x14f0 lib/vsprintf.c:2759
sprintf+0xc0/0x100 lib/vsprintf.c:3009
print_syslog kernel/printk/printk.c:1257 [inline]
info_print_prefix+0x2d5/0x340 kernel/printk/printk.c:1287
record_print_text+0x14d/0x3e0 kernel/printk/printk.c:1339
syslog_print+0x48a/0x580 kernel/printk/printk.c:1539
do_syslog.part.0+0x202/0x640 kernel/printk/printk.c:1658
do_syslog kernel/printk/printk.c:1748 [inline]
__do_sys_syslog kernel/printk/printk.c:1750 [inline]
__se_sys_syslog kernel/printk/printk.c:1748 [inline]
__x64_sys_syslog+0x97/0xc0 kernel/printk/printk.c:1748
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:0x7f4434b438b7
Code: 73 01 c3 48 8b 0d c1 05 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 b8 67 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 91 05 0c 00 f7 d8 64 89 01 48
RSP: 002b:00007ffede0019f8 EFLAGS: 00000206 ORIG_RAX: 0000000000000067
RAX: ffffffffffffffda RBX: 00007f4434cd0490 RCX: 00007f4434b438b7
RDX: 00000000000003ff RSI: 00007f4434cd0490 RDI: 0000000000000002
RBP: 0000000000000000 R08: 0000000000000007 R09: 00007f443601a6f0
R10: 0000000000004000 R11: 0000000000000206 R12: 00007f4434cd0490
R13: 00007f4434cd04f3 R14: 00007f4434cd04f3 R15: 0000000000000000
----------------
Code disassembly (best guess), 1 bytes skipped:
0: 00 00 add %al,(%rax)
2: 45 0f b6 7d 00 movzbl 0x0(%r13),%r15d
7: 31 ff xor %edi,%edi
9: 44 89 fe mov %r15d,%esi
c: e8 42 69 69 fd callq 0xfd696953
11: 45 84 ff test %r15b,%r15b
14: 0f 84 46 08 00 00 je 0x860
1a: 49 bc 00 00 00 00 00 movabs $0xdffffc0000000000,%r12
21: fc ff df
24: 4c 89 eb mov %r13,%rbx
27: eb 43 jmp 0x6c
* 29: e8 45 65 69 fd callq 0xfd696573 <-- trapping instruction
2e: 48 83 c3 01 add $0x1,%rbx
32: 48 89 d8 mov %rbx,%rax
35: 48 89 5c 24 38 mov %rbx,0x38(%rsp)
3a: 48 89 da mov %rbx,%rdx
3d: 48 rex.W
3e: c1 .byte 0xc1