audit: type=1804 audit(1658224643.255:43): pid=11970 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir154732070/syzkaller.P6w0Po/89/file0" dev="sda1" ino=14079 res=1 audit: type=1800 audit(1658224644.125:44): pid=11992 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="file0" dev="sda1" ino=14188 res=0 ====================================================== audit: type=1800 audit(1658224644.125:45): pid=11993 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="file0" dev="sda1" ino=14189 res=0 WARNING: possible circular locking dependency detected 4.14.288-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.3/12064 is trying to acquire lock: (sb_writers#6){.+.+}, at: [] file_start_write include/linux/fs.h:2714 [inline] (sb_writers#6){.+.+}, at: [] vfs_fallocate+0x5c1/0x790 fs/open.c:318 but task is already holding lock: (ashmem_mutex audit: type=1804 audit(1658224644.235:46): pid=11992 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir3340566539/syzkaller.0BbBaB/67/file0" dev="sda1" ino=14188 res=1 ){+.+.}, at: [] ashmem_shrink_scan drivers/staging/android/ashmem.c:494 [inline] ){+.+.}, at: [] ashmem_ioctl+0x27e/0xd00 drivers/staging/android/ashmem.c:843 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #3 (ashmem_mutex){+.+.}: __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 ashmem_mmap+0x50/0x5c0 drivers/staging/android/ashmem.c:393 audit: type=1804 audit(1658224644.235:47): pid=11993 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir154732070/syzkaller.P6w0Po/90/file0" dev="sda1" ino=14189 res=1 call_mmap include/linux/fs.h:1785 [inline] mmap_region+0xa1a/0x1220 mm/mmap.c:1717 do_mmap+0x5b3/0xcb0 mm/mmap.c:1495 do_mmap_pgoff include/linux/mm.h:2187 [inline] vm_mmap_pgoff+0x14e/0x1a0 mm/util.c:366 audit: type=1804 audit(1658224644.335:48): pid=11992 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.2" name="/root/syzkaller-testdir3340566539/syzkaller.0BbBaB/67/file0" dev="sda1" ino=14188 res=1 SYSC_mmap_pgoff mm/mmap.c:1545 [inline] SyS_mmap_pgoff+0x249/0x510 mm/mmap.c:1503 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb -> #2 (&mm->mmap_sem){++++}: __might_fault mm/memory.c:4700 [inline] __might_fault+0x137/0x1b0 mm/memory.c:4685 _copy_to_user+0x27/0xd0 lib/usercopy.c:25 audit: type=1804 audit(1658224644.335:49): pid=11993 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir154732070/syzkaller.P6w0Po/90/file0" dev="sda1" ino=14189 res=1 copy_to_user include/linux/uaccess.h:155 [inline] filldir+0x1d5/0x390 fs/readdir.c:237 dir_emit_dot include/linux/fs.h:3361 [inline] dir_emit_dots include/linux/fs.h:3372 [inline] dcache_readdir+0x180/0x860 fs/libfs.c:192 iterate_dir+0x1a0/0x5e0 fs/readdir.c:52 SYSC_getdents fs/readdir.c:272 [inline] SyS_getdents+0x125/0x240 fs/readdir.c:253 audit: type=1800 audit(1658224645.595:50): pid=12014 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="bus" dev="sda1" ino=14188 res=0 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb -> #1 audit: type=1800 audit(1658224648.406:51): pid=12033 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="bus" dev="sda1" ino=14196 res=0 (&type->i_mutex_dir_key#5){++++}: down_write+0x34/0x90 kernel/locking/rwsem.c:54 inode_lock include/linux/fs.h:719 [inline] do_last fs/namei.c:3331 [inline] path_openat+0xde2/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 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+0x46/0xbb -> #0 (sb_writers#6){.+.+}: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 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 file_start_write include/linux/fs.h:2714 [inline] vfs_fallocate+0x5c1/0x790 fs/open.c:318 ashmem_shrink_scan.part.0+0x135/0x3d0 drivers/staging/android/ashmem.c:501 ashmem_shrink_scan drivers/staging/android/ashmem.c:494 [inline] ashmem_ioctl+0x294/0xd00 drivers/staging/android/ashmem.c:843 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+0x46/0xbb other info that might help us debug this: Chain exists of: sb_writers#6 --> &mm->mmap_sem --> ashmem_mutex Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(ashmem_mutex); lock(&mm->mmap_sem); lock(ashmem_mutex); lock(sb_writers#6); *** DEADLOCK *** 1 lock held by syz-executor.3/12064: #0: (ashmem_mutex){+.+.}, at: [] ashmem_shrink_scan drivers/staging/android/ashmem.c:494 [inline] #0: (ashmem_mutex){+.+.}, at: [] ashmem_ioctl+0x27e/0xd00 drivers/staging/android/ashmem.c:843 stack backtrace: CPU: 1 PID: 12064 Comm: syz-executor.3 Not tainted 4.14.288-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/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 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 file_start_write include/linux/fs.h:2714 [inline] vfs_fallocate+0x5c1/0x790 fs/open.c:318 ashmem_shrink_scan.part.0+0x135/0x3d0 drivers/staging/android/ashmem.c:501 ashmem_shrink_scan drivers/staging/android/ashmem.c:494 [inline] ashmem_ioctl+0x294/0xd00 drivers/staging/android/ashmem.c:843 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+0x46/0xbb RIP: 0033:0x7f5b3aa44199 RSP: 002b:00007f5b393b9168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f5b3ab56f60 RCX: 00007f5b3aa44199 RDX: 0000000000000000 RSI: 000000000000770a RDI: 0000000000000004 RBP: 00007f5b3aa9e13b R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffee5e1c80f R14: 00007f5b393b9300 R15: 0000000000022000 kauditd_printk_skb: 6 callbacks suppressed audit: type=1800 audit(1658224654.636:59): pid=12100 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="bus" dev="sda1" ino=14184 res=0 audit: type=1800 audit(1658224654.636:58): pid=12101 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="sda1" ino=14185 res=0 audit: type=1800 audit(1658224654.646:60): pid=12102 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=14188 res=0 audit: type=1800 audit(1658224655.986:61): pid=12111 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="sda1" ino=14209 res=0 MTD: Attempt to mount non-MTD device "/dev/loop1" romfs: Mounting image 'rom 5f663c08' through the block layer audit: type=1800 audit(1658224655.986:62): pid=12106 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="bus" dev="sda1" ino=14210 res=0 audit: type=1800 audit(1658224657.126:63): pid=12130 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="bus" dev="sda1" ino=14211 res=0 audit: type=1800 audit(1658224657.436:64): pid=12134 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=13889 res=0 audit: type=1800 audit(1658224657.476:65): pid=12135 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="sda1" ino=13921 res=0 MTD: Attempt to mount non-MTD device "/dev/loop1" romfs: Mounting image 'rom 5f663c08' through the block layer audit: type=1400 audit(1658224658.116:66): apparmor="DENIED" operation="change_profile" info="label not found" error=-2 profile="unconfined" name="/}}-#'" pid=12197 comm="syz-executor.3" MTD: Attempt to mount non-MTD device "/dev/loop4" romfs: Mounting image 'rom 5f663c08' through the block layer audit: type=1400 audit(1658224658.176:67): apparmor="DENIED" operation="change_profile" info="label not found" error=-2 profile="unconfined" name="/}}-#'" pid=12209 comm="syz-executor.3" MTD: Attempt to mount non-MTD device "/dev/loop1" romfs: Mounting image 'rom 5f663c08' through the block layer MTD: Attempt to mount non-MTD device "/dev/loop4" romfs: Mounting image 'rom 5f663c08' through the block layer Restarting kernel threads ... done. MTD: Attempt to mount non-MTD device "/dev/loop1" romfs: Mounting image 'rom 5f663c08' through the block layer Restarting kernel threads ... done. MTD: Attempt to mount non-MTD device "/dev/loop4" romfs: Mounting image 'rom 5f663c08' through the block layer Restarting kernel threads ... done. Restarting kernel threads ... done. Restarting kernel threads ... done. EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs warning (device loop0): ext4_group_add:1649: Can't resize non-sparse filesystem further EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop2): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop1): mounted filesystem without journal. Opts: ,errors=continue netlink: 4 bytes leftover after parsing attributes in process `syz-executor.5'. EXT4-fs warning (device loop0): ext4_group_add:1649: Can't resize non-sparse filesystem further EXT4-fs warning (device loop1): ext4_group_add:1649: Can't resize non-sparse filesystem further EXT4-fs warning (device sda1): ext4_group_add:1668: No reserved GDT blocks, can't resize netlink: 4 bytes leftover after parsing attributes in process `syz-executor.5'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.5'. EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop1): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs warning (device loop0): ext4_group_add:1649: Can't resize non-sparse filesystem further netlink: 4 bytes leftover after parsing attributes in process `syz-executor.5'. EXT4-fs warning (device loop1): ext4_group_add:1649: Can't resize non-sparse filesystem further EXT4-fs (loop2): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs warning (device loop2): ext4_group_add:1649: Can't resize non-sparse filesystem further Dev loop4: unable to read RDB block 1 loop4: AHDI p1 p3 loop4: partition table partially beyond EOD, truncated loop4: p1 start 936621042 is beyond EOD, truncated EXT4-fs (loop1): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs warning (device sda1): ext4_group_add:1668: No reserved GDT blocks, can't resize EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs (loop2): mounted filesystem without journal. Opts: ,errors=continue EXT4-fs warning (device loop2): ext4_group_add:1649: Can't resize non-sparse filesystem further EXT4-fs warning (device loop0): ext4_group_add:1649: Can't resize non-sparse filesystem further Dev loop4: unable to read RDB block 1 loop4: AHDI p1 p3 loop4: partition table partially beyond EOD, truncated loop4: p1 start 936621042 is beyond EOD, truncated 9pnet: Insufficient options for proto=fd Dev loop4: unable to read RDB block 1 loop4: AHDI p1 p3 9pnet: Insufficient options for proto=fd loop4: partition table partially beyond EOD, truncated loop4: p1 start 936621042 is beyond EOD, truncated print_req_error: I/O error, dev loop4, sector 0 Buffer I/O error on dev loop4, logical block 0, async page read ldm_validate_partition_table(): Disk read failed. Dev loop4: unable to read RDB block 0 loop4: unable to read partition table loop4: partition table beyond EOD, truncated 9pnet: Insufficient options for proto=fd Cannot find del_set index 0 as target 9pnet: Insufficient options for proto=fd Dev loop4: unable to read RDB block 1 loop4: AHDI p1 p3 loop4: partition table partially beyond EOD, truncated loop4: p1 start 936621042 is beyond EOD, truncated Dev loop4: unable to read RDB block 1 loop4: AHDI p1 p3 loop4: partition table partially beyond EOD, truncated 9pnet: Insufficient options for proto=fd loop4: p1 start 936621042 is beyond EOD, truncated 9pnet: Insufficient options for proto=fd cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88805bd0a000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88805bd0a000(4096) 9pnet: Insufficient options for proto=fd kauditd_printk_skb: 2 callbacks suppressed audit: type=1800 audit(1658224660.606:70): pid=12631 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.5" name="file0" dev="loop5" ino=244 res=0 cramfs: Error -3 while decompressing!