REISERFS (device loop4): Using r5 hash to sort names REISERFS (device loop4): Created .reiserfs_priv - reserved for xattr storage. ====================================================== WARNING: possible circular locking dependency detected 4.14.302-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.4/10216 is trying to acquire lock: (&journal->j_mutex){+.+.}, at: [] reiserfs_mutex_lock_safe fs/reiserfs/reiserfs.h:816 [inline] (&journal->j_mutex){+.+.}, at: [] lock_journal fs/reiserfs/journal.c:537 [inline] (&journal->j_mutex){+.+.}, at: [] do_journal_begin_r+0x26b/0xde0 fs/reiserfs/journal.c:3054 but task is already holding lock: (sb_writers#17){.+.+}, at: [] sb_start_write include/linux/fs.h:1551 [inline] (sb_writers#17){.+.+}, at: [] mnt_want_write_file+0xfd/0x3b0 fs/namespace.c:497 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #2 (sb_writers#17){.+.+}: 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_file+0xfd/0x3b0 fs/namespace.c:497 reiserfs_ioctl+0x18e/0x8b0 fs/reiserfs/ioctl.c:110 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 -> #1 (&sbi->lock){+.+.}: __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 reiserfs_write_lock_nested+0x59/0xd0 fs/reiserfs/lock.c:78 reiserfs_mutex_lock_safe fs/reiserfs/reiserfs.h:817 [inline] lock_journal fs/reiserfs/journal.c:537 [inline] do_journal_begin_r+0x276/0xde0 fs/reiserfs/journal.c:3054 journal_begin+0x162/0x3d0 fs/reiserfs/journal.c:3262 reiserfs_fill_super+0x18f4/0x2990 fs/reiserfs/super.c:2117 mount_bdev+0x2b3/0x360 fs/super.c:1134 mount_fs+0x92/0x2a0 fs/super.c:1237 vfs_kern_mount.part.0+0x5b/0x470 fs/namespace.c:1046 vfs_kern_mount fs/namespace.c:1036 [inline] do_new_mount fs/namespace.c:2572 [inline] do_mount+0xe65/0x2a30 fs/namespace.c:2905 SYSC_mount fs/namespace.c:3121 [inline] SyS_mount+0xa8/0x120 fs/namespace.c:3098 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 -> #0 (&journal->j_mutex){+.+.}: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 reiserfs_mutex_lock_safe fs/reiserfs/reiserfs.h:816 [inline] lock_journal fs/reiserfs/journal.c:537 [inline] do_journal_begin_r+0x26b/0xde0 fs/reiserfs/journal.c:3054 journal_begin+0x162/0x3d0 fs/reiserfs/journal.c:3262 reiserfs_dirty_inode+0xd9/0x200 fs/reiserfs/super.c:716 __mark_inode_dirty+0x11e/0xf40 fs/fs-writeback.c:2134 mark_inode_dirty include/linux/fs.h:2026 [inline] reiserfs_ioctl+0x6f6/0x8b0 fs/reiserfs/ioctl.c:118 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 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: Chain exists of: &journal->j_mutex --> &sbi->lock --> sb_writers#17 Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(sb_writers#17); lock(&sbi->lock); lock(sb_writers#17); lock(&journal->j_mutex); *** DEADLOCK *** 1 lock held by syz-executor.4/10216: #0: (sb_writers#17){.+.+}, at: [] sb_start_write include/linux/fs.h:1551 [inline] #0: (sb_writers#17){.+.+}, at: [] mnt_want_write_file+0xfd/0x3b0 fs/namespace.c:497 stack backtrace: CPU: 1 PID: 10216 Comm: syz-executor.4 Not tainted 4.14.302-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 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 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 reiserfs_mutex_lock_safe fs/reiserfs/reiserfs.h:816 [inline] lock_journal fs/reiserfs/journal.c:537 [inline] do_journal_begin_r+0x26b/0xde0 fs/reiserfs/journal.c:3054 journal_begin+0x162/0x3d0 fs/reiserfs/journal.c:3262 reiserfs_dirty_inode+0xd9/0x200 fs/reiserfs/super.c:716 __mark_inode_dirty+0x11e/0xf40 fs/fs-writeback.c:2134 mark_inode_dirty include/linux/fs.h:2026 [inline] reiserfs_ioctl+0x6f6/0x8b0 fs/reiserfs/ioctl.c:118 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7fb009f0f0d9 RSP: 002b:00007fb008481168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007fb00a02ef80 RCX: 00007fb009f0f0d9 RDX: 0000000020000040 RSI: 0000000040087602 RDI: 0000000000000003 RBP: 00007fb009f6aae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffc78fe990f R14: 00007fb008481300 R15: 0000000000022000 process 'syz-executor.1' launched '/dev/fd/4' with NULL argv: empty string added REISERFS (device loop4): found reiserfs format "3.6" with non-standard journal REISERFS (device loop4): using ordered data mode reiserfs: using flush barriers REISERFS (device loop4): journal params: device loop4, size 512, journal first block 18, max trans len 256, max batch 225, max commit age 30, max trans age 30 REISERFS (device loop4): checking transaction log (loop4) REISERFS (device loop4): Using r5 hash to sort names REISERFS (device loop4): Created .reiserfs_priv - reserved for xattr storage. REISERFS (device loop4): found reiserfs format "3.6" with non-standard journal REISERFS (device loop4): using ordered data mode reiserfs: using flush barriers REISERFS (device loop4): journal params: device loop4, size 512, journal first block 18, max trans len 256, max batch 225, max commit age 30, max trans age 30 REISERFS (device loop4): checking transaction log (loop4) REISERFS (device loop4): Using r5 hash to sort names REISERFS (device loop4): Created .reiserfs_priv - reserved for xattr storage. UDF-fs: error (device loop5): udf_process_sequence: Block 100 of volume descriptor sequence is corrupted or we could not read it EXT4-fs (loop0): can't mount with journal_checksum, fs mounted w/o journal UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) EXT4-fs (loop1): mounted filesystem without journal. Opts: ,errors=continue REISERFS (device loop4): found reiserfs format "3.6" with non-standard journal print_req_error: I/O error, dev loop0, sector 24 serio: Serial port pts0 REISERFS (device loop4): using ordered data mode reiserfs: using flush barriers REISERFS (device loop4): journal params: device loop4, size 512, journal first block 18, max trans len 256, max batch 225, max commit age 30, max trans age 30 EXT4-fs (loop1): mounted filesystem without journal. Opts: ,errors=continue REISERFS (device loop4): checking transaction log (loop4) REISERFS (device loop4): Using r5 hash to sort names REISERFS (device loop4): Created .reiserfs_priv - reserved for xattr storage. serio: Serial port pts0 UDF-fs: error (device loop5): udf_process_sequence: Block 100 of volume descriptor sequence is corrupted or we could not read it UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) EXT4-fs (loop3): 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: 33 vs 41 free clusters EXT4-fs (loop1): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop3): re-mounted. Opts: (null) serio: Serial port pts0 EXT4-fs (loop3): mounted filesystem without journal. Opts: ,errors=continue UDF-fs: error (device loop5): udf_process_sequence: Block 100 of volume descriptor sequence is corrupted or we could not read it EXT4-fs (loop1): 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: 33 vs 41 free clusters UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) EXT4-fs (loop3): re-mounted. Opts: (null) Y4`Ҙ: renamed from lo audit: type=1800 audit(1671052256.406:28): pid=10549 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="file0" dev="sda1" ino=13997 res=0 serio: Serial port pts0 IPVS: ftp: loaded support on port[0] = 21 EXT4-fs (loop3): mounted filesystem without journal. Opts: ,errors=continue audit: type=1804 audit(1671052256.416:29): pid=10549 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir3986917892/syzkaller.I1KjzA/46/file0" dev="sda1" ino=13997 res=1 EXT4-fs error (device loop3): ext4_mb_generate_buddy:754: group 0, block bitmap and bg descriptor inconsistent: 33 vs 41 free clusters UDF-fs: error (device loop5): udf_process_sequence: Block 100 of volume descriptor sequence is corrupted or we could not read it UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) EXT4-fs (loop3): 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: 33 vs 41 free clusters Y4`Ҙ: renamed from lo audit: type=1800 audit(1671052257.296:30): pid=10637 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="file0" dev="sda1" ino=13997 res=0 EXT4-fs (loop3): re-mounted. Opts: (null) audit: type=1804 audit(1671052257.306:31): pid=10637 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir3986917892/syzkaller.I1KjzA/47/file0" dev="sda1" ino=13997 res=1 audit: type=1800 audit(1671052258.086:32): pid=10653 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="file0" dev="sda1" ino=14013 res=0 audit: type=1804 audit(1671052258.086:33): pid=10653 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir2404446263/syzkaller.E9Fp4F/41/file0" dev="sda1" ino=14013 res=1 audit: type=1800 audit(1671052258.116:34): pid=10655 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="file0" dev="sda1" ino=14014 res=0 audit: type=1804 audit(1671052258.116:35): pid=10655 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir4104349606/syzkaller.iQVmbo/28/file0" dev="sda1" ino=14014 res=1 audit: type=1800 audit(1671052259.196:36): pid=10665 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="file0" dev="sda1" ino=14015 res=0 audit: type=1804 audit(1671052259.196:37): pid=10665 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir3986917892/syzkaller.I1KjzA/48/file0" dev="sda1" ino=14015 res=1 nla_parse: 5943 callbacks suppressed netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'. Zero length message leads to an empty skb netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'. kauditd_printk_skb: 10 callbacks suppressed audit: type=1800 audit(1671052262.346:48): pid=10819 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="file0" dev="sda1" ino=14039 res=0 audit: type=1804 audit(1671052262.386:49): pid=10819 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.4" name="/root/syzkaller-testdir1790488532/syzkaller.iMeGDZ/32/file0" dev="sda1" ino=14039 res=1 audit: type=1800 audit(1671052262.516:50): pid=10844 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="file0" dev="sda1" ino=13981 res=0 audit: type=1804 audit(1671052262.536:51): pid=10844 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.4" name="/root/syzkaller-testdir1790488532/syzkaller.iMeGDZ/33/file0" dev="sda1" ino=13981 res=1 audit: type=1800 audit(1671052263.226:52): pid=10885 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="file0" dev="sda1" ino=13999 res=0 audit: type=1804 audit(1671052263.246:53): pid=10885 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.4" name="/root/syzkaller-testdir1790488532/syzkaller.iMeGDZ/34/file0" dev="sda1" ino=13999 res=1 audit: type=1800 audit(1671052263.407:54): pid=10910 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="file0" dev="sda1" ino=13998 res=0