syzbot


INFO: task hung in find_inode

Status: auto-obsoleted due to no activity on 2024/07/20 19:43
Subsystems: btrfs
[Documentation on labels]
First crash: 282d, last: 282d
Similar bugs (1)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in find_inode (2) nilfs C error 2 9d18h 19d 0/28 upstream: reported C repro on 2025/01/09 04:54

Sample crash report:
INFO: task syz-executor.0:6706 blocked for more than 143 seconds.
      Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:24088 pid:6706  tgid:6705  ppid:6266   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5409 [inline]
 __schedule+0x1796/0x4a00 kernel/sched/core.c:6746
 __schedule_loop kernel/sched/core.c:6823 [inline]
 schedule+0x14b/0x320 kernel/sched/core.c:6838
 __wait_on_freeing_inode fs/inode.c:2246 [inline]
 find_inode+0x305/0x440 fs/inode.c:908
 ilookup5_nowait fs/inode.c:1422 [inline]
 ilookup5+0xa1/0x1f0 fs/inode.c:1451
 iget5_locked+0x37/0x280 fs/inode.c:1232
 btrfs_iget_locked fs/btrfs/inode.c:5607 [inline]
 btrfs_iget_path+0x14f/0x14b0 fs/btrfs/inode.c:5624
 add_conflicting_inode fs/btrfs/tree-log.c:5648 [inline]
 copy_inode_items_to_log fs/btrfs/tree-log.c:5919 [inline]
 btrfs_log_inode+0x2015/0x4630 fs/btrfs/tree-log.c:6583
 btrfs_log_inode_parent+0xab7/0x1100 fs/btrfs/tree-log.c:7070
 btrfs_log_new_name+0x6f9/0xb30 fs/btrfs/tree-log.c:7576
 btrfs_rename fs/btrfs/inode.c:9259 [inline]
 btrfs_rename2+0x22fa/0x2b90 fs/btrfs/inode.c:9303
 vfs_rename+0xbdb/0xf00 fs/namei.c:4880
 do_renameat2+0xd94/0x13f0 fs/namei.c:5037
 __do_sys_renameat fs/namei.c:5078 [inline]
 __se_sys_renameat fs/namei.c:5075 [inline]
 __x64_sys_renameat+0xbc/0xd0 fs/namei.c:5075
 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:0x7f3d2d67dea9
RSP: 002b:00007f3d2e49a0c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000108
RAX: ffffffffffffffda RBX: 00007f3d2d7abf80 RCX: 00007f3d2d67dea9
RDX: 0000000000000005 RSI: 0000000020000100 RDI: 0000000000000005
RBP: 00007f3d2d6ca4a4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000020000140 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000000b R14: 00007f3d2d7abf80 R15: 00007ffc80e18648
 </TASK>
INFO: task syz-executor.0:6790 blocked for more than 144 seconds.
      Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:24408 pid:6790  tgid:6705  ppid:6266   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5409 [inline]
 __schedule+0x1796/0x4a00 kernel/sched/core.c:6746
 __schedule_loop kernel/sched/core.c:6823 [inline]
 schedule+0x14b/0x320 kernel/sched/core.c:6838
 btrfs_commit_transaction+0x9c0/0x3740 fs/btrfs/transaction.c:2312
 flush_space+0x9b0/0xd20 fs/btrfs/space-info.c:817
 priority_reclaim_metadata_space+0xe7/0x5a0 fs/btrfs/space-info.c:1411
 __reserve_bytes+0xb75/0x18d0 fs/btrfs/space-info.c:1756
 btrfs_reserve_metadata_bytes+0x2e/0x110 fs/btrfs/space-info.c:1782
 btrfs_block_rsv_refill+0xd8/0x1b0 fs/btrfs/block-rsv.c:264
 evict_refill_and_join+0xcf/0x210 fs/btrfs/inode.c:5241
 btrfs_evict_inode+0xa23/0x1040 fs/btrfs/inode.c:5336
 evict+0x2a8/0x630 fs/inode.c:667
 do_unlinkat+0x512/0x830 fs/namei.c:4406
 __do_sys_unlinkat fs/namei.c:4442 [inline]
 __se_sys_unlinkat fs/namei.c:4435 [inline]
 __x64_sys_unlinkat+0xce/0xf0 fs/namei.c:4435
 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:0x7f3d2d67dea9
