================================ WARNING: inconsistent lock state 4.19.105-syzkaller #0 Not tainted -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. syz-executor.2/9244 [HC0[0]:SC1[1]:HE1:SE0] takes: 000000009a2a66c1 (rxrpc_conn_id_lock){+.?.}, at: spin_lock include/linux/spinlock.h:329 [inline] 000000009a2a66c1 (rxrpc_conn_id_lock){+.?.}, at: rxrpc_put_client_connection_id.part.0+0x19/0x70 net/rxrpc/conn_client.c:143 {SOFTIRQ-ON-W} state was registered at: lock_acquire+0x16f/0x3f0 kernel/locking/lockdep.c:3903 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2f/0x40 kernel/locking/spinlock.c:144 spin_lock include/linux/spinlock.h:329 [inline] rxrpc_get_client_connection_id net/rxrpc/conn_client.c:114 [inline] rxrpc_alloc_client_connection net/rxrpc/conn_client.c:198 [inline] rxrpc_get_client_conn net/rxrpc/conn_client.c:345 [inline] rxrpc_connect_call+0x948/0x4920 net/rxrpc/conn_client.c:702 rxrpc_new_client_call+0x8e7/0x1880 net/rxrpc/call_object.c:291 rxrpc_new_client_call_for_sendmsg net/rxrpc/sendmsg.c:596 [inline] rxrpc_do_sendmsg+0xfc3/0x1ca3 net/rxrpc/sendmsg.c:652 rxrpc_sendmsg+0x4a0/0x5b0 net/rxrpc/af_rxrpc.c:593 sock_sendmsg_nosec net/socket.c:622 [inline] sock_sendmsg+0xd7/0x130 net/socket.c:632 ___sys_sendmsg+0x3e2/0x920 net/socket.c:2115 __sys_sendmmsg+0x1bf/0x4e0 net/socket.c:2210 __do_sys_sendmmsg net/socket.c:2239 [inline] __se_sys_sendmmsg net/socket.c:2236 [inline] __x64_sys_sendmmsg+0x9d/0x100 net/socket.c:2236 do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe irq event stamp: 4148016 hardirqs last enabled at (4148016): [] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] hardirqs last enabled at (4148016): [] _raw_spin_unlock_irqrestore+0x6b/0xe0 kernel/locking/spinlock.c:184 hardirqs last disabled at (4148015): [] __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:108 [inline] hardirqs last disabled at (4148015): [] _raw_spin_lock_irqsave+0x6f/0xcd kernel/locking/spinlock.c:152 softirqs last enabled at (4147978): [] lock_sock_nested+0x9a/0x120 net/core/sock.c:2841 softirqs last disabled at (4147985): [] invoke_softirq kernel/softirq.c:372 [inline] softirqs last disabled at (4147985): [] irq_exit+0x180/0x1d0 kernel/softirq.c:412 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(rxrpc_conn_id_lock); lock(rxrpc_conn_id_lock); *** DEADLOCK *** 2 locks held by syz-executor.2/9244: #0: 00000000c06166e7 (sk_lock-AF_INET){+.+.}, at: lock_sock include/net/sock.h:1506 [inline] #0: 00000000c06166e7 (sk_lock-AF_INET){+.+.}, at: do_ip_getsockopt+0x270/0x1830 net/ipv4/ip_sockglue.c:1328 #1: 00000000e0004870 (rcu_callback){....}, at: __rcu_reclaim kernel/rcu/rcu.h:226 [inline] #1: 00000000e0004870 (rcu_callback){....}, at: rcu_do_batch kernel/rcu/tree.c:2584 [inline] #1: 00000000e0004870 (rcu_callback){....}, at: invoke_rcu_callbacks kernel/rcu/tree.c:2897 [inline] #1: 00000000e0004870 (rcu_callback){....}, at: __rcu_process_callbacks kernel/rcu/tree.c:2864 [inline] #1: 00000000e0004870 (rcu_callback){....}, at: rcu_process_callbacks+0xc79/0x1a30 kernel/rcu/tree.c:2881 stack backtrace: CPU: 1 PID: 9244 Comm: syz-executor.2 Not tainted 4.19.105-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x197/0x210 lib/dump_stack.c:118 print_usage_bug.cold+0x330/0x42a kernel/locking/lockdep.c:2540 valid_state kernel/locking/lockdep.c:2553 [inline] mark_lock_irq kernel/locking/lockdep.c:2747 [inline] mark_lock+0xd1b/0x1370 kernel/locking/lockdep.c:3127 mark_irqflags kernel/locking/lockdep.c:3005 [inline] __lock_acquire+0xc62/0x49c0 kernel/locking/lockdep.c:3368 lock_acquire+0x16f/0x3f0 kernel/locking/lockdep.c:3903 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2f/0x40 kernel/locking/spinlock.c:144 spin_lock include/linux/spinlock.h:329 [inline] rxrpc_put_client_connection_id.part.0+0x19/0x70 net/rxrpc/conn_client.c:143 rxrpc_put_client_connection_id include/linux/spinlock.h:370 [inline] rxrpc_put_one_client_conn net/rxrpc/conn_client.c:956 [inline] rxrpc_put_client_conn+0x6c6/0xc20 net/rxrpc/conn_client.c:1002 rxrpc_put_connection net/rxrpc/ar-internal.h:951 [inline] rxrpc_rcu_destroy_call+0xbd/0x200 net/rxrpc/call_object.c:657 __rcu_reclaim kernel/rcu/rcu.h:236 [inline] rcu_do_batch kernel/rcu/tree.c:2584 [inline] invoke_rcu_callbacks kernel/rcu/tree.c:2897 [inline] __rcu_process_callbacks kernel/rcu/tree.c:2864 [inline] rcu_process_callbacks+0xba0/0x1a30 kernel/rcu/tree.c:2881 __do_softirq+0x25c/0x921 kernel/softirq.c:292 invoke_softirq kernel/softirq.c:372 [inline] irq_exit+0x180/0x1d0 kernel/softirq.c:412 exiting_irq arch/x86/include/asm/apic.h:544 [inline] smp_apic_timer_interrupt+0x13b/0x550 arch/x86/kernel/apic/apic.c:1094 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:893 RIP: 0010:__raw_spin_unlock_irq include/linux/spinlock_api_smp.h:169 [inline] RIP: 0010:_raw_spin_unlock_irq+0x54/0x90 kernel/locking/spinlock.c:192 Code: c0 98 56 f2 88 48 ba 00 00 00 00 00 fc ff df 48 c1 e8 03 80 3c 10 00 75 33 48 83 3d 2d c1 9d 01 00 74 20 fb 66 0f 1f 44 00 00 01 00 00 00 e8 f2 07 f4 f9 65 8b 05 4b 58 ad 78 85 c0 74 06 41 RSP: 0018:ffff888086cb7758 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13 RAX: 1ffffffff11e4ad3 RBX: ffff88805ca42340 RCX: 1ffff1100b94857c RDX: dffffc0000000000 RSI: ffff88805ca42bc0 RDI: ffff88805ca42bbc RBP: ffff888086cb7760 R08: ffff88805ca42340 R09: ffff88805ca42be0 R10: 0000000000000000 R11: 0000000000000000 R12: ffff8880ae92c280 R13: ffff8880505c0240 R14: 0000000000000000 R15: 0000000000000001 finish_lock_switch kernel/sched/core.c:2576 [inline] finish_task_switch+0x146/0x7c0 kernel/sched/core.c:2676 context_switch kernel/sched/core.c:2829 [inline] __schedule+0x86e/0x1dc0 kernel/sched/core.c:3515 preempt_schedule_irq+0xb5/0x140 kernel/sched/core.c:3742 retint_kernel+0x1b/0x2d RIP: 0010:__sanitizer_cov_trace_switch+0x0/0x80 kernel/kcov.c:201 Code: 5d c3 0f 1f 84 00 00 00 00 00 55 48 89 f2 48 89 fe bf 07 00 00 00 48 89 e5 48 8b 4d 08 e8 98 fe ff ff 5d c3 66 0f 1f 44 00 00 <55> 48 89 fa 48 89 e5 41 55 41 54 49 89 f4 53 48 8b 46 08 48 83 f8 RSP: 0018:ffff888086cb7988 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13 RAX: 0000000080000000 RBX: 0000000000000044 RCX: 1ffff1100b94857c RDX: dffffc0000000000 RSI: ffffffff8851eca0 RDI: 0000000000000060 RBP: ffff888086cb7ce0 R08: ffff88805ca42340 R09: ffff88805ca42be0 R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000060 R13: ffff88806cbee800 R14: 0000000000000060 R15: 0000000000000000 ip_getsockopt+0x93/0x1d0 net/ipv4/ip_sockglue.c:1559 tcp_getsockopt net/ipv4/tcp.c:3603 [inline] tcp_getsockopt+0x8f/0xe0 net/ipv4/tcp.c:3597 sock_common_getsockopt+0x94/0xd0 net/core/sock.c:3005 __sys_getsockopt+0x15f/0x240 net/socket.c:1938 __do_sys_getsockopt net/socket.c:1949 [inline] __se_sys_getsockopt net/socket.c:1946 [inline] __x64_sys_getsockopt+0xbe/0x150 net/socket.c:1946 do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x45ef5a Code: b8 34 01 00 00 0f 05 48 3d 01 f0 ff ff 0f 83 8d 8b fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 49 89 ca b8 37 00 00 00 0f 05 <48> 3d 01 f0 ff ff 0f 83 6a 8b fb ff c3 66 0f 1f 84 00 00 00 00 00 RSP: 002b:00007fffe67ccaf8 EFLAGS: 00000246 ORIG_RAX: 0000000000000037 RAX: ffffffffffffffda RBX: 00007fffe67ccb10 RCX: 000000000045ef5a RDX: 0000000000000060 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 0000000000000003 R08: 00007fffe67ccb0c R09: 000000000000000a R10: 00007fffe67ccb10 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fffe67cd280 R14: 00000000000e918f R15: 00007fffe67cd290 kauditd_printk_skb: 10 callbacks suppressed audit: type=1804 audit(2000000128.340:617): pid=5144 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir808868453/syzkaller.BGtoTT/434/cgroup.controllers" dev="sda1" ino=16658 res=1 netlink: 4 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.0'. selinux_nlmsg_perm: 543 callbacks suppressed SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=5131 comm=syz-executor.0 audit: type=1804 audit(2000000129.140:618): pid=5200 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir808868453/syzkaller.BGtoTT/435/cgroup.controllers" dev="sda1" ino=16626 res=1 netlink: 4 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.0'. audit: type=1804 audit(2000000129.310:619): pid=5196 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.2" name="/root/syzkaller-testdir828824990/syzkaller.QUCPHd/424/cgroup.controllers" dev="sda1" ino=17268 res=1 audit: type=1804 audit(2000000129.360:620): pid=5199 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir937405563/syzkaller.xmrNa1/425/cgroup.controllers" dev="sda1" ino=17275 res=1 netlink: 4 bytes leftover after parsing attributes in process `syz-executor.2'. audit: type=1804 audit(2000000129.420:621): pid=5211 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir037087649/syzkaller.Nm3RfY/425/cgroup.controllers" dev="sda1" ino=17281 res=1 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.5'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.5'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.4'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.4'. audit: type=1804 audit(2000000129.540:622): pid=5315 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir096685184/syzkaller.krJMdO/413/cgroup.controllers" dev="sda1" ino=17269 res=1 audit: type=1804 audit(2000000130.130:623): pid=5428 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.2" name="/root/syzkaller-testdir828824990/syzkaller.QUCPHd/425/cgroup.controllers" dev="sda1" ino=17268 res=1 audit: type=1804 audit(2000000130.160:624): pid=5426 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir808868453/syzkaller.BGtoTT/436/cgroup.controllers" dev="sda1" ino=17263 res=1 audit: type=1804 audit(2000000130.370:625): pid=5537 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir937405563/syzkaller.xmrNa1/426/cgroup.controllers" dev="sda1" ino=17279 res=1 audit: type=1804 audit(2000000130.370:626): pid=5533 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir037087649/syzkaller.Nm3RfY/426/cgroup.controllers" dev="sda1" ino=17275 res=1