INFO: task kworker/u4:0:9 blocked for more than 143 seconds.
Not tainted 5.15.103-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/u4:0 state:D stack:22752 pid: 9 ppid: 2 flags:0x00004000
Workqueue: events_unbound btrfs_async_reclaim_metadata_space
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5023 [inline]
__schedule+0x1348/0x4610 kernel/sched/core.c:6369
schedule+0x11b/0x1f0 kernel/sched/core.c:6452
wait_on_state fs/btrfs/extent_io.c:868 [inline]
wait_extent_bit+0x3f9/0x530 fs/btrfs/extent_io.c:906
lock_extent_bits fs/btrfs/extent_io.c:1469 [inline]
find_lock_delalloc_range+0x469/0x8f0 fs/btrfs/extent_io.c:2047
writepage_delalloc+0x1a0/0x5a0 fs/btrfs/extent_io.c:3795
__extent_writepage+0x636/0xcc0 fs/btrfs/extent_io.c:4108
extent_write_cache_pages fs/btrfs/extent_io.c:5029 [inline]
extent_writepages+0xbbd/0x1590 fs/btrfs/extent_io.c:5156
do_writepages+0x481/0x730 mm/page-writeback.c:2364
filemap_fdatawrite_wbc+0x1d6/0x230 mm/filemap.c:400
start_delalloc_inodes+0x7df/0xca0 fs/btrfs/inode.c:10064
btrfs_start_delalloc_roots+0x72f/0xa70 fs/btrfs/inode.c:10143
shrink_delalloc fs/btrfs/space-info.c:538 [inline]
flush_space+0x650/0xe40 fs/btrfs/space-info.c:646
btrfs_async_reclaim_metadata_space+0x29b/0x340 fs/btrfs/space-info.c:953
process_one_work+0x90d/0x1270 kernel/workqueue.c:2306
worker_thread+0xaca/0x1280 kernel/workqueue.c:2453
kthread+0x3f6/0x4f0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:298
</TASK>
INFO: task kworker/1:3:3595 blocked for more than 144 seconds.
Not tainted 5.15.103-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/1:3 state:D stack:23320 pid: 3595 ppid: 2 flags:0x00004000
Workqueue: events bio_dirty_fn
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5023 [inline]
__schedule+0x1348/0x4610 kernel/sched/core.c:6369
schedule+0x11b/0x1f0 kernel/sched/core.c:6452
io_schedule+0x88/0x100 kernel/sched/core.c:8469
wait_on_page_bit_common+0xa13/0x1180 mm/filemap.c:1356
lock_page include/linux/pagemap.h:625 [inline]
set_page_dirty_lock+0xae/0xe0 mm/page-writeback.c:2633
bio_release_pages+0x231/0x550 block/bio.c:1030
bio_dirty_fn+0x6b/0xa0 block/bio.c:1385
process_one_work+0x90d/0x1270 kernel/workqueue.c:2306
worker_thread+0xaca/0x1280 kernel/workqueue.c:2453
kthread+0x3f6/0x4f0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:298
</TASK>
INFO: task syz-executor.5:5959 blocked for more than 145 seconds.
Not tainted 5.15.103-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D stack:22752 pid: 5959 ppid: 3646 flags:0x00004004
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5023 [inline]
__schedule+0x1348/0x4610 kernel/sched/core.c:6369
schedule+0x11b/0x1f0 kernel/sched/core.c:6452
io_schedule+0x88/0x100 kernel/sched/core.c:8469
wait_on_page_bit_common+0xa13/0x1180 mm/filemap.c:1356
lock_page include/linux/pagemap.h:625 [inline]
set_page_dirty_lock+0xae/0xe0 mm/page-writeback.c:2633
bio_set_pages_dirty+0x1f3/0x2c0 block/bio.c:1349
iomap_dio_bio_iter+0xb8d/0x1540 fs/iomap/direct-io.c:338
__iomap_dio_rw+0xf9f/0x1f40 fs/iomap/direct-io.c:586
iomap_dio_rw+0x38/0x80 fs/iomap/direct-io.c:672
btrfs_direct_read fs/btrfs/file.c:3788 [inline]
btrfs_file_read_iter+0x3a1/0x6a0 fs/btrfs/file.c:3828
do_iter_readv_writev+0x594/0x7a0
do_iter_read+0x1ec/0x760 fs/read_write.c:790
vfs_readv fs/read_write.c:910 [inline]
do_preadv+0x211/0x350 fs/read_write.c:1002
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f862696a0f9
RSP: 002b:00007f861dabb168 EFLAGS: 00000246 ORIG_RAX: 0000000000000147
RAX: ffffffffffffffda RBX: 00007f8626a8a050 RCX: 00007f862696a0f9
RDX: 0000000000000001 RSI: 0000000020000100 RDI: 0000000000000005
RBP: 00007f86269c5b39 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffe8c487d7f R14: 00007f861dabb300 R15: 0000000000022000
</TASK>
INFO: task syz-executor.5:5962 blocked for more than 147 seconds.
Not tainted 5.15.103-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D
stack:27904 pid: 5962 ppid: 3646 flags:0x00004004
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5023 [inline]
__schedule+0x1348/0x4610 kernel/sched/core.c:6369
schedule+0x11b/0x1f0 kernel/sched/core.c:6452
rwsem_down_write_slowpath+0xebb/0x15c0 kernel/locking/rwsem.c:1157
__down_write_common kernel/locking/rwsem.c:1284 [inline]
__down_write kernel/locking/rwsem.c:1293 [inline]
down_write+0x164/0x170 kernel/locking/rwsem.c:1542
inode_lock include/linux/fs.h:787 [inline]
btrfs_inode_lock+0x49/0xd0 fs/btrfs/inode.c:124
btrfs_buffered_write+0x20f/0x1270 fs/btrfs/file.c:1679
btrfs_direct_write fs/btrfs/file.c:2038 [inline]
btrfs_file_write_iter+0x975/0xc60 fs/btrfs/file.c:2086
call_write_iter include/linux/fs.h:2103 [inline]
new_sync_write fs/read_write.c:507 [inline]
vfs_write+0xacf/0xe50 fs/read_write.c:594
ksys_write+0x1a2/0x2c0 fs/read_write.c:647
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f862696a0f9
RSP: 002b:00007f861da9a168 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007f8626a8a120 RCX: 00007f862696a0f9
RDX: 0000000000000128 RSI: 00000000200011c0 RDI: 0000000000000005
RBP: 00007f86269c5b39 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffe8c487d7f R14: 00007f861da9a300 R15: 0000000000022000
</TASK>
INFO: task syz-executor.5:5963 blocked for more than 148 seconds.
Not tainted 5.15.103-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D stack:29408 pid: 5963 ppid: 3646 flags:0x00004004
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5023 [inline]
__schedule+0x1348/0x4610 kernel/sched/core.c:6369
schedule+0x11b/0x1f0 kernel/sched/core.c:6452
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6511
__mutex_lock_common+0xe34/0x25a0 kernel/locking/mutex.c:669
__mutex_lock kernel/locking/mutex.c:729 [inline]
mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
__fdget_pos+0x261/0x300 fs/file.c:1073
fdget_pos include/linux/file.h:75 [inline]
ksys_write+0x7e/0x2c0 fs/read_write.c:638
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f862696a0f9
RSP: 002b:00007f861da79168 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007f8626a8a1f0 RCX: 00007f862696a0f9
RDX: 0000000000000128 RSI: 00000000200011c0 RDI: 0000000000000005
RBP: 00007f86269c5b39 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffe8c487d7f R14: 00007f861da79300 R15: 0000000000022000
</TASK>
INFO: task syz-executor.5:5964 blocked for more than 148 seconds.
Not tainted 5.15.103-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5 state:D stack:28608 pid: 5964 ppid: 3646 flags:0x00004004
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5023 [inline]
__schedule+0x1348/0x4610 kernel/sched/core.c:6369
schedule+0x11b/0x1f0 kernel/sched/core.c:6452
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6511
__mutex_lock_common+0xe34/0x25a0 kernel/locking/mutex.c:669
__mutex_lock kernel/locking/mutex.c:729 [inline]
mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
__fdget_pos+0x261/0x300 fs/file.c:1073
fdget_pos include/linux/file.h:75 [inline]
ksys_write+0x7e/0x2c0 fs/read_write.c:638
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f862696a0f9
RSP: 002b:00007f861da58168 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007f8626a8a2c0 RCX: 00007f862696a0f9
RDX: 0000000000000ea5 RSI: 0000000020000300 RDI: 0000000000000005
RBP: 00007f86269c5b39 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffe8c487d7f R14: 00007f861da58300 R15: 0000000000022000
</TASK>
Showing all locks held in the system:
4 locks held by kworker/u4:0/9:
#0: ffff888011c69138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x794/0x1270 kernel/workqueue.c:2279
#1: ffffc90000ce7d20 ((work_completion)(&fs_info->async_reclaim_work)){+.+.}-{0:0}, at: process_one_work+0x7da/0x1270 kernel/workqueue.c:2281
#2: ffff88807e3cceb0 (&fs_info->delalloc_root_mutex){+.+.}-{3:3}, at: btrfs_start_delalloc_roots+0x1c6/0xa70 fs/btrfs/inode.c:10124
#3: ffff88803c52c750 (&root->delalloc_mutex){+.+.}-{3:3}, at: start_delalloc_inodes+0x1a8/0xca0 fs/btrfs/inode.c:10029
1 lock held by khungtaskd/27:
#0: ffffffff8c91c320 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
2 locks held by getty/3272:
#0: ffff88814af38098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x21/0x70 drivers/tty/tty_ldisc.c:252
#1: ffffc900020a32e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6af/0x1da0 drivers/tty/n_tty.c:2147
2 locks held by kworker/1:3/3595:
#0: ffff888011c64d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x794/0x1270 kernel/workqueue.c:2279
#1: ffffc9000215fd20 (bio_dirty_work){+.+.}-{0:0}, at: process_one_work+0x7da/0x1270 kernel/workqueue.c:2281
1 lock held by syz-executor.1/3631:
#0: ffff88801b728918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_get_by_dev+0x14d/0xa50 block/bdev.c:817
3 locks held by kworker/u4:7/3946:
#0: ffff8880145ae138 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x794/0x1270 kernel/workqueue.c:2279
#1: ffffc9000455fd20 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x7da/0x1270 kernel/workqueue.c:2281
#2: ffff88807eb220e0 (&type->s_umount_key#71){++++}-{3:3}, at: trylock_super+0x1b/0xf0 fs/super.c:418
2 locks held by kworker/0:20/7554:
#0: ffff888011c66538 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x794/0x1270 kernel/workqueue.c:2279
#1: ffffc90003a1fd20 ((work_completion)(&rew.rew_work)){+.+.}-{0:0}, at: process_one_work+0x7da/0x1270 kernel/workqueue.c:2281
3 locks held by kworker/0:21/7557:
#0: ffff888011c64d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x794/0x1270 kernel/workqueue.c:2279
#1: ffffc90003a7fd20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x7da/0x1270 kernel/workqueue.c:2281
#2: ffffffff8c920868 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:290 [inline]
#2: ffffffff8c920868 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x280/0x740 kernel/rcu/tree_exp.h:840
2 locks held by kworker/0:28/7573:
2 locks held by kworker/u4:31/8548:
1 lock held by syz-executor.5/5959:
#0: ffff88803c3f40f0 (&sb->s_type->i_mutex_key#21){++++}-{3:3}, at: inode_lock_shared include/linux/fs.h:797 [inline]
#0: ffff88803c3f40f0 (&sb->s_type->i_mutex_key#21){++++}-{3:3}, at: btrfs_inode_lock+0x5c/0xd0 fs/btrfs/inode.c:116
3 locks held by syz-executor.5/5962:
#0: ffff888079b60370 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x261/0x300 fs/file.c:1073
#1: ffff88807eb22460 (sb_writers#13){.+.+}-{0:0}, at: vfs_write+0x29a/0xe50 fs/read_write.c:590
#2: ffff88803c3f40f0 (&sb->s_type->i_mutex_key#21){++++}-{3:3}, at: inode_lock include/linux/fs.h:787 [inline]
#2: ffff88803c3f40f0 (&sb->s_type->i_mutex_key#21){++++}-{3:3}, at: btrfs_inode_lock+0x49/0xd0 fs/btrfs/inode.c:124
1 lock held by syz-executor.5/5963:
#0: ffff888079b60370 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x261/0x300 fs/file.c:1073
1 lock held by syz-executor.5/5964:
#0: ffff888079b60370 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x261/0x300 fs/file.c:1073
2 locks held by udevd/6015:
#0: ffff88801b728918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_put+0xfb/0x790 block/bdev.c:912
#1: ffff88801b6cf468 (&lo->lo_mutex){+.+.}-{3:3}, at: __loop_clr_fd+0xa9/0xbe0 drivers/block/loop.c:1348
2 locks held by udevd/6028:
#0: ffff88801b77e118 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_put+0xfb/0x790 block/bdev.c:912
#1: ffff88801b7a8468 (&lo->lo_mutex){+.+.}-{3:3}, at: __loop_clr_fd+0xa9/0xbe0 drivers/block/loop.c:1348
1 lock held by udevd/6029:
#0: ffffffff8cdf4c08 (uuid_mutex){+.+.}-{3:3}, at: btrfs_control_ioctl+0xbe/0x210 fs/btrfs/super.c:2456
2 locks held by udevd/6157:
#0: ffff88801b779918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_put+0xfb/0x790 block/bdev.c:912
#1: ffff88801b6ed468 (&lo->lo_mutex){+.+.}-{3:3}, at: __loop_clr_fd+0xa9/0xbe0 drivers/block/loop.c:1348
1 lock held by syz-executor.0/7750:
#0: ffff8880b9b39698 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x26/0x140 kernel/sched/core.c:475
1 lock held by syz-executor.0/7791:
2 locks held by syz-executor.5/7772:
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 27 Comm: khungtaskd Not tainted 5.15.103-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/2023
Call Trace:
<TASK>
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0x1e3/0x2cb lib/dump_stack.c:106
nmi_cpu_backtrace+0x46a/0x4a0 lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x181/0x2a0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:148 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
watchdog+0xf54/0xfa0 kernel/hung_task.c:295
kthread+0x3f6/0x4f0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:298
</TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 7772 Comm: syz-executor.5 Not tainted 5.15.103-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/2023
RIP: 0010:get_current arch/x86/include/asm/current.h:15 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x4/0x60 kernel/kcov.c:196
Code: 00 00 00 0f 1f 00 53 48 89 fb e8 17 00 00 00 48 8b 3d 18 12 63 0c 48 89 de 5b e9 e7 b2 48 00 cc cc cc cc cc cc cc 48 8b 04 24 <65> 48 8b 0d e4 f6 81 7e 65 8b 15 e5 f6 81 7e f7 c2 00 01 ff 00 74
RSP: 0018:ffffc9000b2ef6d8 EFLAGS: 00000046
RAX: ffffffff81a780ac RBX: ffffea0000a54580 RCX: 0000000000040000
RDX: ffffc9000f7e8000 RSI: 000000000003ffff RDI: ffffea0000a54580
RBP: ffffc9000b2ef848 R08: dffffc0000000000 R09: 0000000000000003
R10: ffffffffffffffff R11: dffffc0000000001 R12: ffff888093ab4df0
R13: ffffea0000a54580 R14: dffffc0000000000 R15: ffffc9000b2ef7a0
FS: 00007f6160391700(0000) GS:ffff8880b9a00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007ffd1bcf5df8 CR3: 000000001a21c000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<TASK>
trace_mm_filemap_delete_from_page_cache+0xc/0x280 include/trace/events/filemap.h:47
__delete_from_page_cache+0xc6/0x860 mm/filemap.c:229
delete_from_page_cache+0x126/0x190 mm/filemap.c:266
truncate_inode_page+0x8d/0xb0 mm/truncate.c:219
shmem_undo_range+0x535/0x1b50 mm/shmem.c:960
shmem_truncate_range mm/shmem.c:1063 [inline]
shmem_evict_inode+0x21b/0xa00 mm/shmem.c:1145
evict+0x2a4/0x620 fs/inode.c:587
__dentry_kill+0x436/0x650 fs/dcache.c:582
dentry_kill+0xbb/0x290
dput+0x1f1/0x420 fs/dcache.c:888
__fput+0x5ec/0x890 fs/file_table.c:288
task_work_run+0x129/0x1a0 kernel/task_work.c:164
tracehook_notify_resume include/linux/tracehook.h:189 [inline]
exit_to_user_mode_loop+0x106/0x130 kernel/entry/common.c:175
exit_to_user_mode_prepare+0xb1/0x140 kernel/entry/common.c:207
__syscall_exit_to_user_mode_work kernel/entry/common.c:289 [inline]
syscall_exit_to_user_mode+0x5d/0x2b0 kernel/entry/common.c:300
do_syscall_64+0x49/0xb0 arch/x86/entry/common.c:86
entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f6161dd0fab
Code: 0f 05 48 3d 00 f0 ff ff 77 45 c3 0f 1f 40 00 48 83 ec 18 89 7c 24 0c e8 63 fc ff ff 8b 7c 24 0c 41 89 c0 b8 03 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 35 44 89 c7 89 44 24 0c e8 a1 fc ff ff 8b 44
RSP: 002b:00007f6160390f20 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 00007f6161dd0fab
RDX: 0000000000000004 RSI: 0000000000004c00 RDI: 0000000000000004
RBP: 0000000000000005 R08: 0000000000000000 R09: 00000000000055a2
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000010
R13: 00007f6160390fdc R14: 00007f61603916b8 R15: 0000000020005682
</TASK>