RSP: 002b:00007f3d2e4790c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000107
RAX: ffffffffffffffda RBX: 00007f3d2d7ac050 RCX: 00007f3d2d67dea9
RDX: 0000000000000000 RSI: 0000000020000040 RDI: 0000000000000004
RBP: 00007f3d2d6ca4a4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000006e R14: 00007f3d2d7ac050 R15: 00007ffc80e18648
 </TASK>
INFO: task syz-executor.0:6791 blocked for more than 145 seconds.
      Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:23704 pid:6791  tgid:6705  ppid:6266   flags:0x00000006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5409 [inline]
 __schedule+0x1796/0x4a00 kernel/sched/core.c:6746
 __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
 rwsem_down_write_slowpath+0xeeb/0x13b0 kernel/locking/rwsem.c:1178
 __down_write_common+0x1af/0x200 kernel/locking/rwsem.c:1306
 inode_lock include/linux/fs.h:795 [inline]
 btrfs_inode_lock fs/btrfs/inode.c:385 [inline]
 btrfs_get_dir_last_index fs/btrfs/inode.c:5850 [inline]
 btrfs_opendir+0x34/0x200 fs/btrfs/inode.c:5883
 do_dentry_open+0x907/0x15a0 fs/open.c:955
 do_open fs/namei.c:3642 [inline]
 path_openat+0x2860/0x3240 fs/namei.c:3799
 do_filp_open+0x235/0x490 fs/namei.c:3826
 do_sys_openat2+0x13e/0x1d0 fs/open.c:1406
 do_sys_open fs/open.c:1421 [inline]
 __do_sys_openat fs/open.c:1437 [inline]
 __se_sys_openat fs/open.c:1432 [inline]
 __x64_sys_openat+0x247/0x2a0 fs/open.c:1432
 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:0x7f3d2d67dea9
RSP: 002b:00007f3d2e4580c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 00007f3d2d7ac120 RCX: 00007f3d2d67dea9
RDX: 0000000000000000 RSI: 0000000020000080 RDI: ffffffffffffff9c
RBP: 00007f3d2d6ca4a4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000006e R14: 00007f3d2d7ac120 R15: 00007ffc80e18648
 </TASK>
INFO: task syz-executor.0:6802 blocked for more than 145 seconds.
      Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0  state:D stack:28920 pid:6802  tgid:6705  ppid:6266   flags:0x00000006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5409 [inline]
 __schedule+0x1796/0x4a00 kernel/sched/core.c:6746
 __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
 rwsem_down_write_slowpath+0xeeb/0x13b0 kernel/locking/rwsem.c:1178
 __down_write_common+0x1af/0x200 kernel/locking/rwsem.c:1306
 inode_lock_nested include/linux/fs.h:830 [inline]
 do_rmdir+0x263/0x580 fs/namei.c:4253
 __do_sys_unlinkat fs/namei.c:4441 [inline]
 __se_sys_unlinkat fs/namei.c:4435 [inline]
 __x64_sys_unlinkat+0xe0/0xf0 fs/namei.c:4435
 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:0x7f3d2d67dea9
RSP: 002b:00007f3d2e4370c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000107
RAX: ffffffffffffffda RBX: 00007f3d2d7ac1f0 RCX: 00007f3d2d67dea9
RDX: 0000000000000200 RSI: 0000000020000200 RDI: 0000000000000004
RBP: 00007f3d2d6ca4a4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000006e R14: 00007f3d2d7ac1f0 R15: 00007ffc80e18648
 </TASK>

Showing all locks held in the system:
1 lock held by khungtaskd/29:
 #0: ffffffff8e334d20 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire include/linux/rcupdate.h:329 [inline]
 #0: ffffffff8e334d20 (rcu_read_lock){....}-{1:2}, at: rcu_read_lock include/linux/rcupdate.h:781 [inline]
 #0: ffffffff8e334d20 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x2a0 kernel/locking/lockdep.c:6614
3 locks held by kworker/u8:6/1565:
2 locks held by getty/4833:
 #0: ffff88802f6e90a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243
 #1: ffffc9000312b2f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6b5/0x1e10 drivers/tty/n_tty.c:2201
