================================ WARNING: inconsistent lock state 4.19.106-syzkaller #0 Not tainted -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. kworker/0:1/14 [HC0[0]:SC1[1]:HE1:SE0] takes: 00000000a034be61 (rxrpc_conn_id_lock){+.?.}, at: spin_lock include/linux/spinlock.h:329 [inline] 00000000a034be61 (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: 1169034 hardirqs last enabled at (1169034): [] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] hardirqs last enabled at (1169034): [] _raw_spin_unlock_irqrestore+0x6b/0xe0 kernel/locking/spinlock.c:184 hardirqs last disabled at (1169033): [] __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:108 [inline] hardirqs last disabled at (1169033): [] _raw_spin_lock_irqsave+0x6f/0xcd kernel/locking/spinlock.c:152 softirqs last enabled at (1167196): [] spin_unlock_bh include/linux/spinlock.h:374 [inline] softirqs last enabled at (1167196): [] rxrpc_peer_keepalive_dispatch net/rxrpc/peer_event.c:388 [inline] softirqs last enabled at (1167196): [] rxrpc_peer_keepalive_worker+0x8ee/0xe68 net/rxrpc/peer_event.c:434 softirqs last disabled at (1168643): [] invoke_softirq kernel/softirq.c:372 [inline] softirqs last disabled at (1168643): [] 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 *** 4 locks held by kworker/0:1/14: #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline] #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline] #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline] #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline] #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: 000000004b4d956d ((wq_completion)"rcu_gp"){+.+.}, at: process_one_work+0x87e/0x1750 kernel/workqueue.c:2124 #1: 00000000dfb5133b ((work_completion)(&(&sp->work)->work)){+.+.}, at: process_one_work+0x8b4/0x1750 kernel/workqueue.c:2128 #2: 000000008a45d44d (&sp->srcu_gp_mutex){+.+.}, at: srcu_advance_state kernel/rcu/srcutree.c:1109 [inline] #2: 000000008a45d44d (&sp->srcu_gp_mutex){+.+.}, at: process_srcu+0x2f/0xec0 kernel/rcu/srcutree.c:1251 #3: 000000002cc0d87e (rcu_callback){....}, at: __rcu_reclaim kernel/rcu/rcu.h:226 [inline] #3: 000000002cc0d87e (rcu_callback){....}, at: rcu_do_batch kernel/rcu/tree.c:2584 [inline] #3: 000000002cc0d87e (rcu_callback){....}, at: invoke_rcu_callbacks kernel/rcu/tree.c:2897 [inline] #3: 000000002cc0d87e (rcu_callback){....}, at: __rcu_process_callbacks kernel/rcu/tree.c:2864 [inline] #3: 000000002cc0d87e (rcu_callback){....}, at: rcu_process_callbacks+0xc79/0x1a30 kernel/rcu/tree.c:2881 stack backtrace: CPU: 0 PID: 14 Comm: kworker/0:1 Not tainted 4.19.106-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: rcu_gp process_srcu 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:__sanitizer_cov_trace_pc+0x1/0x50 kernel/kcov.c:94 Code: bc f4 ff ff ff e8 73 60 ea ff 48 c7 05 34 ef 5d 09 00 00 00 00 e9 96 e9 ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 55 <48> 89 e5 48 8b 75 08 65 48 8b 04 25 40 ee 01 00 65 8b 15 18 11 97 RSP: 0018:ffff8880aa3afb90 EFLAGS: 00000242 ORIG_RAX: ffffffffffffff13 RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffffffff8367d9ab RDX: 0000000000000003 RSI: 0000000000000000 RDI: 0000000000000007 RBP: ffff8880aa3afbc8 R08: ffff8880aa3a0380 R09: 00000000ffffffff R10: ffff8880aa3afc50 R11: ffffffff8aca5f47 R12: 0000000000000000 R13: 0000000000000040 R14: 0000000000000000 R15: ffffffff89cd2c60 cpumask_next+0x41/0x50 lib/cpumask.c:21 srcu_readers_unlock_idx kernel/rcu/srcutree.c:277 [inline] srcu_readers_active_idx_check kernel/rcu/srcutree.c:293 [inline] try_check_zero+0xc7/0x330 kernel/rcu/srcutree.c:719 srcu_advance_state kernel/rcu/srcutree.c:1157 [inline] process_srcu+0x329/0xec0 kernel/rcu/srcutree.c:1251 process_one_work+0x989/0x1750 kernel/workqueue.c:2153 worker_thread+0x98/0xe40 kernel/workqueue.c:2296 kthread+0x354/0x420 kernel/kthread.c:246 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639475.735:259): pid=25327 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.3" name="/root/syzkaller-testdir129423374/syzkaller.u0lFzT/1268/bus" dev="sda1" ino=17139 res=1 audit: type=1804 audit(1582639475.735:260): pid=25327 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.3" name="/root/syzkaller-testdir129423374/syzkaller.u0lFzT/1268/bus" dev="sda1" ino=17139 res=1 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639476.025:261): pid=25554 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.3" name="/root/syzkaller-testdir129423374/syzkaller.u0lFzT/1269/bus" dev="sda1" ino=17112 res=1 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639476.255:262): pid=25579 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.3" name="/root/syzkaller-testdir129423374/syzkaller.u0lFzT/1270/bus" dev="sda1" ino=17131 res=1 audit: type=1804 audit(1582639476.255:263): pid=25583 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-testdir510219939/syzkaller.Qtktu4/1394/bus" dev="sda1" ino=17218 res=1 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639476.315:264): pid=25586 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-testdir018099281/syzkaller.jKt5SH/1319/bus" dev="sda1" ino=17282 res=1 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639476.475:265): pid=25600 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-testdir510219939/syzkaller.Qtktu4/1395/bus" dev="sda1" ino=17131 res=1 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639476.505:266): pid=25602 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.3" name="/root/syzkaller-testdir129423374/syzkaller.u0lFzT/1271/bus" dev="sda1" ino=17218 res=1 netlink: 49 bytes leftover after parsing attributes in process `syz-executor.1'. audit: type=1804 audit(1582639476.545:267): pid=25605 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-testdir018099281/syzkaller.jKt5SH/1320/bus" dev="sda1" ino=17077 res=1 misc userio: Can't change port type on an already running userio instance audit: type=1804 audit(1582639476.675:268): pid=25619 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-testdir510219939/syzkaller.Qtktu4/1396/bus" dev="sda1" ino=16755 res=1 misc userio: Can't change port type on an already running userio instance netlink: 49 bytes leftover after parsing attributes in process `syz-executor.1'. misc userio: Can't change port type on an already running userio instance netlink: 49 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 49 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 49 bytes leftover after parsing attributes in process `syz-executor.3'. ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP netlink: 49 bytes leftover after parsing attributes in process `syz-executor.3'. ipt_CLUSTERIP: Please specify destination IP netlink: 49 bytes leftover after parsing attributes in process `syz-executor.3'. xt_CT: netfilter: NOTRACK target is deprecated, use CT instead or upgrade iptables ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP ipt_CLUSTERIP: Please specify destination IP