loop2: rw=0, want=201326594, limit=1024 Buffer I/O error on dev loop2, logical block 100663296, async page read hfsplus: unable to mark blocks free: error -5 hfsplus: can't free extent ====================================================== WARNING: possible circular locking dependency detected 4.19.211-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.2/13838 is trying to acquire lock: 0000000038669533 (&tree->tree_lock){+.+.}, at: hfsplus_file_truncate+0xde7/0x1040 fs/hfsplus/extents.c:595 but task is already holding lock: 0000000035b7c8dd (&HFSPLUS_I(inode)->extents_lock){+.+.}, at: hfsplus_file_truncate+0x1e2/0x1040 fs/hfsplus/extents.c:576 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #1 (&HFSPLUS_I(inode)->extents_lock){+.+.}: hfsplus_file_extend+0x1bb/0xf40 fs/hfsplus/extents.c:457 hfsplus_bmap_reserve+0x298/0x440 fs/hfsplus/btree.c:357 hfsplus_create_cat+0x1e3/0x1210 fs/hfsplus/catalog.c:272 hfsplus_fill_super+0x14a8/0x19e0 fs/hfsplus/super.c:560 mount_bdev+0x2fc/0x3b0 fs/super.c:1158 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 (&tree->tree_lock){+.+.}: __mutex_lock_common kernel/locking/mutex.c:937 [inline] __mutex_lock+0xd7/0x1190 kernel/locking/mutex.c:1078 hfsplus_file_truncate+0xde7/0x1040 fs/hfsplus/extents.c:595 hfsplus_delete_inode+0x18d/0x220 fs/hfsplus/inode.c:419 hfsplus_unlink+0x595/0x820 fs/hfsplus/dir.c:405 hfsplus_rename+0xbe/0x200 fs/hfsplus/dir.c:545 vfs_rename+0x67e/0x1bc0 fs/namei.c:4479 do_renameat2+0xb59/0xc70 fs/namei.c:4629 __do_sys_rename fs/namei.c:4675 [inline] __se_sys_rename fs/namei.c:4673 [inline] __x64_sys_rename+0x5d/0x80 fs/namei.c:4673 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(&HFSPLUS_I(inode)->extents_lock); lock(&tree->tree_lock); lock(&HFSPLUS_I(inode)->extents_lock); lock(&tree->tree_lock); *** DEADLOCK *** 8 locks held by syz-executor.2/13838: #0: 0000000059152922 (sb_writers#27){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline] #0: 0000000059152922 (sb_writers#27){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360 #1: 00000000052f40ef (&type->s_vfs_rename_key#4){+.+.}, at: lock_rename+0x54/0x280 fs/namei.c:2867 #2: 000000001e82a805 (&type->i_mutex_dir_key#15/1){+.+.}, at: inode_lock_nested include/linux/fs.h:783 [inline] #2: 000000001e82a805 (&type->i_mutex_dir_key#15/1){+.+.}, at: lock_rename+0x132/0x280 fs/namei.c:2878 #3: 000000001fdcf898 (&type->i_mutex_dir_key#15/2){+.+.}, at: inode_lock_nested include/linux/fs.h:783 [inline] #3: 000000001fdcf898 (&type->i_mutex_dir_key#15/2){+.+.}, at: lock_rename+0x166/0x280 fs/namei.c:2879 #4: 0000000042245647 (&sb->s_type->i_mutex_key#32){+.+.}, at: inode_lock include/linux/fs.h:748 [inline] #4: 0000000042245647 (&sb->s_type->i_mutex_key#32){+.+.}, at: lock_two_nondirectories+0xec/0x110 fs/inode.c:1015 #5: 00000000c8a0d60e (&sb->s_type->i_mutex_key#32/4){+.+.}, at: inode_lock_nested include/linux/fs.h:783 [inline] #5: 00000000c8a0d60e (&sb->s_type->i_mutex_key#32/4){+.+.}, at: lock_two_nondirectories+0xd1/0x110 fs/inode.c:1017 #6: 000000009ba53b36 (&sbi->vh_mutex){+.+.}, at: hfsplus_unlink+0x140/0x820 fs/hfsplus/dir.c:370 #7: 0000000035b7c8dd (&HFSPLUS_I(inode)->extents_lock){+.+.}, at: hfsplus_file_truncate+0x1e2/0x1040 fs/hfsplus/extents.c:576 stack backtrace: CPU: 0 PID: 13838 Comm: syz-executor.2 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023 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 __mutex_lock_common kernel/locking/mutex.c:937 [inline] __mutex_lock+0xd7/0x1190 kernel/locking/mutex.c:1078 hfsplus_file_truncate+0xde7/0x1040 fs/hfsplus/extents.c:595 hfsplus_delete_inode+0x18d/0x220 fs/hfsplus/inode.c:419 hfsplus_unlink+0x595/0x820 fs/hfsplus/dir.c:405 hfsplus_rename+0xbe/0x200 fs/hfsplus/dir.c:545 vfs_rename+0x67e/0x1bc0 fs/namei.c:4479 do_renameat2+0xb59/0xc70 fs/namei.c:4629 __do_sys_rename fs/namei.c:4675 [inline] __se_sys_rename fs/namei.c:4673 [inline] __x64_sys_rename+0x5d/0x80 fs/namei.c:4673 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7fd0394dd0c9 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:00007fd037a4f168 EFLAGS: 00000246 ORIG_RAX: 0000000000000052 RAX: ffffffffffffffda RBX: 00007fd0395fcf80 RCX: 00007fd0394dd0c9 RDX: 0000000000000000 RSI: 0000000020000140 RDI: 0000000020000000 RBP: 00007fd039538ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff5c1ceaaf R14: 00007fd037a4f300 R15: 0000000000022000 netlink: 40 bytes leftover after parsing attributes in process `syz-executor.3'. XFS (loop5): Mounting V4 Filesystem XFS (loop5): Ending clean mount overlayfs: unrecognized mount option "upperdr=./bus" or missing value XFS (loop5): Unmounting Filesystem netlink: 40 bytes leftover after parsing attributes in process `syz-executor.3'. Started in network mode Own node identity 9, cluster identity 4711 32-bit node address hash set to 9 audit: type=1800 audit(1674948665.376:16): pid=13956 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14308 res=0 audit: type=1804 audit(1674948665.386:17): pid=13956 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir2597530243/syzkaller.VxngoW/67/file0" dev="sda1" ino=14308 res=1 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. audit: type=1800 audit(1674948667.026:18): pid=14003 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14301 res=0 netlink: 260 bytes leftover after parsing attributes in process `syz-executor.3'. audit: type=1804 audit(1674948667.066:19): pid=13995 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir2597530243/syzkaller.VxngoW/68/file0" dev="sda1" ino=14301 res=1 IPVS: ftp: loaded support on port[0] = 21 netlink: 260 bytes leftover after parsing attributes in process `syz-executor.3'. audit: type=1800 audit(1674948667.476:20): pid=14058 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14373 res=0 audit: type=1800 audit(1674948667.716:21): pid=14059 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14374 res=0 audit: type=1804 audit(1674948667.886:22): pid=14056 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir2166734186/syzkaller.AcLpbI/85/file0" dev="sda1" ino=14373 res=1 audit: type=1804 audit(1674948667.906:23): pid=14059 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir1580290269/syzkaller.EUJlXT/75/file0" dev="sda1" ino=14374 res=1 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 260 bytes leftover after parsing attributes in process `syz-executor.3'. IPVS: ftp: loaded support on port[0] = 21 netlink: 260 bytes leftover after parsing attributes in process `syz-executor.3'. ieee802154 phy0 wpan0: encryption failed: -22 ieee802154 phy1 wpan1: encryption failed: -22 audit: type=1800 audit(1674948669.606:24): pid=14128 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14379 res=0 audit: type=1800 audit(1674948669.896:25): pid=14129 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14386 res=0 audit: type=1804 audit(1674948669.996:26): pid=14126 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir2166734186/syzkaller.AcLpbI/86/file0" dev="sda1" ino=14379 res=1 audit: type=1804 audit(1674948669.996:27): pid=14129 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir1580290269/syzkaller.EUJlXT/76/file0" dev="sda1" ino=14386 res=1 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 260 bytes leftover after parsing attributes in process `syz-executor.3'. audit: type=1800 audit(1674948670.426:28): pid=14107 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.5" name="file0" dev="sda1" ino=14382 res=0 netlink: 260 bytes leftover after parsing attributes in process `syz-executor.3'. audit: type=1800 audit(1674948670.576:29): pid=14157 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14284 res=0 audit: type=1804 audit(1674948670.606:30): pid=14157 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir2166734186/syzkaller.AcLpbI/87/file0" dev="sda1" ino=14284 res=1 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.0'. audit: type=1800 audit(1674948671.896:31): pid=14244 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14400 res=0 netlink: 260 bytes leftover after parsing attributes in process `syz-executor.0'. audit: type=1804 audit(1674948671.906:32): pid=14244 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir2166734186/syzkaller.AcLpbI/88/file0" dev="sda1" ino=14400 res=1 netlink: 260 bytes leftover after parsing attributes in process `syz-executor.0'. squashfs: SQUASHFS error: unable to read inode lookup table audit: type=1800 audit(1674948672.776:33): pid=14366 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=14389 res=0 squashfs: SQUASHFS error: unable to read inode lookup table