6 locks held by syz-executor.0/6706:
 #0: ffff88801c70c420 (sb_writers#26){.+.+}-{0:0}, at: mnt_want_write+0x3f/0x90 fs/namespace.c:409
 #1: ffff888060426720 (&type->i_mutex_dir_key#10/1){+.+.}-{3:3}, at: inode_lock_nested include/linux/fs.h:830 [inline]
 #1: ffff888060426720 (&type->i_mutex_dir_key#10/1){+.+.}-{3:3}, at: lock_rename fs/namei.c:3066 [inline]
 #1: ffff888060426720 (&type->i_mutex_dir_key#10/1){+.+.}-{3:3}, at: do_renameat2+0x62c/0x13f0 fs/namei.c:4972
 #2: ffff88801c70c610 (sb_internal#2){.+.+}-{0:0}, at: btrfs_rename fs/btrfs/inode.c:9177 [inline]
 #2: ffff88801c70c610 (sb_internal#2){.+.+}-{0:0}, at: btrfs_rename2+0x177c/0x2b90 fs/btrfs/inode.c:9303
 #3: ffff88807adb2380 (btrfs_trans_num_writers){++++}-{0:0}, at: join_transaction+0x405/0xcf0 fs/btrfs/transaction.c:290
 #4: ffff88807adb23a8 (btrfs_trans_num_extwriters){++++}-{0:0}, at: join_transaction+0x405/0xcf0 fs/btrfs/transaction.c:290
 #5: ffff888060426c68 (&ei->log_mutex){+.+.}-{3:3}, at: btrfs_log_inode+0x4ed/0x4630 fs/btrfs/tree-log.c:6472
2 locks held by syz-executor.0/6790:
 #0: ffff88801c70c420 (sb_writers#26){.+.+}-{0:0}, at: mnt_want_write+0x3f/0x90 fs/namespace.c:409
 #1: ffff88807adb2380 (btrfs_trans_num_writers){++++}-{0:0}, at: join_transaction+0x405/0xcf0 fs/btrfs/transaction.c:290
1 lock held by syz-executor.0/6791:
 #0: ffff888060426720 (&type->i_mutex_dir_key#10){++++}-{3:3}, at: inode_lock include/linux/fs.h:795 [inline]
 #0: ffff888060426720 (&type->i_mutex_dir_key#10){++++}-{3:3}, at: btrfs_inode_lock fs/btrfs/inode.c:385 [inline]
 #0: ffff888060426720 (&type->i_mutex_dir_key#10){++++}-{3:3}, at: btrfs_get_dir_last_index fs/btrfs/inode.c:5850 [inline]
 #0: ffff888060426720 (&type->i_mutex_dir_key#10){++++}-{3:3}, at: btrfs_opendir+0x34/0x200 fs/btrfs/inode.c:5883
2 locks held by syz-executor.0/6802:
 #0: ffff88801c70c420 (sb_writers#26){.+.+}-{0:0}, at: mnt_want_write+0x3f/0x90 fs/namespace.c:409
 #1: ffff888060426720 (&type->i_mutex_dir_key#10/1){+.+.}-{3:3}, at: inode_lock_nested include/linux/fs.h:830 [inline]
 #1: ffff888060426720 (&type->i_mutex_dir_key#10/1){+.+.}-{3:3}, at: do_rmdir+0x263/0x580 fs/namei.c:4253
1 lock held by btrfs-transacti/6780:
 #0: ffff88807adb07c0 (&fs_info->transaction_kthread_mutex){+.+.}-{3:3}, at: transaction_kthread+0x100/0x500 fs/btrfs/disk-io.c:1577
1 lock held by syz-executor.1/9272:
 #0: ffff88801c70c0e0 (&type->s_umount_key#65){++++}-{3:3}, at: __super_lock fs/super.c:58 [inline]
 #0: ffff88801c70c0e0 (&type->s_umount_key#65){++++}-{3:3}, at: super_lock+0x27c/0x400 fs/super.c:120
2 locks held by syz-executor.1/9477:
 #0: ffffffff8f593008 (rtnl_mutex){+.+.}-{3:3}, at: rtnl_lock net/core/rtnetlink.c:79 [inline]
 #0: ffffffff8f593008 (rtnl_mutex){+.+.}-{3:3}, at: rtnetlink_rcv_msg+0x842/0x10d0 net/core/rtnetlink.c:6592
 #1: ffffffff8e33a0b8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:291 [inline]
 #1: ffffffff8e33a0b8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x39a/0x820 kernel/rcu/tree_exp.h:939
1 lock held by syz-executor.3/9488:
 #0: ffff88802059e0e0 (&type->s_umount_key#67/1){+.+.}-{3:3}, at: alloc_super+0x221/0x9d0 fs/super.c:343
1 lock held by syz-executor.4/9491:
 #0: ffffffff8f593008 (rtnl_mutex){+.+.}-{3:3}, at: raw_bind+0x99/0x770 net/can/raw.c:463
