============================= WARNING: suspicious RCU usage 9pnet_virtio: no channels available for device ./file0 4.15.0-rc9+ #284 Not tainted ----------------------------- net/tipc/bearer.c:177 suspicious rcu_dereference_protected() usage! other info that might help us debug this: rcu_scheduler_active = 2, debug_locks = 1 2 locks held by syz-executor1/6717: #0: (cb_lock){++++}, at: [<00000000eb4c8afa>] genl_rcv+0x19/0x40 net/netlink/genetlink.c:634 #1: (genl_mutex){+.+.}, at: [<00000000166f4b02>] genl_lock net/netlink/genetlink.c:33 [inline] #1: (genl_mutex){+.+.}, at: [<00000000166f4b02>] genl_rcv_msg+0x115/0x140 net/netlink/genetlink.c:622 stack backtrace: CPU: 0 PID: 6717 Comm: syz-executor1 Not tainted 4.15.0-rc9+ #284 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 lockdep_rcu_suspicious+0x123/0x170 kernel/locking/lockdep.c:4587 tipc_bearer_find+0x2b4/0x3b0 net/tipc/bearer.c:177 tipc_nl_compat_link_set+0x329/0x9f0 net/tipc/netlink_compat.c:729 __tipc_nl_compat_doit net/tipc/netlink_compat.c:288 [inline] tipc_nl_compat_doit+0x15b/0x670 net/tipc/netlink_compat.c:335 tipc_nl_compat_handle net/tipc/netlink_compat.c:1119 [inline] tipc_nl_compat_recv+0x1135/0x18f0 net/tipc/netlink_compat.c:1201 genl_family_rcv_msg+0x7b7/0xfb0 net/netlink/genetlink.c:599 genl_rcv_msg+0xb2/0x140 net/netlink/genetlink.c:624 netlink_rcv_skb+0x14b/0x380 net/netlink/af_netlink.c:2409 genl_rcv+0x28/0x40 net/netlink/genetlink.c:635 netlink_unicast_kernel net/netlink/af_netlink.c:1275 [inline] netlink_unicast+0x4ee/0x700 net/netlink/af_netlink.c:1301 netlink_sendmsg+0xa4a/0xe60 net/netlink/af_netlink.c:1864 sock_sendmsg_nosec net/socket.c:638 [inline] sock_sendmsg+0xca/0x110 net/socket.c:648 ___sys_sendmsg+0x767/0x8b0 net/socket.c:2028 __sys_sendmsg+0xe5/0x210 net/socket.c:2062 SYSC_sendmsg net/socket.c:2073 [inline] SyS_sendmsg+0x2d/0x50 net/socket.c:2069 entry_SYSCALL_64_fastpath+0x29/0xa0 RIP: 0033:0x453299 RSP: 002b:00007fb1f3abdc58 EFLAGS: 00000212 ORIG_RAX: 000000000000002e RAX: ffffffffffffffda RBX: 000000000071bea0 RCX: 0000000000453299 RDX: 0000000000000000 RSI: 0000000020003000 RDI: 0000000000000013 RBP: 000000000000037c R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000212 R12: 00000000006f4440 R13: 00000000ffffffff R14: 00007fb1f3abe6d4 R15: 0000000000000000 rdma_op 00000000128537c7 conn xmit_rdma (null) rdma_op 00000000fa8820fa conn xmit_rdma (null) handle_userfault: 137 callbacks suppressed FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 1 PID: 6897 Comm: syz-executor2 Not tainted 4.15.0-rc9+ #284 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 handle_userfault+0x12fa/0x24c0 fs/userfaultfd.c:427 do_anonymous_page mm/memory.c:3127 [inline] handle_pte_fault mm/memory.c:3941 [inline] __handle_mm_fault+0x32a3/0x3ce0 mm/memory.c:4067 handle_mm_fault+0x334/0x8d0 mm/memory.c:4104 __do_page_fault+0x5c9/0xc90 arch/x86/mm/fault.c:1426 do_page_fault+0xee/0x720 arch/x86/mm/fault.c:1501 page_fault+0x2c/0x60 arch/x86/entry/entry_64.S:1260 RIP: 0010:fault_in_pages_readable include/linux/pagemap.h:601 [inline] RIP: 0010:iov_iter_fault_in_readable+0x1a7/0x410 lib/iov_iter.c:421 RSP: 0018:ffff8801c54df928 EFLAGS: 00010246 RAX: 0000000000010000 RBX: 0000000020011fd2 RCX: ffffffff82587fe1 RDX: 000000000000010b RSI: ffffc9000284a000 RDI: ffff8801c54dfd28 RBP: ffff8801c54dfa08 R08: ffff8801d67986d0 R09: 1ffff10038a9bee7 R10: ffff8801c54df858 R11: 0000000000000003 R12: 1ffff10038a9bf28 R13: ffff8801c54df9e0 R14: 0000000000000000 R15: ffff8801c54dfd20 generic_perform_write+0x200/0x600 mm/filemap.c:3129 __generic_file_write_iter+0x366/0x5b0 mm/filemap.c:3264 generic_file_write_iter+0x399/0x790 mm/filemap.c:3292 call_write_iter include/linux/fs.h:1772 [inline] new_sync_write fs/read_write.c:469 [inline] __vfs_write+0x684/0x970 fs/read_write.c:482 vfs_write+0x189/0x510 fs/read_write.c:544 SYSC_write fs/read_write.c:589 [inline] SyS_write+0xef/0x220 fs/read_write.c:581 entry_SYSCALL_64_fastpath+0x29/0xa0 RIP: 0033:0x453299 RSP: 002b:00007fe2225b7c58 EFLAGS: 00000212 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 000000000071bea0 RCX: 0000000000453299 RDX: 0000000000000030 RSI: 0000000020011fd2 RDI: 0000000000000014 RBP: 0000000000000647 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000212 R12: 00000000006f8748 R13: 00000000ffffffff R14: 00007fe2225b86d4 R15: 0000000000000000 IPv4: Oversized IP packet from 127.0.0.1 IPv4: Oversized IP packet from 127.0.0.1 audit: type=1326 audit(1517209056.354:386): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.358:387): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=230 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.394:388): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=219 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.419:389): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.419:390): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.430:391): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=9 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.431:392): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.431:393): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209056.437:394): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7105 comm="syz-executor1" exe="/root/syz-executor1" sig=0 arch=c000003e syscall=317 compat=0 ip=0x453299 code=0x7ffc0000 binder: 7145:7147 BC_ACQUIRE_DONE u0000000000000000 no match binder: 7145:7147 BC_FREE_BUFFER u0000000000000000 no match binder: 7145:7147 DecRefs 0 refcount change on invalid ref 4 ret -22 binder: 7145:7147 BC_DEAD_BINDER_DONE 0000000000000000 not found binder: 7145:7147 got reply transaction with no transaction stack binder: 7145:7147 transaction failed 29201/-71, size 0-24 line 2703 binder: 7145:7147 BC_ACQUIRE_DONE u0000000000000000 no match binder: 7145:7147 BC_FREE_BUFFER u0000000000000000 no match binder: 7145:7147 DecRefs 0 refcount change on invalid ref 4 ret -22 binder: 7145:7147 BC_DEAD_BINDER_DONE 0000000000000000 not found binder: 7145:7147 got reply transaction with no transaction stack binder: 7145:7147 transaction failed 29201/-71, size 0-24 line 2703 TCP: request_sock_TCPv6: Possible SYN flooding on port 20030. Sending cookies. Check SNMP counters. binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29201 netlink: 1 bytes leftover after parsing attributes in process `syz-executor3'. netlink: 1 bytes leftover after parsing attributes in process `syz-executor3'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. x_tables: ip_tables: socket match: used from hooks INPUT/OUTPUT, but only valid from PREROUTING/INPUT x_tables: ip_tables: socket match: used from hooks INPUT/OUTPUT, but only valid from PREROUTING/INPUT netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. binder: BINDER_SET_CONTEXT_MGR already set binder: 7527:7537 ioctl 40046207 0 returned -16 binder: send failed reply for transaction 15 to 7527:7537 binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_ERROR: 29189 sock: sock_set_timeout: `syz-executor7' (pid 7574) tries to set negative timeout sock: sock_set_timeout: `syz-executor7' (pid 7579) tries to set negative timeout netlink: 'syz-executor1': attribute type 1 has an invalid length. netlink: 'syz-executor1': attribute type 1 has an invalid length. sctp: [Deprecated]: syz-executor5 (pid 7790) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead sctp: [Deprecated]: syz-executor5 (pid 7790) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead IPv4: Oversized IP packet from 127.0.0.1 kauditd_printk_skb: 51 callbacks suppressed audit: type=1326 audit(1517209060.312:446): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:447): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:448): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=228 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:449): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:450): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=288 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:451): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:452): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=55 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:453): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 audit: type=1326 audit(1517209060.315:454): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=42 compat=0 ip=0x453299 code=0x7ffc0000 binder: 7906:7911 transaction failed 29189/-22, size 0-0 line 2788 binder: BINDER_SET_CONTEXT_MGR already set binder: 7906:7918 ioctl 40046207 0 returned -16 binder: 7906:7911 transaction failed 29189/-22, size 0-0 line 2788 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 audit: type=1326 audit(1517209060.315:455): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=7856 comm="syz-executor5" exe="/root/syz-executor5" sig=0 arch=c000003e syscall=202 compat=0 ip=0x453299 code=0x7ffc0000 SELinux: policydb version 1119089356 does not match my version range 15-31 SELinux: failed to load policy SELinux: policydb version 1119089356 does not match my version range 15-31 SELinux: failed to load policy xt_nfacct: accounting object with name `syz1' does not exists irq bypass consumer (token 00000000c3793b13) registration fails: -16 irq bypass consumer (token 00000000cdff53e2) registration fails: -16 xt_nfacct: accounting object with name `syz1' does not exists openvswitch: netlink: Flow key attr not present in new flow. QAT: Invalid ioctl openvswitch: netlink: Flow key attr not present in new flow. QAT: Invalid ioctl binder: 8450:8455 transaction failed 29189/-22, size 0-0 line 2788 binder: 8450:8455 ioctl c0306201 20008fd0 returned -14 binder: 8450:8467 transaction failed 29189/-22, size 0-0 line 2788 binder: 8450:8467 ioctl c0306201 20008fd0 returned -14 binder: undelivered TRANSACTION_ERROR: 29189 QAT: Stopping all acceleration devices. QAT: Invalid ioctl QAT: Invalid ioctl QAT: Stopping all acceleration devices. ================================ WARNING: inconsistent lock state 4.15.0-rc9+ #284 Not tainted -------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. syz-executor5/8606 [HC0[0]:SC1[1]:HE1:SE0] takes: (&(&est->lock)->rlock){+.?.}, at: [<000000006790c885>] spin_lock include/linux/spinlock.h:310 [inline] (&(&est->lock)->rlock){+.?.}, at: [<000000006790c885>] est_fetch_counters+0x4f/0x150 net/core/gen_estimator.c:70 {SOFTIRQ-ON-W} state was registered at: lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:3915 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:144 spin_lock include/linux/spinlock.h:310 [inline] est_fetch_counters+0x4f/0x150 net/core/gen_estimator.c:70 gen_new_estimator+0x317/0x770 net/core/gen_estimator.c:162 xt_rateest_tg_checkentry+0x487/0xaa0 net/netfilter/xt_RATEEST.c:135 xt_check_target+0x22c/0x7d0 net/netfilter/x_tables.c:845 check_target net/ipv6/netfilter/ip6_tables.c:538 [inline] find_check_entry.isra.7+0x935/0xcf0 net/ipv6/netfilter/ip6_tables.c:580 translate_table+0xf52/0x1690 net/ipv6/netfilter/ip6_tables.c:749 do_replace net/ipv6/netfilter/ip6_tables.c:1167 [inline] do_ip6t_set_ctl+0x370/0x5f0 net/ipv6/netfilter/ip6_tables.c:1693 nf_sockopt net/netfilter/nf_sockopt.c:106 [inline] nf_setsockopt+0x67/0xc0 net/netfilter/nf_sockopt.c:115 ipv6_setsockopt+0x115/0x150 net/ipv6/ipv6_sockglue.c:928 udpv6_setsockopt+0x45/0x80 net/ipv6/udp.c:1452 sock_common_setsockopt+0x95/0xd0 net/core/sock.c:2968 SYSC_setsockopt net/socket.c:1831 [inline] SyS_setsockopt+0x189/0x360 net/socket.c:1810 entry_SYSCALL_64_fastpath+0x29/0xa0 irq event stamp: 3688 hardirqs last enabled at (3688): [<0000000074edf17f>] restore_regs_and_return_to_kernel+0x0/0x21 hardirqs last disabled at (3687): [<00000000d0919b79>] apic_timer_interrupt+0xa4/0xb0 arch/x86/entry/entry_64.S:937 softirqs last enabled at (3562): [<000000001c174965>] __do_softirq+0x7a0/0xb85 kernel/softirq.c:311 softirqs last disabled at (3627): [<000000009d96ad8a>] invoke_softirq kernel/softirq.c:365 [inline] softirqs last disabled at (3627): [<000000009d96ad8a>] irq_exit+0x1cc/0x200 kernel/softirq.c:405 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&(&est->lock)->rlock); lock(&(&est->lock)->rlock); *** DEADLOCK *** 2 locks held by syz-executor5/8606: #0: (&anon_vma->rwsem){++++}, at: [<000000008a8a2f68>] lock_anon_vma_root mm/rmap.c:237 [inline] #0: (&anon_vma->rwsem){++++}, at: [<000000008a8a2f68>] unlink_anon_vmas+0x2bc/0x9f0 mm/rmap.c:387 #1: ((&est->timer)){+.-.}, at: [<00000000ffa81ccb>] lockdep_copy_map include/linux/lockdep.h:178 [inline] #1: ((&est->timer)){+.-.}, at: [<00000000ffa81ccb>] call_timer_fn+0x1c6/0x820 kernel/time/timer.c:1308 stack backtrace: CPU: 1 PID: 8606 Comm: syz-executor5 Not tainted 4.15.0-rc9+ #284 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 print_usage_bug+0x377/0x38c kernel/locking/lockdep.c:2538 valid_state kernel/locking/lockdep.c:2551 [inline] mark_lock_irq kernel/locking/lockdep.c:2745 [inline] mark_lock+0xf61/0x1430 kernel/locking/lockdep.c:3143 mark_irqflags kernel/locking/lockdep.c:3021 [inline] __lock_acquire+0x173a/0x3e00 kernel/locking/lockdep.c:3384 lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:3915 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:144 spin_lock include/linux/spinlock.h:310 [inline] est_fetch_counters+0x4f/0x150 net/core/gen_estimator.c:70 est_timer+0x97/0x7c0 net/core/gen_estimator.c:85 call_timer_fn+0x228/0x820 kernel/time/timer.c:1318 expire_timers kernel/time/timer.c:1355 [inline] __run_timers+0x7ee/0xb70 kernel/time/timer.c:1658