audit: type=1804 audit(1677261119.983:5): pid=9499 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir2952980710/syzkaller.T0kUiq/1/file0/bus" dev="loop1" ino=10 res=1 EXT4-fs warning (device sda1): ext4_group_extend:1795: can't shrink FS - resize aborted ================================ WARNING: inconsistent lock state 4.14.306-syzkaller #0 Not tainted -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. syz-executor.2/9555 [HC0[0]:SC1[1]:HE1:SE0] takes: (&(&local->client_conns_lock)->rlock){+.?.}, at: [] spin_lock include/linux/spinlock.h:317 [inline] (&(&local->client_conns_lock)->rlock){+.?.}, at: [] rxrpc_put_one_client_conn net/rxrpc/conn_client.c:905 [inline] (&(&local->client_conns_lock)->rlock){+.?.}, at: [] rxrpc_put_client_conn+0x661/0xac0 net/rxrpc/conn_client.c:957 {SOFTIRQ-ON-W} state was registered at: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:152 spin_lock include/linux/spinlock.h:317 [inline] rxrpc_get_client_conn net/rxrpc/conn_client.c:306 [inline] rxrpc_connect_call+0x2bb/0x3e10 net/rxrpc/conn_client.c:692 rxrpc_new_client_call+0x8f4/0x1a10 net/rxrpc/call_object.c:276 rxrpc_new_client_call_for_sendmsg net/rxrpc/sendmsg.c:531 [inline] rxrpc_do_sendmsg+0x8dc/0xfb0 net/rxrpc/sendmsg.c:583 rxrpc_sendmsg+0x3cf/0x5f0 net/rxrpc/af_rxrpc.c:543 sock_sendmsg_nosec net/socket.c:646 [inline] sock_sendmsg+0xb5/0x100 net/socket.c:656 ___sys_sendmsg+0x6c8/0x800 net/socket.c:2062 __sys_sendmsg+0xa3/0x120 net/socket.c:2096 SYSC_sendmsg net/socket.c:2107 [inline] SyS_sendmsg+0x27/0x40 net/socket.c:2103 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 irq event stamp: 1652 hardirqs last enabled at (1652): [] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] hardirqs last enabled at (1652): [] _raw_spin_unlock_irqrestore+0x79/0xe0 kernel/locking/spinlock.c:192 hardirqs last disabled at (1651): [] __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:108 [inline] hardirqs last disabled at (1651): [] _raw_spin_lock_irqsave+0x66/0xc0 kernel/locking/spinlock.c:160 softirqs last enabled at (1100): [] __do_softirq+0x68b/0x9ff kernel/softirq.c:314 softirqs last disabled at (1609): [] invoke_softirq kernel/softirq.c:368 [inline] softirqs last disabled at (1609): [] irq_exit+0x193/0x240 kernel/softirq.c:409 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&(&local->client_conns_lock)->rlock); lock(&(&local->client_conns_lock)->rlock); *** DEADLOCK *** 3 locks held by syz-executor.2/9555: #0: (sb_writers#3){.+.+}, at: [] sb_start_write include/linux/fs.h:1551 [inline] #0: (sb_writers#3){.+.+}, at: [] mnt_want_write_file+0xfd/0x3b0 fs/namespace.c:497 #1: (console_lock){+.+.}, at: [] vprintk_func+0x58/0x160 kernel/printk/printk_safe.c:409 #2: (rcu_callback){....}, at: [] __rcu_reclaim kernel/rcu/rcu.h:185 [inline] #2: (rcu_callback){....}, at: [] rcu_do_batch kernel/rcu/tree.c:2699 [inline] #2: (rcu_callback){....}, at: [] invoke_rcu_callbacks kernel/rcu/tree.c:2962 [inline] #2: (rcu_callback){....}, at: [] __rcu_process_callbacks kernel/rcu/tree.c:2929 [inline] #2: (rcu_callback){....}, at: [] rcu_process_callbacks+0x84e/0x1180 kernel/rcu/tree.c:2946 stack backtrace: CPU: 1 PID: 9555 Comm: syz-executor.2 Not tainted 4.14.306-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/16/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 print_usage_bug.cold+0x42e/0x570 kernel/locking/lockdep.c:2589 valid_state kernel/locking/lockdep.c:2602 [inline] mark_lock_irq kernel/locking/lockdep.c:2796 [inline] mark_lock+0xb4d/0x1050 kernel/locking/lockdep.c:3194 mark_irqflags kernel/locking/lockdep.c:3072 [inline] __lock_acquire+0xc81/0x3f20 kernel/locking/lockdep.c:3448 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:152 spin_lock include/linux/spinlock.h:317 [inline] rxrpc_put_one_client_conn net/rxrpc/conn_client.c:905 [inline] rxrpc_put_client_conn+0x661/0xac0 net/rxrpc/conn_client.c:957 rxrpc_put_connection net/rxrpc/ar-internal.h:862 [inline] rxrpc_rcu_destroy_call+0x83/0x190 net/rxrpc/call_object.c:653 __rcu_reclaim kernel/rcu/rcu.h:195 [inline] rcu_do_batch kernel/rcu/tree.c:2699 [inline] invoke_rcu_callbacks kernel/rcu/tree.c:2962 [inline] __rcu_process_callbacks kernel/rcu/tree.c:2929 [inline] rcu_process_callbacks+0x780/0x1180 kernel/rcu/tree.c:2946 __do_softirq+0x24d/0x9ff kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x193/0x240 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:638 [inline] smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1106 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:796 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline] RIP: 0010:console_unlock+0xbeb/0xf20 kernel/printk/printk.c:2417 RSP: 0018:ffff8880522af840 EFLAGS: 00000216 ORIG_RAX: ffffffffffffff10 RAX: 0000000000040000 RBX: 0000000000000200 RCX: ffffc900079ef000 RDX: 000000000000bf99 RSI: ffffffff814412f4 RDI: 0000000000000216 RBP: 0000000000000000 R08: ffffffff8ba5265c R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff83d287b0 R13: ffffffff8961f2b0 R14: dffffc0000000000 R15: 0000000000000067 vprintk_emit+0x224/0x620 kernel/printk/printk.c:1925 vprintk_func+0x58/0x160 kernel/printk/printk_safe.c:409 printk+0x9e/0xbc kernel/printk/printk.c:1998 __ext4_warning.cold+0x55/0x5a fs/ext4/super.c:707 ext4_group_extend+0x399/0x4a0 fs/ext4/resize.c:1795 ext4_ioctl+0x20bb/0x3820 fs/ext4/ioctl.c:773 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:0x7f8190bef0f9 RSP: 002b:00007f818f161168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f8190d0ef80 RCX: 00007f8190bef0f9 RDX: 0000000020000040 RSI: 0000000040086607 RDI: 0000000000000007 RBP: 00007f8190c4aae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffef3bb777f R14: 00007f818f161300 R15: 0000000000022000 attempt to access beyond end of device loop1: rw=2049, want=40432, limit=40427 F2FS-fs (loop0): Found nat_bits in checkpoint EXT4-fs warning (device sda1): ext4_group_extend:1795: can't shrink FS - resize aborted F2FS-fs (loop0): Mounted with checkpoint version = 753bd00b EXT4-fs warning (device sda1): ext4_group_extend:1795: can't shrink FS - resize aborted EXT4-fs warning (device sda1): ext4_group_extend:1795: can't shrink FS - resize aborted F2FS-fs (loop1): Found nat_bits in checkpoint F2FS-fs (loop1): Mounted with checkpoint version = 48b305e5 audit: type=1804 audit(1677261122.923:6): pid=9607 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir2952980710/syzkaller.T0kUiq/2/file0/bus" dev="loop1" ino=10 res=1 audit: type=1804 audit(1677261122.923:7): pid=9607 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir2952980710/syzkaller.T0kUiq/2/file0/bus" dev="loop1" ino=10 res=1 audit: type=1804 audit(1677261123.033:8): pid=9663 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir1537574938/syzkaller.sJfxwh/2/bus" dev="sda1" ino=13939 res=1 audit: type=1800 audit(1677261123.033:9): pid=9663 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=13939 res=0 attempt to access beyond end of device loop1: rw=2049, want=40432, limit=40427 IPVS: ftp: loaded support on port[0] = 21 audit: type=1804 audit(1677261124.693:10): pid=9714 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir1537574938/syzkaller.sJfxwh/3/bus" dev="sda1" ino=13940 res=1 audit: type=1800 audit(1677261124.693:11): pid=9714 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=13940 res=0 F2FS-fs (loop1): Found nat_bits in checkpoint F2FS-fs (loop1): Mounted with checkpoint version = 48b305e5 audit: type=1804 audit(1677261125.583:12): pid=9750 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir2952980710/syzkaller.T0kUiq/3/file0/bus" dev="loop1" ino=10 res=1 audit: type=1804 audit(1677261125.613:13): pid=9750 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir2952980710/syzkaller.T0kUiq/3/file0/bus" dev="loop1" ino=10 res=1 attempt to access beyond end of device loop1: rw=2049, want=40432, limit=40427 audit: type=1804 audit(1677261126.173:14): pid=9789 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir1537574938/syzkaller.sJfxwh/4/bus" dev="sda1" ino=13966 res=1 audit: type=1800 audit(1677261126.173:15): pid=9789 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=13966 res=0 audit: type=1804 audit(1677261127.893:16): pid=9801 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.2" name="/root/syzkaller-testdir1787388061/syzkaller.1wV1wx/11/bus" dev="sda1" ino=13974 res=1 audit: type=1800 audit(1677261127.893:17): pid=9801 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="bus" dev="sda1" ino=13974 res=0 audit: type=1804 audit(1677261127.903:18): pid=9800 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir1898319/syzkaller.OJw2vm/12/bus" dev="sda1" ino=13973 res=1 audit: type=1800 audit(1677261127.903:19): pid=9800 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="sda1" ino=13973 res=0 ntfs: volume version 3.1.