audit: type=1326 audit(1661514177.199:17): auid=0 uid=0 gid=0 ses=4 subj=system_u:system_r:kernel_t:s0 pid=4227 comm="syz-executor.7" exe="/syz-executor.7" sig=31 arch=c000003e syscall=202 compat=0 ip=0x7f997bbfeb19 code=0x0 ====================================================== WARNING: possible circular locking dependency detected 6.0.0-rc2-next-20220826 #1 Not tainted ------------------------------------------------------ syz-executor.3/4231 is trying to acquire lock: ffff888010022170 (&journal->j_barrier){+.+.}-{3:3}, at: jbd2_journal_lock_updates+0x15e/0x310 but task is already holding lock: ffff888010020bd0 (&sbi->s_writepages_rwsem){++++}-{0:0}, at: ext4_change_inode_journal_flag+0x177/0x530 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #4 (&sbi->s_writepages_rwsem){++++}-{0:0}: percpu_down_write+0x4d/0x3d0 ext4_change_inode_journal_flag+0x177/0x530 ext4_fileattr_set+0x140d/0x18a0 vfs_fileattr_set+0x77c/0xb80 do_vfs_ioctl+0xfc2/0x1610 __x64_sys_ioctl+0x10c/0x210 do_syscall_64+0x3b/0x90 entry_SYSCALL_64_after_hwframe+0x63/0xcd -> #3 (mapping.invalidate_lock){++++}-{3:3}: down_write+0x90/0x150 ext4_setattr+0x970/0x2b90 notify_change+0xcce/0x1420 do_truncate+0x13c/0x200 do_sys_ftruncate+0x3ec/0x730 do_syscall_64+0x3b/0x90 entry_SYSCALL_64_after_hwframe+0x63/0xcd -> #2 (&sb->s_type->i_mutex_key#6){++++}-{3:3}: down_read+0x98/0x450 ext4_bmap+0x4e/0x470 bmap+0xac/0x120 jbd2_journal_bmap+0xa8/0x180 jbd2_journal_flush+0x853/0xc00 __ext4_ioctl+0x9e9/0x4090 __x64_sys_ioctl+0x19a/0x210 do_syscall_64+0x3b/0x90 entry_SYSCALL_64_after_hwframe+0x63/0xcd -> #1 (&journal->j_checkpoint_mutex){+.+.}-{3:3}: mutex_lock_io_nested+0x148/0x1310 jbd2_journal_flush+0x19a/0xc00 __ext4_ioctl+0x9e9/0x4090 __x64_sys_ioctl+0x19a/0x210 do_syscall_64+0x3b/0x90 entry_SYSCALL_64_after_hwframe+0x63/0xcd -> #0 (&journal->j_barrier){+.+.}-{3:3}: __lock_acquire+0x2a02/0x5e70 lock_acquire+0x1a2/0x530 __mutex_lock+0x136/0x14d0 jbd2_journal_lock_updates+0x15e/0x310 ext4_change_inode_journal_flag+0x17f/0x530 ext4_fileattr_set+0x140d/0x18a0 vfs_fileattr_set+0x77c/0xb80 do_vfs_ioctl+0xfc2/0x1610 __x64_sys_ioctl+0x10c/0x210 do_syscall_64+0x3b/0x90 entry_SYSCALL_64_after_hwframe+0x63/0xcd other info that might help us debug this: Chain exists of: &journal->j_barrier --> mapping.invalidate_lock --> &sbi->s_writepages_rwsem Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(&sbi->s_writepages_rwsem); lock(mapping.invalidate_lock); lock(&sbi->s_writepages_rwsem); lock(&journal->j_barrier); *** DEADLOCK *** 4 locks held by syz-executor.3/4231: #0: ffff888010016438 (sb_writers#3){.+.+}-{0:0}, at: do_vfs_ioctl+0xf87/0x1610 #1: ffff88801e595380 (&type->i_mutex_dir_key#3){++++}-{3:3}, at: vfs_fileattr_set+0x148/0xb80 #2: ffff88801e595520 (mapping.invalidate_lock){++++}-{3:3}, at: ext4_change_inode_journal_flag+0x11e/0x530 #3: ffff888010020bd0 (&sbi->s_writepages_rwsem){++++}-{0:0}, at: ext4_change_inode_journal_flag+0x177/0x530 stack backtrace: CPU: 1 PID: 4231 Comm: syz-executor.3 Not tainted 6.0.0-rc2-next-20220826 #1 Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.14.0-0-g155821a1990b-prebuilt.qemu.org 04/01/2014 Call Trace: dump_stack_lvl+0x8b/0xb3 check_noncircular+0x263/0x2e0 __lock_acquire+0x2a02/0x5e70 lock_acquire+0x1a2/0x530 __mutex_lock+0x136/0x14d0 jbd2_journal_lock_updates+0x15e/0x310 ext4_change_inode_journal_flag+0x17f/0x530 ext4_fileattr_set+0x140d/0x18a0 vfs_fileattr_set+0x77c/0xb80 do_vfs_ioctl+0xfc2/0x1610 __x64_sys_ioctl+0x10c/0x210 do_syscall_64+0x3b/0x90 entry_SYSCALL_64_after_hwframe+0x63/0xcd RIP: 0033:0x7f4a1ae86b19 Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 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 bc ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007f4a183fc188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f4a1af99f60 RCX: 00007f4a1ae86b19 RDX: 0000000020000080 RSI: 0000000040086602 RDI: 0000000000000003 RBP: 00007f4a1aee0f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff1f5e8eff R14: 00007f4a183fc300 R15: 0000000000022000 warning: checkpointing journal with EXT4_IOC_CHECKPOINT_FLAG_ZEROOUT can be slow UDC core: USB Raw Gadget: couldn't find an available UDC or it's busy UDC core: USB Raw Gadget: couldn't find an available UDC or it's busy misc raw-gadget: fail, usb_gadget_register_driver returned -16 misc raw-gadget: fail, usb_gadget_register_driver returned -16 audit: type=1326 audit(1661514177.568:18): auid=0 uid=0 gid=0 ses=4 subj=system_u:system_r:kernel_t:s0 pid=4245 comm="syz-executor.7" exe="/syz-executor.7" sig=31 arch=c000003e syscall=202 compat=0 ip=0x7f997bbfeb19 code=0x0 audit: type=1326 audit(1661514178.147:19): auid=0 uid=0 gid=0 ses=4 subj=system_u:system_r:kernel_t:s0 pid=4253 comm="syz-executor.7" exe="/syz-executor.7" sig=31 arch=c000003e syscall=202 compat=0 ip=0x7f997bbfeb19 code=0x0 warning: checkpointing journal with EXT4_IOC_CHECKPOINT_FLAG_ZEROOUT can be slow audit: type=1326 audit(1661514178.571:20): auid=0 uid=0 gid=0 ses=4 subj=system_u:system_r:kernel_t:s0 pid=4274 comm="syz-executor.7" exe="/syz-executor.7" sig=31 arch=c000003e syscall=202 compat=0 ip=0x7f997bbfeb19 code=0x0 warning: checkpointing journal with EXT4_IOC_CHECKPOINT_FLAG_ZEROOUT can be slow IPv6: RTM_NEWROUTE with no NLM_F_CREATE or NLM_F_REPLACE IPv6: NLM_F_CREATE should be set when creating new route kernel write not supported for file /sg0 (pid: 35 comm: kworker/1:1) kernel write not supported for file /sg0 (pid: 35 comm: kworker/1:1) loop4: detected capacity change from 0 to 40 kernel write not supported for file /sg0 (pid: 35 comm: kworker/1:1) kernel write not supported for file /sg0 (pid: 35 comm: kworker/1:1) loop4: detected capacity change from 0 to 40 9pnet_fd: Insufficient options for proto=fd Process accounting resumed Process accounting resumed kernel write not supported for file /sg0 (pid: 35 comm: kworker/1:1) loop4: detected capacity change from 0 to 40 loop2: detected capacity change from 0 to 40 Process accounting resumed Process accounting resumed loop2: detected capacity change from 0 to 40 audit: type=1400 audit(1661514180.661:21): avc: denied { block_suspend } for pid=4383 comm="syz-executor.6" capability=36 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 perf: interrupt took too long (4035 > 4028), lowering kernel.perf_event_max_sample_rate to 49000 perf: interrupt took too long (6325 > 6305), lowering kernel.perf_event_max_sample_rate to 31000 perf: interrupt took too long (7913 > 7906), lowering kernel.perf_event_max_sample_rate to 25000 perf: interrupt took too long (9895 > 9891), lowering kernel.perf_event_max_sample_rate to 20000 Process accounting resumed loop4: detected capacity change from 0 to 40 loop2: detected capacity change from 0 to 40 Process accounting resumed Process accounting resumed random: crng reseeded on system resumption loop4: detected capacity change from 0 to 40 perf: interrupt took too long (12417 > 12368), lowering kernel.perf_event_max_sample_rate to 16000 random: crng reseeded on system resumption perf: interrupt took too long (15522 > 15521), lowering kernel.perf_event_max_sample_rate to 12000 random: crng reseeded on system resumption Process accounting resumed random: crng reseeded on system resumption random: crng reseeded on system resumption loop7: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop7: unable to read partition table random: crng reseeded on system resumption loop7: partition table beyond EOD, truncated loop7: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 random: crng reseeded on system resumption random: crng reseeded on system resumption loop5: detected capacity change from 0 to 27 loop7: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop3: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop2: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop5: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop7: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop3: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop2: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop5: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop4: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop7: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop3: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 loop2: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.6'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.6'. No source specified No source specified loop4: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 tmpfs: Bad value for 'mpol' tmpfs: Bad value for 'mpol' loop1: detected capacity change from 0 to 320 loop4: detected capacity change from 0 to 27 SELinux: security_context_str_to_sid (sysadm_u) failed with errno=-22 ISO 9660 Extensions: Microsoft Joliet Level 3 ISO 9660 Extensions: RRIP_1991A UDC core: USB Raw Gadget: couldn't find an available UDC or it's busy misc raw-gadget: fail, usb_gadget_register_driver returned -16 mmap: syz-executor.5 (4693) uses deprecated remap_file_pages() syscall. See Documentation/mm/remap_file_pages.rst. UDC core: USB Raw Gadget: couldn't find an available UDC or it's busy misc raw-gadget: fail, usb_gadget_register_driver returned -16 syz-executor.3 (4710) used greatest stack depth: 24072 bytes left device syz_tun entered promiscuous mode loop7: detected capacity change from 0 to 76800 FAT-fs (loop7): bogus number of reserved sectors FAT-fs (loop7): Can't find a valid FAT filesystem loop7: detected capacity change from 0 to 76800 FAT-fs (loop7): bogus number of reserved sectors FAT-fs (loop7): Can't find a valid FAT filesystem device syz_tun left promiscuous mode device syz_tun entered promiscuous mode device syz_tun left promiscuous mode syz-executor.4 (4750) used greatest stack depth: 23880 bytes left device syz_tun entered promiscuous mode device syz_tun left promiscuous mode device syz_tun entered promiscuous mode loop5: detected capacity change from 0 to 264192 device syz_tun left promiscuous mode process 'syz-executor.6' launched './file1' with NULL argv: empty string added device syz_tun entered promiscuous mode Bluetooth: MGMT ver 1.22 cgroup: Unknown subsys name 'no' device syz_tun left promiscuous mode