audit: type=1804 audit(1582352267.128:80): pid=9180 uid=0 auid=4294967295 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 op=invalid_pcr cause=ToMToU comm="syz-executor.0" name="/root/syzkaller-testdir235417398/syzkaller.EuP1iq/31/bus" dev="sda1" ino=16650 res=1 Started in network mode ================================ WARNING: inconsistent lock state 4.19.105-syzkaller #0 Not tainted -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. ksoftirqd/1/18 [HC0[0]:SC1[1]:HE1:SE0] takes: 0000000001bc6a90 (rxrpc_conn_id_lock){+.?.}, at: spin_lock include/linux/spinlock.h:329 [inline] 0000000001bc6a90 (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: 650810 hardirqs last enabled at (650810): [] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] hardirqs last enabled at (650810): [] _raw_spin_unlock_irqrestore+0x6b/0xe0 kernel/locking/spinlock.c:184 hardirqs last disabled at (650809): [] __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:108 [inline] hardirqs last disabled at (650809): [] _raw_spin_lock_irqsave+0x6f/0xcd kernel/locking/spinlock.c:152 softirqs last enabled at (650014): [] __do_softirq+0x633/0x921 kernel/softirq.c:318 softirqs last disabled at (650021): [] run_ksoftirqd kernel/softirq.c:653 [inline] softirqs last disabled at (650021): [] run_ksoftirqd+0x8e/0x110 kernel/softirq.c:645 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(rxrpc_conn_id_lock); lock(rxrpc_conn_id_lock); *** DEADLOCK *** 1 lock held by ksoftirqd/1/18: #0: 00000000b5c99b14 (rcu_callback){....}, at: __rcu_reclaim kernel/rcu/rcu.h:226 [inline] #0: 00000000b5c99b14 (rcu_callback){....}, at: rcu_do_batch kernel/rcu/tree.c:2584 [inline] #0: 00000000b5c99b14 (rcu_callback){....}, at: invoke_rcu_callbacks kernel/rcu/tree.c:2897 [inline] #0: 00000000b5c99b14 (rcu_callback){....}, at: __rcu_process_callbacks kernel/rcu/tree.c:2864 [inline] #0: 00000000b5c99b14 (rcu_callback){....}, at: rcu_process_callbacks+0xc79/0x1a30 kernel/rcu/tree.c:2881 stack backtrace: CPU: 1 PID: 18 Comm: ksoftirqd/1 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 run_ksoftirqd kernel/softirq.c:653 [inline] run_ksoftirqd+0x8e/0x110 kernel/softirq.c:645 smpboot_thread_fn+0x6a3/0xa30 kernel/smpboot.c:164 kthread+0x354/0x420 kernel/kthread.c:246 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Own node identity aaaaaaaaaa32, cluster identity 4711 audit: type=1400 audit(1582352267.668:81): avc: denied { name_bind } for pid=9201 comm="syz-executor.2" src=20000 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:port_t:s0 tclass=dccp_socket permissive=1 Enabled bearer , priority 0 audit: type=1400 audit(1582352267.668:82): avc: denied { node_bind } for pid=9201 comm="syz-executor.2" src=20000 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:node_t:s0 tclass=dccp_socket permissive=1 TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies. Check SNMP counters. SELinux: Context  is not valid (left unmapped). audit: type=1400 audit(1582352267.808:83): avc: denied { name_connect } for pid=9201 comm="syz-executor.2" dest=20000 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:port_t:s0 tclass=dccp_socket permissive=1 device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode 32-bit node address hash set to aaaa9800 caif:caif_disconnect_client(): nothing to disconnect caif:caif_disconnect_client(): nothing to disconnect syz-executor.5 (9215) used greatest stack depth: 21408 bytes left netlink: 8 bytes leftover after parsing attributes in process `syz-executor.4'. device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode netlink: 8 bytes leftover after parsing attributes in process `syz-executor.4'. device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode kauditd_printk_skb: 19 callbacks suppressed audit: type=1804 audit(1582352271.198:103): pid=9384 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-testdir870343092/syzkaller.h9bd3b/39/bus" dev="sda1" ino=16703 res=1 device ip6tnl1 entered promiscuous mode audit: type=1804 audit(1582352271.198:104): pid=9384 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-testdir870343092/syzkaller.h9bd3b/39/bus" dev="sda1" ino=16703 res=1 audit: type=1804 audit(1582352271.208:105): pid=9384 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-testdir870343092/syzkaller.h9bd3b/39/bus" dev="sda1" ino=16703 res=1 device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode audit: type=1804 audit(1582352271.798:106): pid=9412 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-testdir870343092/syzkaller.h9bd3b/40/bus" dev="sda1" ino=16703 res=1 device ip6tnl1 entered promiscuous mode audit: type=1804 audit(1582352271.868:107): pid=9412 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-testdir870343092/syzkaller.h9bd3b/40/bus" dev="sda1" ino=16703 res=1 audit: type=1804 audit(1582352272.148:108): pid=9428 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-testdir870343092/syzkaller.h9bd3b/41/bus" dev="sda1" ino=16697 res=1 device ip6tnl1 entered promiscuous mode audit: type=1804 audit(1582352272.148:109): pid=9428 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-testdir870343092/syzkaller.h9bd3b/41/bus" dev="sda1" ino=16697 res=1 device ip6tnl1 entered promiscuous mode audit: type=1804 audit(1582352272.158:110): pid=9428 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-testdir870343092/syzkaller.h9bd3b/41/bus" dev="sda1" ino=16697 res=1 audit: type=1804 audit(1582352272.348:111): pid=9445 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-testdir870343092/syzkaller.h9bd3b/42/bus" dev="sda1" ino=16699 res=1 audit: type=1804 audit(1582352272.348:112): pid=9445 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-testdir870343092/syzkaller.h9bd3b/42/bus" dev="sda1" ino=16699 res=1 device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode device ip6tnl1 entered promiscuous mode kauditd_printk_skb: 37 callbacks suppressed audit: type=1804 audit(1582352276.358:150): pid=9721 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-testdir870343092/syzkaller.h9bd3b/55/bus" dev="sda1" ino=16737 res=1 audit: type=1804 audit(1582352276.398:151): pid=9721 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-testdir870343092/syzkaller.h9bd3b/55/bus" dev="sda1" ino=16737 res=1 audit: type=1804 audit(1582352276.408:152): pid=9721 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-testdir870343092/syzkaller.h9bd3b/55/bus" dev="sda1" ino=16737 res=1 audit: type=1804 audit(1582352276.608:153): pid=9746 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-testdir870343092/syzkaller.h9bd3b/56/bus" dev="sda1" ino=16729 res=1 audit: type=1804 audit(1582352276.608:154): pid=9746 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-testdir870343092/syzkaller.h9bd3b/56/bus" dev="sda1" ino=16729 res=1 audit: type=1804 audit(1582352276.778:155): pid=9757 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-testdir870343092/syzkaller.h9bd3b/57/bus" dev="sda1" ino=16735 res=1 audit: type=1804 audit(1582352276.778:156): pid=9757 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-testdir870343092/syzkaller.h9bd3b/57/bus" dev="sda1" ino=16735 res=1 audit: type=1804 audit(1582352276.988:157): pid=9779 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-testdir870343092/syzkaller.h9bd3b/58/bus" dev="sda1" ino=16734 res=1 audit: type=1804 audit(1582352277.008:158): pid=9779 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-testdir870343092/syzkaller.h9bd3b/58/bus" dev="sda1" ino=16734 res=1