UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) ====================================================== WARNING: possible circular locking dependency detected 4.14.305-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.2/10144 is trying to acquire lock: (&ovl_i_mutex_dir_key[depth]){++++}, at: [] iterate_dir+0x387/0x5e0 fs/readdir.c:43 but task is already holding lock: (sb_writers#3){.+.+}, at: [] sb_start_write include/linux/fs.h:1551 [inline] (sb_writers#3){.+.+}, at: [] mnt_want_write+0x3a/0xb0 fs/namespace.c:386 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: overlayfs: fs on 'file0' does not support file handles, falling back to index=off. -> #1 (sb_writers#3){.+.+}: percpu_down_read_preempt_disable include/linux/percpu-rwsem.h:36 [inline] percpu_down_read include/linux/percpu-rwsem.h:59 [inline] __sb_start_write+0x64/0x260 fs/super.c:1342 sb_start_write include/linux/fs.h:1551 [inline] mnt_want_write+0x3a/0xb0 fs/namespace.c:386 ovl_create_object+0x75/0x1d0 fs/overlayfs/dir.c:538 lookup_open+0x77a/0x1750 fs/namei.c:3241 do_last fs/namei.c:3334 [inline] path_openat+0xe08/0x2970 fs/namei.c:3571 do_filp_open+0x179/0x3c0 fs/namei.c:3605 overlayfs: fs on './file0' does not support file handles, falling back to index=off. do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 -> #0 (&ovl_i_mutex_dir_key[depth]){++++}: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 down_write_killable+0x37/0xb0 kernel/locking/rwsem.c:68 iterate_dir+0x387/0x5e0 fs/readdir.c:43 ovl_dir_read fs/overlayfs/readdir.c:306 [inline] ovl_dir_read_merged+0x2c5/0x430 fs/overlayfs/readdir.c:365 ovl_check_empty_dir+0x6e/0x200 fs/overlayfs/readdir.c:870 ovl_check_empty_and_clear+0x72/0xe0 fs/overlayfs/dir.c:306 Bearer rejected, not supported in standalone mode ovl_rename+0x57d/0xe50 fs/overlayfs/dir.c:959 vfs_rename+0x560/0x1820 fs/namei.c:4498 SYSC_renameat2 fs/namei.c:4646 [inline] SyS_renameat2+0x95b/0xad0 fs/namei.c:4535 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 other info that might help us debug this: Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(sb_writers#3); lock(&ovl_i_mutex_dir_key[depth]); lock(sb_writers#3); lock(&ovl_i_mutex_dir_key[depth]); *** DEADLOCK *** 6 locks held by syz-executor.2/10144: #0: (sb_writers#16){.+.+}, at: [] sb_start_write include/linux/fs.h:1551 [inline] #0: (sb_writers#16){.+.+}, at: [] mnt_want_write+0x3a/0xb0 fs/namespace.c:386 #1: (&type->s_vfs_rename_key#2){+.+.}, at: [] lock_rename+0x54/0x280 fs/namei.c:2889 #2: (&ovl_i_mutex_dir_key[depth]#2/1){+.+.}, at: [] inode_lock_nested include/linux/fs.h:754 [inline] #2: (&ovl_i_mutex_dir_key[depth]#2/1){+.+.}, at: [] lock_rename+0x132/0x280 fs/namei.c:2900 #3: (&ovl_i_mutex_dir_key[depth]#2 audit: type=1804 audit(1676765533.869:40): pid=10135 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir2715177775/syzkaller.fKgrXy/28/bus" dev="sda1" ino=14028 res=1 /2){+.+.}, at: [] inode_lock_nested include/linux/fs.h:754 [inline] /2){+.+.}, at: [] lock_rename+0x166/0x280 fs/namei.c:2901 #4: audit: type=1800 audit(1676765533.869:41): pid=10135 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="bus" dev="sda1" ino=14028 res=0 (&ovl_i_mutex_dir_key[depth]#2){++++}, at: [] inode_lock include/linux/fs.h:719 [inline] (&ovl_i_mutex_dir_key[depth]#2){++++}, at: [] vfs_rename+0xbd8/0x1820 fs/namei.c:4472 #5: (sb_writers#3){.+.+}, at: [] sb_start_write include/linux/fs.h:1551 [inline] #5: (sb_writers#3){.+.+}, at: [] mnt_want_write+0x3a/0xb0 fs/namespace.c:386 stack backtrace: CPU: 0 PID: 10144 Comm: syz-executor.2 Not tainted 4.14.305-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 print_circular_bug.constprop.0.cold+0x2d7/0x41e kernel/locking/lockdep.c:1258 check_prev_add kernel/locking/lockdep.c:1905 [inline] check_prevs_add kernel/locking/lockdep.c:2022 [inline] validate_chain kernel/locking/lockdep.c:2464 [inline] __lock_acquire+0x2e0e/0x3f20 kernel/locking/lockdep.c:3491 audit: type=1804 audit(1676765534.989:42): pid=10162 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir2715177775/syzkaller.fKgrXy/29/bus" dev="sda1" ino=14059 res=1 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 down_write_killable+0x37/0xb0 kernel/locking/rwsem.c:68 iterate_dir+0x387/0x5e0 fs/readdir.c:43 ovl_dir_read fs/overlayfs/readdir.c:306 [inline] ovl_dir_read_merged+0x2c5/0x430 fs/overlayfs/readdir.c:365 ovl_check_empty_dir+0x6e/0x200 fs/overlayfs/readdir.c:870 ovl_check_empty_and_clear+0x72/0xe0 fs/overlayfs/dir.c:306 ovl_rename+0x57d/0xe50 fs/overlayfs/dir.c:959 vfs_rename+0x560/0x1820 fs/namei.c:4498 SYSC_renameat2 fs/namei.c:4646 [inline] SyS_renameat2+0x95b/0xad0 fs/namei.c:4535 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f0a808810f9 RSP: 002b:00007f0a7edf3168 EFLAGS: 00000246 ORIG_RAX: 0000000000000052 RAX: ffffffffffffffda RBX: 00007f0a809a0f80 RCX: 00007f0a808810f9 RDX: 0000000000000000 RSI: 0000000020000440 RDI: 0000000020000100 RBP: 00007f0a808dcae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff127afa2f R14: 00007f0a7edf3300 R15: 0000000000022000 audit: type=1800 audit(1676765534.989:43): pid=10162 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="bus" dev="sda1" ino=14059 res=0 overlayfs: fs on 'file0' does not support file handles, falling back to index=off. audit: type=1804 audit(1676765536.509:44): pid=10190 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir2715177775/syzkaller.fKgrXy/30/bus" dev="sda1" ino=14047 res=1 overlayfs: fs on './file0' does not support file handles, falling back to index=off. audit: type=1800 audit(1676765536.509:45): pid=10190 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="bus" dev="sda1" ino=14047 res=0 overlayfs: fs on './file0' does not support file handles, falling back to index=off. overlayfs: fs on 'file0' does not support file handles, falling back to index=off. Bearer rejected, not supported in standalone mode overlayfs: fs on 'file0' does not support file handles, falling back to index=off. overlayfs: fs on './file0' does not support file handles, falling back to index=off. overlayfs: fs on 'file0' does not support file handles, falling back to index=off. overlayfs: fs on './file0' does not support file handles, falling back to index=off. Bearer rejected, not supported in standalone mode overlayfs: fs on 'file0' does not support file handles, falling back to index=off. overlayfs: fs on './file0' does not support file handles, falling back to index=off. Bearer rejected, not supported in standalone mode Bearer rejected, not supported in standalone mode overlayfs: fs on 'file0' does not support file handles, falling back to index=off. kauditd_printk_skb: 8 callbacks suppressed audit: type=1804 audit(1676765539.639:54): pid=10250 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.4" name="/root/syzkaller-testdir3759780930/syzkaller.HEQDFf/31/bus" dev="sda1" ino=14023 res=1 overlayfs: fs on './file0' does not support file handles, falling back to index=off. Bearer rejected, not supported in standalone mode audit: type=1800 audit(1676765539.639:55): pid=10250 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="sda1" ino=14023 res=0 overlayfs: fs on 'file0' does not support file handles, falling back to index=off. overlayfs: fs on 'file0' does not support file handles, falling back to index=off. overlayfs: fs on './file0' does not support file handles, falling back to index=off. overlayfs: fs on './file0' does not support file handles, falling back to index=off. Bearer rejected, not supported in standalone mode Bearer rejected, not supported in standalone mode ntfs: (device loop5): parse_options(): The fmask option requires an argument. BTRFS: device fsid 24c7a497-3402-47dd-bef8-82358f5f30e0 devid 1 transid 8 /dev/loop0 BTRFS info (device loop0): unrecognized mount option 'ref_verify' BTRFS error (device loop0): open_ctree failed ntfs: (device loop5): parse_options(): The fmask option requires an argument. ntfs: (device loop5): parse_options(): The fmask option requires an argument. BTRFS info (device loop3): unrecognized mount option 'ref_verify' BTRFS error (device loop3): open_ctree failed BTRFS info (device loop3): unrecognized mount option 'ref_verify' BTRFS error (device loop3): open_ctree failed ntfs: (device loop5): parse_options(): The fmask option requires an argument. BTRFS info (device loop3): unrecognized mount option 'ref_verify' BTRFS error (device loop3): open_ctree failed BTRFS info (device loop0): unrecognized mount option 'ref_verify' BTRFS error (device loop0): open_ctree failed EXT4-fs (loop5): mounted filesystem without journal. Opts: ,errors=continue device erspan1 entered promiscuous mode audit: type=1804 audit(1676765543.089:56): pid=10490 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir1405032912/syzkaller.Bp0K2D/32/file0/bus" dev="loop5" ino=18 res=1 audit: type=1804 audit(1676765543.119:57): pid=10490 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.5" name="/root/syzkaller-testdir1405032912/syzkaller.Bp0K2D/32/file0/bus" dev="loop5" ino=18 res=1 BTRFS info (device loop0): unrecognized mount option 'ref_verify' device erspan1 entered promiscuous mode BTRFS error (device loop0): open_ctree failed BTRFS info (device loop0): unrecognized mount option 'ref_verify' EXT4-fs error (device loop5): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop5): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop5): This should not happen!! Data will be lost device erspan1 entered promiscuous mode BTRFS error (device loop0): open_ctree failed EXT4-fs (loop5): Total free blocks count 0 EXT4-fs (loop5): Free/Dirty block details EXT4-fs (loop5): free_blocks=2415919104 EXT4-fs (loop5): dirty_blocks=16 EXT4-fs (loop5): Block reservation details EXT4-fs (loop5): i_reserved_data_blocks=1 device erspan1 entered promiscuous mode EXT4-fs (loop5): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue audit: type=1804 audit(1676765543.749:58): pid=10554 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir1405032912/syzkaller.Bp0K2D/33/file0/bus" dev="loop5" ino=18 res=1 EXT4-fs (loop2): mounted filesystem without journal. Opts: ,errors=continue audit: type=1804 audit(1676765543.849:59): pid=10554 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.5" name="/root/syzkaller-testdir1405032912/syzkaller.Bp0K2D/33/file0/bus" dev="loop5" ino=18 res=1 EXT4-fs error (device loop5): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop5): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop4): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop5): This should not happen!! Data will be lost EXT4-fs (loop5): Total free blocks count 0 EXT4-fs (loop5): Free/Dirty block details EXT4-fs (loop5): free_blocks=2415919104 EXT4-fs (loop5): dirty_blocks=16 EXT4-fs (loop5): Block reservation details EXT4-fs (loop5): i_reserved_data_blocks=1 EXT4-fs error (device loop0): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop3): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop0): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop0): This should not happen!! Data will be lost EXT4-fs (loop0): Total free blocks count 0 EXT4-fs (loop0): Free/Dirty block details EXT4-fs (loop0): free_blocks=2415919104 EXT4-fs (loop0): dirty_blocks=16 EXT4-fs (loop0): Block reservation details EXT4-fs (loop0): i_reserved_data_blocks=1 EXT4-fs error (device loop3): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop3): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop3): This should not happen!! Data will be lost EXT4-fs (loop3): Total free blocks count 0 EXT4-fs (loop3): Free/Dirty block details EXT4-fs (loop3): free_blocks=2415919104 EXT4-fs (loop3): dirty_blocks=16 EXT4-fs (loop3): Block reservation details EXT4-fs (loop5): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop3): i_reserved_data_blocks=1 EXT4-fs error (device loop2): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs error (device loop4): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop2): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop4): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop2): This should not happen!! Data will be lost EXT4-fs (loop4): This should not happen!! Data will be lost EXT4-fs (loop2): Total free blocks count 0 EXT4-fs (loop4): Total free blocks count 0 EXT4-fs (loop2): Free/Dirty block details EXT4-fs (loop4): Free/Dirty block details EXT4-fs (loop2): free_blocks=2415919104 EXT4-fs (loop4): free_blocks=2415919104 EXT4-fs (loop2): dirty_blocks=16 EXT4-fs (loop4): dirty_blocks=16 EXT4-fs (loop2): Block reservation details EXT4-fs (loop4): Block reservation details EXT4-fs (loop2): i_reserved_data_blocks=1 EXT4-fs (loop4): i_reserved_data_blocks=1 EXT4-fs error (device loop5): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop5): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop5): This should not happen!! Data will be lost EXT4-fs (loop5): Total free blocks count 0 EXT4-fs (loop5): Free/Dirty block details EXT4-fs (loop5): free_blocks=2415919104 EXT4-fs (loop5): dirty_blocks=16 EXT4-fs (loop5): Block reservation details EXT4-fs (loop5): i_reserved_data_blocks=1 EXT4-fs (loop3): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop4): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop2): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop5): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs error (device loop3): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop3): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop3): This should not happen!! Data will be lost kauditd_printk_skb: 14 callbacks suppressed audit: type=1804 audit(1676765545.079:74): pid=10644 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir2934898695/syzkaller.YKgKLi/40/file0/bus" dev="loop0" ino=18 res=1 EXT4-fs (loop3): Total free blocks count 0 audit: type=1804 audit(1676765545.089:75): pid=10644 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.0" name="/root/syzkaller-testdir2934898695/syzkaller.YKgKLi/40/file0/bus" dev="loop0" ino=18 res=1 EXT4-fs (loop3): Free/Dirty block details audit: type=1804 audit(1676765545.149:76): pid=10658 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir1405032912/syzkaller.Bp0K2D/35/file0/bus" dev="loop5" ino=18 res=1 EXT4-fs (loop3): free_blocks=2415919104 EXT4-fs (loop3): dirty_blocks=16 EXT4-fs (loop3): Block reservation details audit: type=1804 audit(1676765545.189:77): pid=10658 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.5" name="/root/syzkaller-testdir1405032912/syzkaller.Bp0K2D/35/file0/bus" dev="loop5" ino=18 res=1 EXT4-fs (loop3): i_reserved_data_blocks=1 EXT4-fs error (device loop0): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs error (device loop4): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop0): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop4): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop0): This should not happen!! Data will be lost EXT4-fs (loop4): This should not happen!! Data will be lost EXT4-fs (loop0): Total free blocks count 0 EXT4-fs (loop4): Total free blocks count 0 EXT4-fs (loop0): Free/Dirty block details EXT4-fs (loop4): Free/Dirty block details EXT4-fs (loop0): free_blocks=2415919104 EXT4-fs (loop4): free_blocks=2415919104 EXT4-fs (loop0): dirty_blocks=16 EXT4-fs (loop4): dirty_blocks=16 EXT4-fs (loop4): Block reservation details EXT4-fs (loop0): Block reservation details EXT4-fs (loop0): i_reserved_data_blocks=1 EXT4-fs (loop4): i_reserved_data_blocks=1 EXT4-fs error (device loop5): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters audit: type=1804 audit(1676765545.419:78): pid=10659 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir3404269177/syzkaller.4ODKQO/50/file0/bus" dev="loop2" ino=18 res=1 EXT4-fs (loop5): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop5): This should not happen!! Data will be lost EXT4-fs (loop5): Total free blocks count 0 EXT4-fs (loop5): Free/Dirty block details EXT4-fs error (device loop2): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 25 vs 150994969 free clusters EXT4-fs (loop5): free_blocks=2415919104 audit: type=1804 audit(1676765545.419:79): pid=10659 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.2" name="/root/syzkaller-testdir3404269177/syzkaller.4ODKQO/50/file0/bus" dev="loop2" ino=18 res=1 EXT4-fs (loop5): dirty_blocks=16 EXT4-fs (loop2): Delayed block allocation failed for inode 18 at logical offset 0 with max blocks 1 with error 28 EXT4-fs (loop5): Block reservation details EXT4-fs (loop2): This should not happen!! Data will be lost EXT4-fs (loop5): i_reserved_data_blocks=1 EXT4-fs (loop2): Total free blocks count 0 EXT4-fs (loop2): Free/Dirty block details EXT4-fs (loop2): free_blocks=2415919104 EXT4-fs (loop2): dirty_blocks=16 EXT4-fs (loop2): Block reservation details EXT4-fs (loop2): i_reserved_data_blocks=1