INFO: task kworker/0:0:7 blocked for more than 143 seconds.
Not tainted 6.5.0-rc1-syzkaller-00006-g3f01e9fed845 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/0:0 state:D stack:23848 pid:7 ppid:2 flags:0x00004000
Workqueue: xfs-sync/loop2 xfs_flush_inodes_worker
Call Trace:
context_switch kernel/sched/core.c:5381 [inline]
__schedule+0x1873/0x48f0 kernel/sched/core.c:6710
schedule+0xc3/0x180 kernel/sched/core.c:6786
wb_wait_for_completion+0x166/0x290 fs/fs-writeback.c:192
sync_inodes_sb+0x297/0xab0 fs/fs-writeback.c:2736
xfs_flush_inodes_worker+0x61/0x80 fs/xfs/xfs_super.c:623
process_one_work+0x92c/0x12c0 kernel/workqueue.c:2597
worker_thread+0xa63/0x1210 kernel/workqueue.c:2748
kthread+0x2b8/0x350 kernel/kthread.c:389
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:308
INFO: task syz-executor.2:10369 blocked for more than 144 seconds.
Not tainted 6.5.0-rc1-syzkaller-00006-g3f01e9fed845 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:23688 pid:10369 ppid:5049 flags:0x00004006
Call Trace:
context_switch kernel/sched/core.c:5381 [inline]
__schedule+0x1873/0x48f0 kernel/sched/core.c:6710
schedule+0xc3/0x180 kernel/sched/core.c:6786
schedule_timeout+0xb0/0x310 kernel/time/timer.c:2143
do_wait_for_common+0x449/0x5f0 kernel/sched/completion.c:85
__wait_for_common kernel/sched/completion.c:106 [inline]
wait_for_common kernel/sched/completion.c:117 [inline]
wait_for_completion+0x4a/0x60 kernel/sched/completion.c:138
__flush_work+0x130/0x1b0 kernel/workqueue.c:3386
xfs_file_buffered_write+0x35b/0xa90 fs/xfs/xfs_file.c:771
call_write_iter include/linux/fs.h:1871 [inline]
new_sync_write fs/read_write.c:491 [inline]
vfs_write+0x782/0xaf0 fs/read_write.c:584
ksys_write+0x1a0/0x2c0 fs/read_write.c:637
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x41/0xc0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7fe1ae08c389
RSP: 002b:00007fe1aee8b168 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007fe1ae1abf80 RCX: 00007fe1ae08c389
RDX: 00000000ffffffe4 RSI: 0000000020000000 RDI: 0000000000000004
RBP: 00007fe1ae0d7493 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffde90e1d6f R14: 00007fe1aee8b300 R15: 0000000000022000
Showing all locks held in the system:
4 locks held by kworker/0:0/7:
#0: ffff88807b0e0938 ((wq_completion)xfs-sync/loop2){+.+.}-{0:0}, at: process_one_work+0x7e3/0x12c0 kernel/workqueue.c:2569
#1: ffffc900000c7d20 ((work_completion)(&mp->m_flush_inodes_work)){+.+.}-{0:0}, at: process_one_work+0x82b/0x12c0 kernel/workqueue.c:2571
#2: ffff88802928c0e0 (&type->s_umount_key#57){++++}-{3:3}, at: xfs_flush_inodes_worker+0x45/0x80 fs/xfs/xfs_super.c:622
#3: ffff8881413647d0 (&bdi->wb_switch_rwsem){+.+.}-{3:3}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:364 [inline]
#3: ffff8881413647d0 (&bdi->wb_switch_rwsem){+.+.}-{3:3}, at: sync_inodes_sb+0x278/0xab0 fs/fs-writeback.c:2734
1 lock held by rcu_tasks_kthre/13:
#0: ffffffff8d328af0 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x29/0xd20 kernel/rcu/tasks.h:522
1 lock held by rcu_tasks_trace/14:
#0: ffffffff8d328eb0 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x29/0xd20 kernel/rcu/tasks.h:522
1 lock held by khungtaskd/28:
#0: ffffffff8d328920 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
2 locks held by getty/4766:
#0: ffff88802890c098 (&tty->ldisc_sem
){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243
#1: ffffc900015b02f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6b1/0x1dc0 drivers/tty/n_tty.c:2187
1 lock held by syz-executor.0/5043:
#0: ffff88807b1340e0 (&type->s_umount_key#57){++++}-{3:3}, at: deactivate_super+0xad/0xf0 fs/super.c:360
1 lock held by syz-executor.1/5047:
#0: ffffffff8d32dfb8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:293 [inline]
#0: ffffffff8d32dfb8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x3a3/0x890 kernel/rcu/tree_exp.h:992
1 lock held by syz-executor.3/5057:
#0: ffff88807ca080e0 (&type->s_umount_key#57){++++}-{3:3}, at: deactivate_super+0xad/0xf0 fs/super.c:360
2 locks held by kworker/0:5/5098:
#0: ffff888012870d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x7e3/0x12c0 kernel/workqueue.c:2569
#1: ffffc900048dfd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x82b/0x12c0 kernel/workqueue.c:2571
3 locks held by kworker/0:7/5101:
#0: ffff888012870d38 ((wq_completion)events){+.+.}-{0:0}
, at: process_one_work+0x7e3/0x12c0 kernel/workqueue.c:2569
#1: ffffc9000490fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x82b/0x12c0 kernel/workqueue.c:2571
#2: ffffffff8d32dfb8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:325 [inline]
#2: ffffffff8d32dfb8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x46c/0x890 kernel/rcu/tree_exp.h:992
2 locks held by kworker/0:8/5314:
#0: ffff888012872538 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x7e3/0x12c0 kernel/workqueue.c:2569
#1: ffffc900055efd20 ((work_completion)(&rew->rew_work)){+.+.}-{0:0}, at: process_one_work+0x82b/0x12c0 kernel/workqueue.c:2571
4 locks held by kworker/0:12/5330:
2 locks held by kworker/u4:19/7876:
4 locks held by kworker/u4:26/8875:
#0: ffff888012ea6938 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x7e3/0x12c0 kernel/workqueue.c:2569
#1: ffffc9000641fd20
(
(work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x82b/0x12c0 kernel/workqueue.c:2571
#2: ffff88802928c600 (sb_internal#4){.+.+}-{0:0}, at: xfs_bmapi_convert_delalloc+0x267/0x1310 fs/xfs/libxfs/xfs_bmap.c:4527
#3: ffff88807c721098 (&xfs_nondir_ilock_class#3){++++}-{3:3}, at: xfs_bmapi_convert_delalloc+0x295/0x1310 fs/xfs/libxfs/xfs_bmap.c:4532
3 locks held by syz-executor.2/10369:
#0: ffff88802cb4e5c8 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x254/0x2f0 fs/file.c:1047
#1: ffff88802928c410 (sb_writers#16){.+.+}-{0:0}, at: vfs_write+0x216/0xaf0 fs/read_write.c:580
#2: ffff88807c7212b8 (&sb->s_type->i_mutex_key#22){++++}-{3:3}, at: xfs_ilock+0xf2/0x3a0 fs/xfs/xfs_inode.c:195
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 28 Comm: khungtaskd Not tainted 6.5.0-rc1-syzkaller-00006-g3f01e9fed845 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/03/2023
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0x1e7/0x2d0 lib/dump_stack.c:106
nmi_cpu_backtrace+0x498/0x4d0 lib/nmi_backtrace.c:113
nmi_trigger_cpumask_backtrace+0x187/0x300 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:160 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:222 [inline]
watchdog+0xec2/0xf00 kernel/hung_task.c:379
kthread+0x2b8/0x350 kernel/kthread.c:389
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:308
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 5330 Comm: kworker/0:12 Not tainted 6.5.0-rc1-syzkaller-00006-g3f01e9fed845 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/03/2023
Workqueue: xfs-buf/loop2 xfs_buf_ioend_work
RIP: 0010:skip_atoi+0x6f/0xd0 lib/vsprintf.c:168
Code: e8 03 42 0f b6 04 28 84 c0 75 3a 45 01 e4 0f b6 1b 4c 89 f8 48 c1 e8 03 42 0f b6 04 28 84 c0 75 37 43 8d 04 a4 44 8d 64 18 d0 <41> 0f b6 07 83 c0 c6 83 f8 f6 72 36 e8 70 f0 d4 f6 42 80 7c 2d 00
RSP: 0018:ffffc9000538f028 EFLAGS: 00000246
RAX: 0000000000000000 RBX: 0000000000000035 RCX: ffff888036f9bb80
RDX: 0000000000000000 RSI: 00000000fffffffb RDI: ffffc9000538f0e0
RBP: 1ffff92000a71e1c R08: ffffffff8ab67f02 R09: ffffffff8ab67d7a
R10: 0000000000000005 R11: ffff888036f9bb80 R12: 0000000000000005
R13: dffffc0000000000 R14: ffffc9000538f0e0 R15: ffffffff8b0b4f63
FS: 0000000000000000(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000555555e25848 CR3: 00000000339f4000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
format_decode+0xb15/0x1bb0 lib/vsprintf.c:2585
vsnprintf+0x14a/0x1d80 lib/vsprintf.c:2769
sprintf+0xda/0x120 lib/vsprintf.c:3021
print_time kernel/printk/printk.c:1327 [inline]
info_print_prefix+0x16b/0x300 kernel/printk/printk.c:1353
record_print_text kernel/printk/printk.c:1402 [inline]
printk_get_next_message+0x408/0xce0 kernel/printk/printk.c:2809
console_emit_next_record kernel/printk/printk.c:2844 [inline]
console_flush_all+0x3aa/0xd70 kernel/printk/printk.c:2933
console_unlock+0xff/0x270 kernel/printk/printk.c:3007
vprintk_emit+0xd5/0x1f0 kernel/printk/printk.c:2307
_printk+0xd5/0x120 kernel/printk/printk.c:2328
__xfs_printk fs/xfs/xfs_message.c:24 [inline]
xfs_printk_level+0x1ca/0x310 fs/xfs/xfs_message.c:44
xfs_buf_verifier_error+0x16c/0x2a0 fs/xfs/xfs_error.c:454
xfs_allocbt_read_verify+0x5a/0xd0
xfs_buf_ioend+0x270/0x6f0 fs/xfs/xfs_buf.c:1302
process_one_work+0x92c/0x12c0 kernel/workqueue.c:2597
worker_thread+0xa63/0x1210 kernel/workqueue.c:2748
kthread+0x2b8/0x350 kernel/kthread.c:389
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:308