INFO: task kworker/u9:5:5126 blocked for more than 144 seconds. Not tainted 6.9.0-syzkaller-08414-g7ee332c9f12b #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:kworker/u9:5 state:D stack:23600 pid:5126 tgid:5126 ppid:2 flags:0x00004000 Workqueue: bcachefs bch2_write_point_do_index_updates Call Trace: context_switch kernel/sched/core.c:5409 [inline] __schedule+0x17e8/0x4a50 kernel/sched/core.c:6746 __schedule_loop kernel/sched/core.c:6823 [inline] schedule+0x14b/0x320 kernel/sched/core.c:6838 six_lock_slowpath+0x660/0x16a0 fs/bcachefs/six.c:493 six_lock_ip_waiter+0x117/0x160 fs/bcachefs/six.c:550 __btree_node_lock_nopath fs/bcachefs/btree_locking.h:208 [inline] btree_node_lock_nopath+0xb6/0x160 fs/bcachefs/btree_locking.h:221 btree_node_lock fs/bcachefs/btree_locking.h:268 [inline] btree_path_lock_root fs/bcachefs/btree_iter.c:760 [inline] bch2_btree_path_traverse_one+0xc78/0x3250 fs/bcachefs/btree_iter.c:1178 bch2_extent_update+0x11b/0xbb0 fs/bcachefs/io_write.c:289 bch2_write_index_default fs/bcachefs/io_write.c:374 [inline] __bch2_write_index+0xee9/0x2190 fs/bcachefs/io_write.c:528 bch2_write_point_do_index_updates+0x32e/0x690 fs/bcachefs/io_write.c:637 process_one_work kernel/workqueue.c:3231 [inline] process_scheduled_works+0xa2e/0x1830 kernel/workqueue.c:3312 worker_thread+0x86d/0xd70 kernel/workqueue.c:3393 kthread+0x2f2/0x390 kernel/kthread.c:389 ret_from_fork+0x4d/0x80 arch/x86/kernel/process.c:147 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244 INFO: task kworker/0:0H:10954 blocked for more than 146 seconds. Not tainted 6.9.0-syzkaller-08414-g7ee332c9f12b #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:kworker/0:0H state:D stack:25592 pid:10954 tgid:10954 ppid:2 flags:0x00004000 Workqueue: bcachefs_btree_io btree_node_write_work Call Trace: context_switch kernel/sched/core.c:5409 [inline] __schedule+0x17e8/0x4a50 kernel/sched/core.c:6746 __schedule_loop kernel/sched/core.c:6823 [inline] schedule+0x14b/0x320 kernel/sched/core.c:6838 six_lock_slowpath+0x660/0x16a0 fs/bcachefs/six.c:493 six_lock_ip_waiter+0x117/0x160 fs/bcachefs/six.c:550 __btree_node_lock_nopath fs/bcachefs/btree_locking.h:208 [inline] __bch2_btree_node_lock_write+0x11c/0x3c0 fs/bcachefs/btree_locking.c:429 __btree_node_lock_write fs/bcachefs/btree_locking.h:298 [inline] bch2_btree_node_lock_write_nofail+0x24c/0x440 fs/bcachefs/btree_locking.c:443 __bch2_btree_node_update_key fs/bcachefs/btree_update_interior.c:2390 [inline] bch2_btree_node_update_key+0x16ff/0x3260 fs/bcachefs/btree_update_interior.c:2450 bch2_btree_node_update_key_get_iter+0x87a/0xab0 fs/bcachefs/btree_update_interior.c:2494 btree_node_write_work+0x8a2/0xe10 fs/bcachefs/btree_io.c:1870 process_one_work kernel/workqueue.c:3231 [inline] process_scheduled_works+0xa2e/0x1830 kernel/workqueue.c:3312 worker_thread+0x86d/0xd70 kernel/workqueue.c:3393 kthread+0x2f2/0x390 kernel/kthread.c:389 ret_from_fork+0x4d/0x80 arch/x86/kernel/process.c:147 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244 Showing all locks held in the system: 1 lock held by khungtaskd/30: #0: ffffffff8e3343a0 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire include/linux/rcupdate.h:329 [inline] #0: ffffffff8e3343a0 (rcu_read_lock){....}-{1:2}, at: rcu_read_lock include/linux/rcupdate.h:781 [inline] #0: ffffffff8e3343a0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x2a0 kernel/locking/lockdep.c:6614 2 locks held by getty/4848: #0: ffff88802bb450a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243 #1: ffffc900031332f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6b5/0x1e10 drivers/tty/n_tty.c:2201 5 locks held by kworker/u9:5/5126: #0: ffff888074299148 ((wq_completion)bcachefs){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3206 [inline] #0: ffff888074299148 ((wq_completion)bcachefs){+.+.}-{0:0}, at: process_scheduled_works+0x90a/0x1830 kernel/workqueue.c:3312 #1: ffffc900030cfd00 ((work_completion)(&wp->index_update_work)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3207 [inline] #1: ffffc900030cfd00 ((work_completion)(&wp->index_update_work)){+.+.}-{0:0}, at: process_scheduled_works+0x945/0x1830 kernel/workqueue.c:3312 #2: ffff888047f04250 (&c->btree_trans_barrier){.+.+}-{0:0}, at: srcu_lock_acquire include/linux/srcu.h:116 [inline] #2: ffff888047f04250 (&c->btree_trans_barrier){.+.+}-{0:0}, at: srcu_read_lock include/linux/srcu.h:215 [inline] #2: ffff888047f04250 (&c->btree_trans_barrier){.+.+}-{0:0}, at: __bch2_trans_get+0x911/0xd30 fs/bcachefs/btree_iter.c:3069 #3: ffff88802c637870 (&dev->mutex){....}-{3:3}, at: six_trylock_type fs/bcachefs/six.h:207 [inline] #3: ffff88802c637870 (&dev->mutex){....}-{3:3}, at: btree_node_lock fs/bcachefs/btree_locking.h:266 [inline] #3: ffff88802c637870 (&dev->mutex){....}-{3:3}, at: btree_path_lock_root fs/bcachefs/btree_iter.c:760 [inline] #3: ffff88802c637870 (&dev->mutex){....}-{3:3}, at: bch2_btree_path_traverse_one+0xa85/0x3250 fs/bcachefs/btree_iter.c:1178 #4: ffff888065bdc070 (&dev->mutex){....}-{3:3}, at: bch2_trans_iter_init fs/bcachefs/btree_iter.h:487 [inline] #4: ffff888065bdc070 (&dev->mutex){....}-{3:3}, at: bch2_write_index_default fs/bcachefs/io_write.c:369 [inline] #4: ffff888065bdc070 (&dev->mutex){....}-{3:3}, at: __bch2_write_index+0xbc3/0x2190 fs/bcachefs/io_write.c:528 2 locks held by kworker/0:5/5181: #0: ffff8880b943e658 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140 kernel/sched/core.c:559 #1: ffff8880b9428948 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x441/0x770 kernel/sched/psi.c:988 4 locks held by kworker/0:0H/10954: #0: ffff88805bbbd548 ((wq_completion)bcachefs_btree_io){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3206 [inline] #0: ffff88805bbbd548 ((wq_completion)bcachefs_btree_io){+.+.}-{0:0}, at: process_scheduled_works+0x90a/0x1830 kernel/workqueue.c:3312 #1: ffffc900050dfd00 ((work_completion)(&wb->work)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3207 [inline] #1: ffffc900050dfd00 ((work_completion)(&wb->work)){+.+.}-{0:0}, at: process_scheduled_works+0x945/0x1830 kernel/workqueue.c:3312 #2: ffff888047f04250 (&c->btree_trans_barrier){.+.+}-{0:0}, at: srcu_lock_acquire include/linux/srcu.h:116 [inline] #2: ffff888047f04250 (&c->btree_trans_barrier){.+.+}-{0:0}, at: srcu_read_lock include/linux/srcu.h:215 [inline] #2: ffff888047f04250 (&c->btree_trans_barrier){.+.+}-{0:0}, at: __bch2_trans_get+0x911/0xd30 fs/bcachefs/btree_iter.c:3069 #3: ffff888065bdc070 (&dev->mutex){....}-{3:3}, at: bch2_btree_node_update_key_get_iter+0x198/0xab0 fs/bcachefs/btree_update_interior.c:2474 4 locks held by syz-executor.2/12125: 2 locks held by syz-executor.5/14644: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 #1: ffffffff8e339778 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:323 [inline] #1: ffffffff8e339778 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x451/0x830 kernel/rcu/tree_exp.h:939 1 lock held by syz-executor.5/14646: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 1 lock held by syz-executor.2/14648: #0: ffff8880778cfa10 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: inode_lock include/linux/fs.h:791 [inline] #0: ffff8880778cfa10 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: __sock_release net/socket.c:658 [inline] #0: ffff8880778cfa10 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: sock_close+0x90/0x240 net/socket.c:1421 1 lock held by syz-executor.2/14649: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 1 lock held by syz-executor.2/14650: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 1 lock held by syz-executor.2/14651: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 1 lock held by syz-executor.1/14654: #0: ffff88805b435610 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: inode_lock include/linux/fs.h:791 [inline] #0: ffff88805b435610 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: __sock_release net/socket.c:658 [inline] #0: ffff88805b435610 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: sock_close+0x90/0x240 net/socket.c:1421 1 lock held by syz-executor.1/14655: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 1 lock held by syz-executor.1/14659: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 1 lock held by syz-executor.1/14661: #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline] #0: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592 2 locks held by syz-executor.4/14657: #0: ffffffff8f646c50 (cb_lock){++++}-{3:3}, at: genl_rcv+0x19/0x40 net/netlink/genetlink.c:1218 #1: ffffffff8f5e0608 (rtnl_mutex){+.+.}-{3:3}, at: nl80211_pre_doit+0x5f/0x8b0 net/wireless/nl80211.c:16405 3 locks held by syz-executor.3/14660: #0: ffff88805b435c10 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: inode_lock include/linux/fs.h:791 [inline] #0: ffff88805b435c10 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: __sock_release net/socket.c:658 [inline] #0: ffff88805b435c10 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: sock_close+0x90/0x240 net/socket.c:1421 #1: ffff8880204d8258 (sk_lock-AF_PPPOX){+.+.}-{0:0}, at: lock_sock include/net/sock.h:1595 [inline] #1: ffff8880204d8258 (sk_lock-AF_PPPOX){+.+.}-{0:0}, at: pppol2tp_release+0x51/0x350 net/l2tp/l2tp_ppp.c:434 #2: ffffffff8e339778 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:323 [inline] #2: ffffffff8e339778 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x451/0x830 kernel/rcu/tree_exp.h:939 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 30 Comm: khungtaskd Not tainted 6.9.0-syzkaller-08414-g7ee332c9f12b #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024 Call Trace: __dump_stack lib/dump_stack.c:88 [inline] dump_stack_lvl+0x241/0x360 lib/dump_stack.c:114 nmi_cpu_backtrace+0x49c/0x4d0 lib/nmi_backtrace.c:113 nmi_trigger_cpumask_backtrace+0x198/0x320 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:160 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:223 [inline] watchdog+0xfde/0x1020 kernel/hung_task.c:380 kthread+0x2f2/0x390 kernel/kthread.c:389 ret_from_fork+0x4d/0x80 arch/x86/kernel/process.c:147 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 14663 Comm: syz-executor.0 Not tainted 6.9.0-syzkaller-08414-g7ee332c9f12b #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024 RIP: 0010:__bfs kernel/locking/lockdep.c:1799 [inline] RIP: 0010:__bfs_backwards kernel/locking/lockdep.c:1844 [inline] RIP: 0010:check_irq_usage kernel/locking/lockdep.c:2804 [inline] RIP: 0010:check_prev_add kernel/locking/lockdep.c:3138 [inline] RIP: 0010:check_prevs_add kernel/locking/lockdep.c:3253 [inline] RIP: 0010:validate_chain+0x1f9e/0x58e0 kernel/locking/lockdep.c:3869 Code: 80 3c 20 00 74 08 4c 89 f7 e8 be fc 87 00 48 09 9c 24 90 00 00 00 4d 8b 3e 4d 39 f7 0f 84 6e fc ff ff 41 b4 01 eb 0c 4d 8b 3f <4d> 39 f7 0f 84 5d fc ff ff 49 8d 5f 30 48 89 d8 48 c1 e8 03 48 b9 RSP: 0018:ffffc90011cb6d00 EFLAGS: 00000046 RAX: 1ffffffff283a637 RBX: ffffffff941d31e8 RCX: dffffc0000000000 RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffffffff92c9ee48 RBP: ffffc90011cb7000 R08: ffffffff92f9d587 R09: 1ffffffff25f3ab0 R10: dffffc0000000000 R11: fffffbfff25f3ab1 R12: 0000000000000000 R13: ffffffff940b9fc8 R14: ffffffff92c9ee18 R15: ffffffff941d56e8 FS: 00007f424253d6c0(0000) GS:ffff8880b9400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 0000000020000080 CR3: 000000007c306000 CR4: 0000000000350ef0 Call Trace: __lock_acquire+0x1346/0x1fd0 kernel/locking/lockdep.c:5137 lock_acquire+0x1ed/0x550 kernel/locking/lockdep.c:5754 do_write_seqcount_begin_nested include/linux/seqlock.h:469 [inline] do_write_seqcount_begin include/linux/seqlock.h:495 [inline] psi_account_irqtime+0x1b2/0x5d0 kernel/sched/psi.c:1016 update_rq_clock_task kernel/sched/core.c:726 [inline] update_rq_clock+0x22b/0x8a0 kernel/sched/core.c:770 __schedule+0x432/0x4a50 kernel/sched/core.c:6659 __schedule_loop kernel/sched/core.c:6823 [inline] schedule+0x14b/0x320 kernel/sched/core.c:6838 schedule_preempt_disabled+0x13/0x30 kernel/sched/core.c:6895 __mutex_lock_common kernel/locking/mutex.c:684 [inline] __mutex_lock+0x6a4/0xd70 kernel/locking/mutex.c:752 text_poke_copy+0x29/0xd0 arch/x86/kernel/alternative.c:2025 bpf_arch_text_copy+0x25/0x40 arch/x86/net/bpf_jit_comp.c:3417 bpf_jit_binary_pack_finalize+0x3d/0xf0 kernel/bpf/core.c:1183 bpf_int_jit_compile+0xb3c/0x15e0 arch/x86/net/bpf_jit_comp.c:3366 bpf_prog_select_runtime+0x93e/0xc90 kernel/bpf/core.c:2410 bpf_migrate_filter net/core/filter.c:1306 [inline] bpf_prepare_filter+0x1288/0x1580 net/core/filter.c:1354 sk_attach_filter+0x22/0x140 net/core/filter.c:1538 sk_setsockopt+0x1f76/0x3380 net/core/sock.c:1372 do_sock_setsockopt+0x2fb/0x720 net/socket.c:2307 __sys_setsockopt+0x1ae/0x250 net/socket.c:2334 __do_sys_setsockopt net/socket.c:2343 [inline] __se_sys_setsockopt net/socket.c:2340 [inline] __x64_sys_setsockopt+0xb5/0xd0 net/socket.c:2340 do_syscall_x64 arch/x86/entry/common.c:52 [inline] do_syscall_64+0xf5/0x240 arch/x86/entry/common.c:83 entry_SYSCALL_64_after_hwframe+0x77/0x7f RIP: 0033:0x7f424187cee9 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:00007f424253d0c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 RAX: ffffffffffffffda RBX: 00007f42419abf80 RCX: 00007f424187cee9 RDX: 000000000000001a RSI: 0000000000000001 RDI: 0000000000000004 RBP: 00007f42418c949e R08: 0000000000000010 R09: 0000000000000000 R10: 00000000200000c0 R11: 0000000000000246 R12: 0000000000000000 R13: 000000000000000b R14: 00007f42419abf80 R15: 00007ffcaad60488