INFO: task kworker/u4:10:2837 blocked for more than 143 seconds.
Not tainted 6.7.0-syzkaller-00562-g9f8413c4a66f #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/u4:10 state:D stack:19800 pid:2837 tgid:2837 ppid:2 flags:0x00004000
Workqueue: btrfs-endio-write btrfs_work_helper
Call Trace:
context_switch kernel/sched/core.c:5399 [inline]
__schedule+0x177f/0x4960 kernel/sched/core.c:6726
__schedule_loop kernel/sched/core.c:6801 [inline]
schedule+0x149/0x260 kernel/sched/core.c:6816
wait_extent_bit fs/btrfs/extent-io-tree.c:799 [inline]
lock_extent+0x69d/0x850 fs/btrfs/extent-io-tree.c:1843
btrfs_finish_one_ordered+0x57f/0x1680 fs/btrfs/inode.c:3083
btrfs_work_helper+0x37c/0xbe0 fs/btrfs/async-thread.c:315
process_one_work kernel/workqueue.c:2633 [inline]
process_scheduled_works+0x90f/0x1420 kernel/workqueue.c:2706
worker_thread+0xa5f/0x1000 kernel/workqueue.c:2787
kthread+0x2d3/0x370 kernel/kthread.c:388
ret_from_fork+0x48/0x80 arch/x86/kernel/process.c:147
ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:242
INFO: task syz-executor.2:27720 blocked for more than 144 seconds.
Not tainted 6.7.0-syzkaller-00562-g9f8413c4a66f #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:23536 pid:27720 tgid:27719 ppid:10819 flags:0x00004006
Call Trace:
context_switch kernel/sched/core.c:5399 [inline]
__schedule+0x177f/0x4960 kernel/sched/core.c:6726
__schedule_loop kernel/sched/core.c:6801 [inline]
schedule+0x149/0x260 kernel/sched/core.c:6816
io_schedule+0x8c/0x100 kernel/sched/core.c:9022
folio_wait_bit_common+0x881/0x12b0 mm/filemap.c:1273
invalidate_inode_pages2_range+0x467/0x1040 mm/truncate.c:657
kiocb_invalidate_post_direct_write+0x137/0x2d0 mm/filemap.c:3828
iomap_dio_complete+0x50b/0x8e0 fs/iomap/direct-io.c:114
btrfs_direct_write fs/btrfs/file.c:1556 [inline]
btrfs_do_write_iter+0x7f4/0x1190 fs/btrfs/file.c:1684
call_write_iter include/linux/fs.h:2084 [inline]
iter_file_splice_write+0xa64/0x1320 fs/splice.c:743
do_splice_from fs/splice.c:941 [inline]
direct_splice_actor+0xf6/0x1e0 fs/splice.c:1164
splice_direct_to_actor+0x54e/0xc80 fs/splice.c:1108
do_splice_direct_actor fs/splice.c:1207 [inline]
do_splice_direct+0x28c/0x3e0 fs/splice.c:1233
do_sendfile+0x56d/0xdb0 fs/read_write.c:1295
__do_sys_sendfile64 fs/read_write.c:1362 [inline]
__se_sys_sendfile64+0x17c/0x1e0 fs/read_write.c:1348
do_syscall_x64 arch/x86/entry/common.c:52 [inline]
do_syscall_64+0xf5/0x230 arch/x86/entry/common.c:83
entry_SYSCALL_64_after_hwframe+0x63/0x6b
RIP: 0033:0x7f8bc1a7cd29
RSP: 002b:00007f8bc28480c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 00007f8bc1babf80 RCX: 00007f8bc1a7cd29
RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000004
RBP: 00007f8bc1ac947a R08: 0000000000000000 R09: 0000000000000000
R10: 0000000008800000 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000000b R14: 00007f8bc1babf80 R15: 00007ffe700a3f08
INFO: task syz-executor.2:27746 blocked for more than 145 seconds.
Not tainted 6.7.0-syzkaller-00562-g9f8413c4a66f #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:20944 pid:27746 tgid:27719 ppid:10819 flags:0x00004006
Call Trace:
context_switch kernel/sched/core.c:5399 [inline]
__schedule+0x177f/0x4960 kernel/sched/core.c:6726
__schedule_loop kernel/sched/core.c:6801 [inline]
schedule+0x149/0x260 kernel/sched/core.c:6816
btrfs_start_ordered_extent+0x41c/0x5f0 fs/btrfs/ordered-data.c:801
btrfs_lock_and_flush_ordered_range+0x115/0x220 fs/btrfs/ordered-data.c:1105
contiguous_readpages fs/btrfs/extent_io.c:1152 [inline]
extent_readahead+0x10c1/0x1470 fs/btrfs/extent_io.c:2248
read_pages+0x183/0x830 mm/readahead.c:160
page_cache_ra_unbounded+0x186/0x7c0 mm/readahead.c:241
page_cache_sync_readahead include/linux/pagemap.h:1283 [inline]
filemap_get_pages+0x49c/0x2080 mm/filemap.c:2497
filemap_splice_read+0x4ce/0xcd0 mm/filemap.c:2869
do_splice_read fs/splice.c:985 [inline]
splice_direct_to_actor+0x48a/0xc80 fs/splice.c:1089
do_splice_direct_actor fs/splice.c:1207 [inline]
do_splice_direct+0x28c/0x3e0 fs/splice.c:1233
do_sendfile+0x56d/0xdb0 fs/read_write.c:1295
__do_sys_sendfile64 fs/read_write.c:1362 [inline]
__se_sys_sendfile64+0x17c/0x1e0 fs/read_write.c:1348
do_syscall_x64 arch/x86/entry/common.c:52 [inline]
do_syscall_64+0xf5/0x230 arch/x86/entry/common.c:83
entry_SYSCALL_64_after_hwframe+0x63/0x6b
RIP: 0033:0x7f8bc1a7cd29
RSP: 002b:00007f8bc28270c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 00007f8bc1bac050 RCX: 00007f8bc1a7cd29
RDX: 0000000000000000 RSI: 0000000000000007 RDI: 0000000000000008
RBP: 00007f8bc1ac947a R08: 0000000000000000 R09: 0000000000000000
R10: 0001000000201005 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000006e R14: 00007f8bc1bac050 R15: 00007ffe700a3f08
Showing all locks held in the system:
1 lock held by khungtaskd/29:
#0: ffffffff8d92eba0 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire include/linux/rcupdate.h:301 [inline]
#0: ffffffff8d92eba0 (rcu_read_lock){....}-{1:2}, at: rcu_read_lock include/linux/rcupdate.h:747 [inline]
#0: ffffffff8d92eba0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x2a0 kernel/locking/lockdep.c:6614
3 locks held by kworker/u4:6/1052:
3 locks held by kworker/u4:10/2837:
#0: ffff888027e92938 ((wq_completion)btrfs-endio-write){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:2608 [inline]
#0: ffff888027e92938 ((wq_completion)btrfs-endio-write){+.+.}-{0:0}, at: process_scheduled_works+0x825/0x1420 kernel/workqueue.c:2706
#1: ffffc9000a477d20 ((work_completion)(&work->normal_work)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:2608 [inline]
#1: ffffc9000a477d20 ((work_completion)(&work->normal_work)){+.+.}-{0:0}, at: process_scheduled_works+0x825/0x1420 kernel/workqueue.c:2706
#2: ffff888049c1a4a8 (btrfs_ordered_extent){++++}-{0:0}, at: btrfs_finish_one_ordered+0x339/0x1680 fs/btrfs/inode.c:3043
2 locks held by getty/4817:
#0: ffff88802a5820a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243
#1: ffffc90002f062f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6b4/0x1e10 drivers/tty/n_tty.c:2201
2 locks held by kworker/u4:5/13909:
#0: ffff888056e53938 ((wq_completion)btrfs-flush_delalloc){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:2608 [inline]
#0: ffff888056e53938 ((wq_completion)btrfs-flush_delalloc){+.+.}-{0:0}, at: process_scheduled_works+0x825/0x1420 kernel/workqueue.c:2706
#1: ffffc9000a4e7d20 ((work_completion)(&work->normal_work)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:2608 [inline]
#1: ffffc9000a4e7d20 ((work_completion)(&work->normal_work)){+.+.}-{0:0}, at: process_scheduled_works+0x825/0x1420 kernel/workqueue.c:2706
1 lock held by kworker/1:3/25430:
#0: ffff8880b993c958 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140 kernel/sched/core.c:558
1 lock held by syz-executor.2/27720:
#0: ffff888041bea420 (sb_writers#14){.+.+}-{0:0}, at: direct_splice_actor+0x4a/0x1e0 fs/splice.c:1163
1 lock held by syz-executor.2/27746:
#0: ffff88807968ac48 (mapping.invalidate_lock#3){.+.+}-{3:3}, at: filemap_invalidate_lock_shared include/linux/fs.h:857 [inline]
#0: ffff88807968ac48 (mapping.invalidate_lock#3){.+.+}-{3:3}, at: page_cache_ra_unbounded+0xf1/0x7c0 mm/readahead.c:225
3 locks held by syz-executor.5/28482:
#0: ffff888041bea0e0 (&type->s_umount_key#54){++++}-{3:3}, at: __super_lock fs/super.c:58 [inline]
#0: ffff888041bea0e0 (&type->s_umount_key#54){++++}-{3:3}, at: super_lock+0x27c/0x400 fs/super.c:120
#1: ffff888049c18a20 (&fs_info->ordered_operations_mutex){+.+.}-{3:3}, at: btrfs_wait_ordered_roots+0x104/0x950 fs/btrfs/ordered-data.c:744
#2: ffff888044926850 (&root->ordered_extent_mutex){+.+.}-{3:3}, at: btrfs_wait_ordered_extents+0x1f3/0xe20 fs/btrfs/ordered-data.c:692
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 29 Comm: khungtaskd Not tainted 6.7.0-syzkaller-00562-g9f8413c4a66f #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/17/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+0x198/0x310 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+0xfaf/0xff0 kernel/hung_task.c:379
kthread+0x2d3/0x370 kernel/kthread.c:388
ret_from_fork+0x48/0x80 arch/x86/kernel/process.c:147
ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:242
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 20901 Comm: syz-executor.3 Not tainted 6.7.0-syzkaller-00562-g9f8413c4a66f #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/17/2023
RIP: 0010:match_held_lock+0x1/0xb0 kernel/locking/lockdep.c:5199
Code: 31 db 65 48 8b 04 25 28 00 00 00 48 3b 44 24 08 75 0d 48 89 d8 48 83 c4 10 5b 41 5e 41 5f c3 e8 a5 f9 ff ff 0f 1f 44 00 00 55 <53> bd 01 00 00 00 48 39 77 10 74 67 48 89 fb 81 7f 20 00 00 20 00
RSP: 0018:ffffc900036ff9f0 EFLAGS: 00000097
RAX: 0000000000000001 RBX: 0000000000000000 RCX: ffffc900036ffa03
RDX: 1ffff920006dff50 RSI: ffff8880490582b0 RDI: ffff888026738ac0
RBP: ffffc900036ffb30 R08: ffffffff8f00d7ef R09: 1ffffffff1e01afd
R10: dffffc0000000000 R11: fffffbfff1e01afe R12: 0000000000000000
R13: 0000000000000000 R14: ffff888026738ac0 R15: ffff888026738ac0
FS: 00007fe9dd9076c0(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000c0067d4000 CR3: 0000000042bec000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
find_held_lock kernel/locking/lockdep.c:5244 [inline]
__lock_release kernel/locking/lockdep.c:5429 [inline]
lock_release+0x24b/0x9c0 kernel/locking/lockdep.c:5774
__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:149 [inline]
_raw_spin_unlock_irqrestore+0x79/0x140 kernel/locking/spinlock.c:194
spin_unlock_irqrestore include/linux/spinlock.h:406 [inline]
prepare_to_wait_exclusive+0xcc/0x220 kernel/sched/wait.c:256
io_cqring_wait io_uring/io_uring.c:2628 [inline]
__do_sys_io_uring_enter io_uring/io_uring.c:3757 [inline]
__se_sys_io_uring_enter+0x17d3/0x2b30 io_uring/io_uring.c:3647
do_syscall_x64 arch/x86/entry/common.c:52 [inline]
do_syscall_64+0xf5/0x230 arch/x86/entry/common.c:83
entry_SYSCALL_64_after_hwframe+0x63/0x6b
RIP: 0033:0x7fe9dcc7cd29
Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 e1 20 00 00 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 b0 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fe9dd9070c8 EFLAGS: 00000246 ORIG_RAX: 00000000000001aa
RAX: ffffffffffffffda RBX: 00007fe9dcdabf80 RCX: 00007fe9dcc7cd29
RDX: 0000000000400000 RSI: 0000000000000000 RDI: 0000000000000004
RBP: 00007fe9dccc947a R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000000b R14: 00007fe9dcdabf80 R15: 00007ffff85d5f98