1 lock held by syz-executor.4/9493:
 #0: ffffffff8f593008 (rtnl_mutex){+.+.}-{3:3}, at: dev_ioctl+0x86e/0x1340 net/core/dev_ioctl.c:811
1 lock held by syz-executor.4/9496:
 #0: ffffffff8f593008 (rtnl_mutex){+.+.}-{3:3}, at: dev_ioctl+0x86e/0x1340 net/core/dev_ioctl.c:811
2 locks held by syz-executor.2/9494:

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 29 Comm: khungtaskd Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
Call Trace:
 <TASK>
 __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+0x2f0/0x390 kernel/kthread.c:388
 ret_from_fork+0x4b/0x80 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 9494 Comm: syz-executor.2 Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
RIP: 0010:io_serial_in+0x76/0xb0 drivers/tty/serial/8250/8250_port.c:406
Code: b0 fb 62 fc 89 e9 41 d3 e6 48 83 c3 40 48 89 d8 48 c1 e8 03 42 80 3c 38 00 74 08 48 89 df e8 f1 0c c4 fc 44 03 33 44 89 f2 ec <0f> b6 c0 5b 41 5e 41 5f 5d c3 cc cc cc cc 89 e9 80 e1 07 38 c1 7c
RSP: 0018:ffffc9000bb27058 EFLAGS: 00000006
RAX: 1ffffffff2944005 RBX: ffffffff94a20760 RCX: 0000000000000000
RDX: 00000000000003f9 RSI: 0000000000007980 RDI: 0000000000007981
RBP: 0000000000000000 R08: ffffffff853304b6 R09: fffff52001764dec
R10: dffffc0000000000 R11: ffffffff85330470 R12: dffffc0000000000
R13: ffffffff8eb01d80 R14: 00000000000003f9 R15: dffffc0000000000
FS:  00007f116df0e6c0(0000) GS:ffff8880b9400000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000560d3d7ff0c8 CR3: 00000000562a8000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <TASK>
 serial_port_in include/linux/serial_core.h:675 [inline]
 serial8250_console_write+0x2c2/0x1770 drivers/tty/serial/8250/8250_port.c:3360
 console_emit_next_record kernel/printk/printk.c:2913 [inline]
 console_flush_all+0x865/0xfd0 kernel/printk/printk.c:2979
 console_unlock+0x13b/0x4d0 kernel/printk/printk.c:3048
 vprintk_emit+0x5a6/0x770 kernel/printk/printk.c:2348
 _printk+0xd5/0x120 kernel/printk/printk.c:2373
 device_list_add+0x12a8/0x1ba0
 btrfs_scan_one_device+0x7ec/0xaf0 fs/btrfs/volumes.c:1432
 btrfs_get_tree_super fs/btrfs/super.c:1811 [inline]
 btrfs_get_tree+0x30e/0x1920 fs/btrfs/super.c:2084
 vfs_get_tree+0x90/0x2a0 fs/super.c:1779
 fc_mount+0x1b/0xb0 fs/namespace.c:1125
 btrfs_get_tree_subvol fs/btrfs/super.c:2047 [inline]
 btrfs_get_tree+0x652/0x1920 fs/btrfs/super.c:2085
 vfs_get_tree+0x90/0x2a0 fs/super.c:1779
 do_new_mount+0x2be/0xb40 fs/namespace.c:3352
 do_mount fs/namespace.c:3692 [inline]
 __do_sys_mount fs/namespace.c:3898 [inline]
 __se_sys_mount+0x2d9/0x3c0 fs/namespace.c:3875
 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:0x7f116d27f5aa
Code: d8 64 89 02 48 c7 c0 ff ff ff ff eb a6 e8 de 09 00 00 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 49 89 ca b8 a5 00 00 00 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:00007f116df0def8 EFLAGS: 00000202 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00007f116df0df80 RCX: 00007f116d27f5aa
RDX: 0000000020000080 RSI: 0000000020000000 RDI: 00007f116df0df40
RBP: 0000000020000080 R08: 00007f116df0df80 R09: 0000000000000016
R10: 0000000000000016 R11: 0000000000000202 R12: 0000000020000000
R13: 00007f116df0df40 R14: 0000000000005104 R15: 0000000020000180
 </TASK>

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/04/21 19:36 upstream 3b68086599f8 af24b050 .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce-smack-root INFO: task hung in find_inode
* Struck through repros no longer work on HEAD.