overlayfs: fs on './file0' does not support file handles, falling back to index=off. ====================================================== WARNING: possible circular locking dependency detected 4.14.306-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.0/11099 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: -> #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 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 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.0/11099: #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/2){+.+.}, at: [] inode_lock_nested include/linux/fs.h:754 [inline] #3: (&ovl_i_mutex_dir_key[depth]#2/2){+.+.}, at: [] lock_rename+0x166/0x280 fs/namei.c:2901 #4: (&ovl_i_mutex_dir_key[depth]#2){++++}, at: [] inode_lock include/linux/fs.h:719 [inline] #4: (&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: 11099 Comm: syz-executor.0 Not tainted 4.14.306-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 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:0x7f53b75b80f9 RSP: 002b:00007f53b5b2a168 EFLAGS: 00000246 ORIG_RAX: 0000000000000052 RAX: ffffffffffffffda RBX: 00007f53b76d7f80 RCX: 00007f53b75b80f9 RDX: 0000000000000000 RSI: 0000000020000440 RDI: 0000000020000100 RBP: 00007f53b7613ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffcfe13f9af R14: 00007f53b5b2a300 R15: 0000000000022000 Zero length message leads to an empty skb device vxlan0 entered promiscuous mode device vxlan0 entered promiscuous mode audit: type=1800 audit(1677101075.992:18): pid=11245 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="file0" dev="sda1" ino=14163 res=0 audit: type=1804 audit(1677101075.992:19): pid=11245 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir1013284204/syzkaller.d0IFL3/59/file0" dev="sda1" ino=14163 res=1 device vxlan0 entered promiscuous mode device vxlan0 entered promiscuous mode audit: type=1800 audit(1677101076.882:20): pid=11327 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="file0" dev="sda1" ino=14163 res=0 audit: type=1804 audit(1677101076.892:21): pid=11327 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir1013284204/syzkaller.d0IFL3/60/file0" dev="sda1" ino=14163 res=1 F2FS-fs (loop1): Found nat_bits in checkpoint F2FS-fs (loop1): sanity_check_inode: inode (ino=3) has corrupted i_extra_isize: 24, max: 12 F2FS-fs (loop1): Failed to read root inode F2FS-fs (loop1): Found nat_bits in checkpoint F2FS-fs (loop1): sanity_check_inode: inode (ino=3) has corrupted i_extra_isize: 24, max: 12 F2FS-fs (loop1): Failed to read root inode FAT-fs (loop0): Directory bread(block 64) failed FAT-fs (loop0): Directory bread(block 65) failed audit: type=1800 audit(1677101077.872:22): pid=11385 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="file0" dev="sda1" ino=14138 res=0 audit: type=1804 audit(1677101078.072:23): pid=11385 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir3080879043/syzkaller.yZQQX3/72/file0" dev="sda1" ino=14138 res=1 audit: type=1800 audit(1677101078.102:24): pid=11386 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="file0" dev="sda1" ino=14165 res=0 audit: type=1804 audit(1677101078.112:25): pid=11386 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir1013284204/syzkaller.d0IFL3/61/file0" dev="sda1" ino=14165 res=1 FAT-fs (loop0): Directory bread(block 66) failed FAT-fs (loop0): Directory bread(block 67) failed FAT-fs (loop0): Directory bread(block 68) failed FAT-fs (loop0): Directory bread(block 69) failed FAT-fs (loop0): Directory bread(block 70) failed FAT-fs (loop0): Directory bread(block 71) failed FAT-fs (loop0): Directory bread(block 72) failed FAT-fs (loop0): Directory bread(block 73) failed audit: type=1800 audit(1677101078.512:26): pid=11377 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="loop0" ino=7 res=0 audit: type=1800 audit(1677101078.512:27): pid=11393 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="file2" dev="loop0" ino=8 res=0 FAT-fs (loop0): error, invalid access to FAT (entry 0x0000b706) FAT-fs (loop0): Filesystem has been set read-only FAT-fs (loop0): Directory bread(block 64) failed FAT-fs (loop0): Directory bread(block 65) failed FAT-fs (loop0): Directory bread(block 66) failed FAT-fs (loop0): Directory bread(block 67) failed FAT-fs (loop0): Directory bread(block 68) failed FAT-fs (loop0): Directory bread(block 69) failed FAT-fs (loop0): Directory bread(block 70) failed FAT-fs (loop0): Directory bread(block 71) failed FAT-fs (loop0): Directory bread(block 72) failed FAT-fs (loop0): Directory bread(block 73) failed FAT-fs (loop0): error, invalid access to FAT (entry 0x0000b706) FAT-fs (loop0): Filesystem has been set read-only FAT-fs (loop0): Directory bread(block 64) failed FAT-fs (loop0): Directory bread(block 65) failed FAT-fs (loop0): Directory bread(block 66) failed FAT-fs (loop0): Directory bread(block 67) failed FAT-fs (loop0): Directory bread(block 68) failed FAT-fs (loop0): Directory bread(block 69) failed FAT-fs (loop0): Directory bread(block 70) failed FAT-fs (loop0): Directory bread(block 71) failed FAT-fs (loop0): Directory bread(block 72) failed FAT-fs (loop0): Directory bread(block 73) failed kauditd_printk_skb: 8 callbacks suppressed audit: type=1800 audit(1677101081.123:36): pid=11483 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="loop0" ino=11 res=0 FAT-fs (loop0): error, invalid access to FAT (entry 0x0000b706) FAT-fs (loop0): Filesystem has been set read-only audit: type=1800 audit(1677101081.173:37): pid=11483 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="file2" dev="loop0" ino=12 res=0 FAT-fs (loop0): Directory bread(block 64) failed FAT-fs (loop0): Directory bread(block 65) failed FAT-fs (loop0): Directory bread(block 66) failed FAT-fs (loop0): Directory bread(block 67) failed FAT-fs (loop0): Directory bread(block 68) failed FAT-fs (loop0): Directory bread(block 69) failed FAT-fs (loop0): Directory bread(block 70) failed FAT-fs (loop0): Directory bread(block 71) failed FAT-fs (loop0): Directory bread(block 72) failed FAT-fs (loop0): Directory bread(block 73) failed audit: type=1800 audit(1677101082.013:38): pid=11520 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="loop0" ino=13 res=0 FAT-fs (loop0): error, invalid access to FAT (entry 0x0000b706) audit: type=1800 audit(1677101082.113:39): pid=11520 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="file2" dev="loop0" ino=14 res=0 FAT-fs (loop0): Filesystem has been set read-only IPv6: ADDRCONF(NETDEV_UP): bond1: link is not ready 8021q: adding VLAN 0 to HW filter on device bond1 bond1: making interface bridge1 the new active one bond1: Enslaving bridge1 as an active interface with an up link IPv6: ADDRCONF(NETDEV_CHANGE): bond1: link becomes ready device syz_tun entered promiscuous mode audit: type=1804 audit(1677101082.403:40): pid=11547 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir1869068300/syzkaller.e8SqTN/59/cgroup.controllers" dev="sda1" ino=14013 res=1 bond1: Enslaving macvlan2 as an active interface with an up link IPv6: ADDRCONF(NETDEV_UP): bond2: link is not ready 8021q: adding VLAN 0 to HW filter on device bond2 bond2: making interface bridge2 the new active one bond2: Enslaving bridge2 as an active interface with an up link bond2: Enslaving macvlan3 as an active interface with an up link audit: type=1804 audit(1677101083.173:41): pid=11695 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir1869068300/syzkaller.e8SqTN/60/cgroup.controllers" dev="sda1" ino=14142 res=1 IPv6: ADDRCONF(NETDEV_UP): bond1: link is not ready 8021q: adding VLAN 0 to HW filter on device bond1 IPv6: ADDRCONF(NETDEV_CHANGE): bond2: link becomes ready bond1: making interface bridge1 the new active one bond1: Enslaving bridge1 as an active interface with an up link device syz_tun entered promiscuous mode bond1: Enslaving macvlan2 as an active interface with an up link IPv6: ADDRCONF(NETDEV_CHANGE): bond1: link becomes ready IPv6: ADDRCONF(NETDEV_UP): bond2: link is not ready 8021q: adding VLAN 0 to HW filter on device bond2 IPv6: ADDRCONF(NETDEV_UP): bond3: link is not ready audit: type=1804 audit(1677101083.863:42): pid=11807 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir1869068300/syzkaller.e8SqTN/61/cgroup.controllers" dev="sda1" ino=14119 res=1 8021q: adding VLAN 0 to HW filter on device bond3 IPv6: ADDRCONF(NETDEV_UP): bond2: link is not ready 8021q: adding VLAN 0 to HW filter on device bond2 bond2: making interface bridge2 the new active one bond2: Enslaving bridge2 as an active interface with an up link bond2: making interface bridge1 the new active one bond2: Enslaving bridge1 as an active interface with an up link bond3: making interface macvlan4 the new active one bond3: Enslaving macvlan4 as an active interface with an up link IPv6: ADDRCONF(NETDEV_CHANGE): bond2: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): bond3: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): bond2: link becomes ready