NILFS (loop2): segctord starting. Construction interval = 5 seconds, CP frequency < 30 seconds NILFS error (device loop2): nilfs_ifile_get_inode_block: bad inode number: 1 ====================================================== WARNING: possible circular locking dependency detected 4.19.211-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.2/8167 is trying to acquire lock: 00000000f7dff93d (&nilfs->ns_sem){++++}, at: nilfs_set_error fs/nilfs2/super.c:86 [inline] 00000000f7dff93d (&nilfs->ns_sem){++++}, at: __nilfs_error+0x195/0x401 fs/nilfs2/super.c:131 but task is already holding lock: 0000000048f2cdca (&dat_lock_key){.+.+}, at: __nilfs_read_inode fs/nilfs2/inode.c:482 [inline] 0000000048f2cdca (&dat_lock_key){.+.+}, at: nilfs_iget+0x1cf/0x860 fs/nilfs2/inode.c:592 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #1 (&dat_lock_key){.+.+}: nilfs_count_free_blocks+0x68/0x180 fs/nilfs2/the_nilfs.c:698 nilfs_set_log_cursor fs/nilfs2/super.c:237 [inline] nilfs_cleanup_super+0x133/0x490 fs/nilfs2/super.c:319 load_nilfs+0x79f/0x11f0 fs/nilfs2/the_nilfs.c:317 nilfs_fill_super fs/nilfs2/super.c:1063 [inline] nilfs_mount+0x9b5/0xe70 fs/nilfs2/super.c:1321 mount_fs+0xa3/0x310 fs/super.c:1261 vfs_kern_mount.part.0+0x68/0x470 fs/namespace.c:961 vfs_kern_mount fs/namespace.c:951 [inline] do_new_mount fs/namespace.c:2492 [inline] do_mount+0x115c/0x2f50 fs/namespace.c:2822 ksys_mount+0xcf/0x130 fs/namespace.c:3038 __do_sys_mount fs/namespace.c:3052 [inline] __se_sys_mount fs/namespace.c:3049 [inline] __x64_sys_mount+0xba/0x150 fs/namespace.c:3049 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe -> #0 (&nilfs->ns_sem){++++}: down_write+0x34/0x90 kernel/locking/rwsem.c:70 nilfs_set_error fs/nilfs2/super.c:86 [inline] __nilfs_error+0x195/0x401 fs/nilfs2/super.c:131 nilfs_ifile_get_inode_block+0x12a/0x170 fs/nilfs2/ifile.c:139 __nilfs_read_inode fs/nilfs2/inode.c:483 [inline] nilfs_iget+0x204/0x860 fs/nilfs2/inode.c:592 nilfs_lookup fs/nilfs2/namei.c:63 [inline] nilfs_lookup+0xfd/0x130 fs/nilfs2/namei.c:54 lookup_open+0x698/0x1a20 fs/namei.c:3214 do_last fs/namei.c:3327 [inline] path_openat+0x1804/0x2df0 fs/namei.c:3537 do_filp_open+0x18c/0x3f0 fs/namei.c:3567 do_sys_open+0x3b3/0x520 fs/open.c:1085 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe other info that might help us debug this: Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(&dat_lock_key); lock(&nilfs->ns_sem); lock(&dat_lock_key); lock(&nilfs->ns_sem); *** DEADLOCK *** 2 locks held by syz-executor.2/8167: #0: 00000000dbf2fb00 (&type->i_mutex_dir_key#9){++++}, at: inode_lock_shared include/linux/fs.h:758 [inline] #0: 00000000dbf2fb00 (&type->i_mutex_dir_key#9){++++}, at: do_last fs/namei.c:3326 [inline] #0: 00000000dbf2fb00 (&type->i_mutex_dir_key#9){++++}, at: path_openat+0x17ec/0x2df0 fs/namei.c:3537 #1: 0000000048f2cdca (&dat_lock_key){.+.+}, at: __nilfs_read_inode fs/nilfs2/inode.c:482 [inline] #1: 0000000048f2cdca (&dat_lock_key){.+.+}, at: nilfs_iget+0x1cf/0x860 fs/nilfs2/inode.c:592 stack backtrace: CPU: 0 PID: 8167 Comm: syz-executor.2 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 print_circular_bug.constprop.0.cold+0x2d7/0x41e kernel/locking/lockdep.c:1222 check_prev_add kernel/locking/lockdep.c:1866 [inline] check_prevs_add kernel/locking/lockdep.c:1979 [inline] validate_chain kernel/locking/lockdep.c:2420 [inline] __lock_acquire+0x30c9/0x3ff0 kernel/locking/lockdep.c:3416 lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3908 down_write+0x34/0x90 kernel/locking/rwsem.c:70 nilfs_set_error fs/nilfs2/super.c:86 [inline] __nilfs_error+0x195/0x401 fs/nilfs2/super.c:131 nilfs_ifile_get_inode_block+0x12a/0x170 fs/nilfs2/ifile.c:139 __nilfs_read_inode fs/nilfs2/inode.c:483 [inline] nilfs_iget+0x204/0x860 fs/nilfs2/inode.c:592 nilfs_lookup fs/nilfs2/namei.c:63 [inline] nilfs_lookup+0xfd/0x130 fs/nilfs2/namei.c:54 lookup_open+0x698/0x1a20 fs/namei.c:3214 do_last fs/namei.c:3327 [inline] path_openat+0x1804/0x2df0 fs/namei.c:3537 do_filp_open+0x18c/0x3f0 fs/namei.c:3567 do_sys_open+0x3b3/0x520 fs/open.c:1085 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7fcffcadd0a9 Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 f1 19 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 b8 ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007fcffb04f168 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007fcffcbfcf80 RCX: 00007fcffcadd0a9 RDX: 0000000000000000 RSI: 00000000200003c0 RDI: 0000000000000004 RBP: 00007fcffcb38ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffdc80a05ef R14: 00007fcffb04f300 R15: 0000000000022000 Remounting filesystem read-only audit: type=1800 audit(1672745928.371:723): pid=8215 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14132 res=0 audit: type=1800 audit(1672745928.381:724): pid=8218 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14649 res=0 NILFS (loop2): segctord starting. Construction interval = 5 seconds, CP frequency < 30 seconds audit: type=1804 audit(1672745928.401:725): pid=8215 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir876999642/syzkaller.JUOcli/437/file0" dev="sda1" ino=14132 res=1 NILFS error (device loop2): nilfs_ifile_get_inode_block: bad inode number: 1 Remounting filesystem read-only audit: type=1804 audit(1672745928.401:726): pid=8218 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir1711126840/syzkaller.Jnfa8q/459/file0" dev="sda1" ino=14649 res=1 audit: type=1800 audit(1672745928.411:727): pid=8220 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14650 res=0 audit: type=1804 audit(1672745928.431:728): pid=8220 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir1740708070/syzkaller.5tYgyU/428/file0" dev="sda1" ino=14650 res=1 audit: type=1800 audit(1672745930.101:729): pid=8241 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14651 res=0 audit: type=1800 audit(1672745930.111:730): pid=8243 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14652 res=0 NILFS (loop2): segctord starting. Construction interval = 5 seconds, CP frequency < 30 seconds NILFS error (device loop2): nilfs_ifile_get_inode_block: bad inode number: 1 Remounting filesystem read-only IPVS: ftp: loaded support on port[0] = 21 audit: type=1804 audit(1672745930.131:731): pid=8241 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir1711126840/syzkaller.Jnfa8q/460/file0" dev="sda1" ino=14651 res=1 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. NILFS (loop2): segctord starting. Construction interval = 5 seconds, CP frequency < 30 seconds NILFS error (device loop2): nilfs_ifile_get_inode_block: bad inode number: 1 audit: type=1804 audit(1672745930.131:732): pid=8243 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir1740708070/syzkaller.5tYgyU/429/file0" dev="sda1" ino=14652 res=1 Remounting filesystem read-only audit: type=1800 audit(1672745930.141:733): pid=8245 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14653 res=0 audit: type=1804 audit(1672745930.151:734): pid=8245 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir876999642/syzkaller.JUOcli/438/file0" dev="sda1" ino=14653 res=1 audit: type=1800 audit(1672745930.701:735): pid=8269 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14655 res=0 audit: type=1804 audit(1672745930.741:736): pid=8269 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir1740708070/syzkaller.5tYgyU/430/file0" dev="sda1" ino=14655 res=1 audit: type=1800 audit(1672745931.001:737): pid=8262 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14656 res=0 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. IPVS: ftp: loaded support on port[0] = 21 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21