device syz_tun entered promiscuous mode F2FS-fs (loop3): invalid crc value F2FS-fs (loop3): Found nat_bits in checkpoint tmpfs: Bad value 'local:00:96' for mount option 'mpol' ====================================================== WARNING: possible circular locking dependency detected 4.19.211-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.4/15574 is trying to acquire lock: 00000000586005e3 (&HFSPLUS_I(inode)->extents_lock){+.+.}, at: hfsplus_file_extend+0x1bb/0xf40 fs/hfsplus/extents.c:457 F2FS-fs (loop3): f2fs_check_nid_range: out-of-range nid=2, run fsck to fix. but task is already holding lock: 00000000a3854f73 (&tree->tree_lock#2){+.+.}, at: hfsplus_find_init+0x1b7/0x220 fs/hfsplus/bfind.c:30 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #1 (&tree->tree_lock#2){+.+.}: hfsplus_file_truncate+0xde7/0x1040 fs/hfsplus/extents.c:595 hfsplus_setattr+0x1e7/0x310 fs/hfsplus/inode.c:263 notify_change+0x70b/0xfc0 fs/attr.c:334 do_truncate+0x134/0x1f0 fs/open.c:63 handle_truncate fs/namei.c:3009 [inline] do_last fs/namei.c:3427 [inline] path_openat+0x2308/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 -> #0 (&HFSPLUS_I(inode)->extents_lock){+.+.}: __mutex_lock_common kernel/locking/mutex.c:937 [inline] __mutex_lock+0xd7/0x1190 kernel/locking/mutex.c:1078 hfsplus_file_extend+0x1bb/0xf40 fs/hfsplus/extents.c:457 hfsplus_bmap_reserve+0x298/0x440 fs/hfsplus/btree.c:357 hfsplus_rename_cat+0x272/0x1490 fs/hfsplus/catalog.c:456 hfsplus_unlink+0x49c/0x820 fs/hfsplus/dir.c:376 vfs_unlink+0x27d/0x4e0 fs/namei.c:4002 do_unlinkat+0x3b8/0x660 fs/namei.c:4065 do_coredump+0x1f9c/0x2d60 fs/coredump.c:687 get_signal+0xed9/0x1f70 kernel/signal.c:2583 do_signal+0x8f/0x1670 arch/x86/kernel/signal.c:799 exit_to_usermode_loop+0x204/0x2a0 arch/x86/entry/common.c:163 prepare_exit_to_usermode+0x277/0x2d0 arch/x86/entry/common.c:198 retint_user+0x8/0x18 other info that might help us debug this: Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(&tree->tree_lock#2); lock(&HFSPLUS_I(inode)->extents_lock); lock(&tree->tree_lock#2); lock(&HFSPLUS_I(inode)->extents_lock); *** DEADLOCK *** 5 locks held by syz-executor.4/15574: #0: 00000000d669146d (sb_writers#23){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline] #0: 00000000d669146d (sb_writers#23){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360 #1: 00000000cd652118 (&type->i_mutex_dir_key#14/1){+.+.}, at: inode_lock_nested include/linux/fs.h:783 [inline] #1: 00000000cd652118 (&type->i_mutex_dir_key#14/1){+.+.}, at: do_unlinkat+0x27d/0x660 fs/namei.c:4051 #2: 000000007198299a (&sb->s_type->i_mutex_key#29){+.+.}, at: inode_lock include/linux/fs.h:748 [inline] #2: 000000007198299a (&sb->s_type->i_mutex_key#29){+.+.}, at: vfs_unlink+0xca/0x4e0 fs/namei.c:3993 #3: 0000000023756156 (&sbi->vh_mutex){+.+.}, at: hfsplus_unlink+0x140/0x820 fs/hfsplus/dir.c:370 #4: 00000000a3854f73 (&tree->tree_lock#2){+.+.}, at: hfsplus_find_init+0x1b7/0x220 fs/hfsplus/bfind.c:30 stack backtrace: CPU: 1 PID: 15574 Comm: syz-executor.4 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/02/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_extend+0x1bb/0xf40 fs/hfsplus/extents.c:457 hfsplus_bmap_reserve+0x298/0x440 fs/hfsplus/btree.c:357 hfsplus_rename_cat+0x272/0x1490 fs/hfsplus/catalog.c:456 hfsplus_unlink+0x49c/0x820 fs/hfsplus/dir.c:376 vfs_unlink+0x27d/0x4e0 fs/namei.c:4002 do_unlinkat+0x3b8/0x660 fs/namei.c:4065 do_coredump+0x1f9c/0x2d60 fs/coredump.c:687 get_signal+0xed9/0x1f70 kernel/signal.c:2583 do_signal+0x8f/0x1670 arch/x86/kernel/signal.c:799 exit_to_usermode_loop+0x204/0x2a0 arch/x86/entry/common.c:163 prepare_exit_to_usermode+0x277/0x2d0 arch/x86/entry/common.c:198 retint_user+0x8/0x18 RIP: 0033:0x8000000001 Code: Bad RIP value. RSP: 002b:0000000020000188 EFLAGS: 00010217 RAX: 0000000000000000 RBX: 00007f0750d2d120 RCX: 00007f0750c0d0f9 RDX: 0000000000000000 RSI: 0000000020000180 RDI: 0000000000000000 RBP: 00007f0750c68ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000020000240 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffc2bd6990f R14: 00007f074f13d300 R15: 0000000000022000 device syz_tun left promiscuous mode F2FS-fs (loop3): invalid crc value F2FS-fs (loop3): Failed to initialize F2FS segment manager hub 9-0:1.0: USB hub found hub 9-0:1.0: 8 ports detected netlink: 40 bytes leftover after parsing attributes in process `syz-executor.5'. device syz_tun entered promiscuous mode device syz_tun left promiscuous mode netlink: 40 bytes leftover after parsing attributes in process `syz-executor.5'. device syz_tun entered promiscuous mode kauditd_printk_skb: 6 callbacks suppressed audit: type=1804 audit(1677984774.257:218): pid=15664 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir1914202319/syzkaller.YnVScy/110/bus" dev="sda1" ino=14567 res=1 device syz_tun left promiscuous mode F2FS-fs (loop3): invalid crc value F2FS-fs (loop3): Found nat_bits in checkpoint F2FS-fs (loop3): f2fs_check_nid_range: out-of-range nid=2, run fsck to fix. F2FS-fs (loop3): Mounted with checkpoint version = 48b305e4 audit: type=1804 audit(1677984774.547:219): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:220): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:221): pid=15676 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:222): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:223): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:224): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:225): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:226): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 audit: type=1804 audit(1677984774.577:227): pid=15661 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/92/file0/bus" dev="loop3" ino=10 res=1 hub 9-0:1.0: USB hub found hub 9-0:1.0: 8 ports detected overlayfs: unrecognized mount option "workéS" or missing value netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. device batadv1 entered promiscuous mode 8021q: adding VLAN 0 to HW filter on device batadv1 hub 9-0:1.0: USB hub found hub 9-0:1.0: 8 ports detected netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. overlayfs: unrecognized mount option "workéS" or missing value netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.1'. REISERFS (device loop1): found reiserfs format "3.5" with non-standard journal REISERFS (device loop1): using ordered data mode reiserfs: using flush barriers REISERFS (device loop1): journal params: device loop1, size 512, journal first block 18, max trans len 256, max batch 225, max commit age 30, max trans age 30 REISERFS (device loop1): checking transaction log (loop1) overlayfs: unrecognized mount option "workéS" or missing value REISERFS (device loop1): Using r5 hash to sort names overlayfs: unrecognized mount option "workéS" or missing value overlayfs: fs on './file0' does not support file handles, falling back to index=off,nfs_export=off. UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) overlayfs: fs on './file0' does not support file handles, falling back to index=off,nfs_export=off. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. overlayfs: './file0' not a directory netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. print_req_error: 14 callbacks suppressed print_req_error: I/O error, dev loop6, sector 648 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. device syz_tun entered promiscuous mode netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. device syz_tun left promiscuous mode device syz_tun entered promiscuous mode netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. device syz_tun left promiscuous mode netlink: 24 bytes leftover after parsing attributes in process `syz-executor.3'. kauditd_printk_skb: 56 callbacks suppressed audit: type=1800 audit(1677984779.497:284): pid=16248 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14002 res=0 audit: type=1804 audit(1677984779.647:285): pid=16279 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/106/bus" dev="sda1" ino=14603 res=1 audit: type=1804 audit(1677984779.747:286): pid=16291 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/106/bus" dev="sda1" ino=14603 res=1 audit: type=1800 audit(1677984779.827:287): pid=16290 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14050 res=0 audit: type=1800 audit(1677984779.987:288): pid=16286 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="file0" dev="sda1" ino=13955 res=0 print_req_error: I/O error, dev loop6, sector 648 audit: type=1800 audit(1677984780.577:289): pid=16315 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14081 res=0 audit: type=1800 audit(1677984780.647:290): pid=16360 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="file0" dev="sda1" ino=13909 res=0 audit: type=1800 audit(1677984780.887:291): pid=16371 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=14607 res=0 audit: type=1804 audit(1677984781.227:292): pid=16416 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/108/bus" dev="sda1" ino=14603 res=1 audit: type=1804 audit(1677984781.307:293): pid=16418 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.3" name="/root/syzkaller-testdir4174752887/syzkaller.K7SGDX/108/bus" dev="sda1" ino=14603 res=1 print_req_error: I/O error, dev loop6, sector 392