===================================== WARNING: bad unlock balance detected! 4.16.0-rc2+ #323 Not tainted ------------------------------------- syz-executor5/5518 is trying to release lock (rcu_read_lock_bh) at: [] rcu_read_unlock_bh include/linux/rcupdate.h:722 [inline] [] hashlimit_mt_common.isra.10+0x1beb/0x2610 net/netfilter/xt_hashlimit.c:777 but there are no more locks to release! other info that might help us debug this: 1 lock held by syz-executor5/5518: #0: (rcu_read_lock){....}, at: [<00000000371cfb23>] nf_hook include/linux/netfilter.h:206 [inline] #0: (rcu_read_lock){....}, at: [<00000000371cfb23>] __ip6_local_out+0x2f1/0xaa0 net/ipv6/output_core.c:164 stack backtrace: CPU: 1 PID: 5518 Comm: syz-executor5 Not tainted 4.16.0-rc2+ #323 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_unlock_imbalance_bug+0x12f/0x140 kernel/locking/lockdep.c:3484 __lock_release kernel/locking/lockdep.c:3691 [inline] lock_release+0x6fe/0xa40 kernel/locking/lockdep.c:3939 rcu_lock_release include/linux/rcupdate.h:249 [inline] rcu_read_unlock_bh include/linux/rcupdate.h:724 [inline] hashlimit_mt_common.isra.10+0x1c08/0x2610 net/netfilter/xt_hashlimit.c:777 hashlimit_mt+0x78/0x90 net/netfilter/xt_hashlimit.c:846 ip6t_do_table+0x98d/0x1a30 net/ipv6/netfilter/ip6_tables.c:319 ip6table_raw_hook+0x65/0x80 net/ipv6/netfilter/ip6table_raw.c:42 nf_hook_entry_hookfn include/linux/netfilter.h:120 [inline] nf_hook_slow+0xba/0x1a0 net/netfilter/core.c:483 nf_hook include/linux/netfilter.h:243 [inline] __ip6_local_out+0x517/0xaa0 net/ipv6/output_core.c:164 ip6_local_out+0x2d/0x160 net/ipv6/output_core.c:174 ip6_send_skb+0xa1/0x330 net/ipv6/ip6_output.c:1677 udp_v6_send_skb+0x5ee/0xf70 net/ipv6/udp.c:1044 udpv6_sendmsg+0x2835/0x3400 net/ipv6/udp.c:1316 inet_sendmsg+0x11f/0x5e0 net/ipv4/af_inet.c:764 sock_sendmsg_nosec net/socket.c:630 [inline] sock_sendmsg+0xca/0x110 net/socket.c:640 SYSC_sendto+0x361/0x5c0 net/socket.c:1747 SyS_sendto+0x40/0x50 net/socket.c:1715 do_syscall_64+0x280/0x940 arch/x86/entry/common.c:287 entry_SYSCALL_64_after_hwframe+0x42/0xb7 RIP: 0033:0x453da9 RSP: 002b:00007f06cc570c68 EFLAGS: 00000246 ORIG_RAX: 000000000000002c RAX: ffffffffffffffda RBX: 00007f06cc5716d4 RCX: 0000000000453da9 RDX: 00000000000001df RSI: 0000000020867000 RDI: 0000000000000016 RBP: 000000000072bf58 R08: 000000002064afe4 R09: 000000000000001c R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 00000000000004ba R14: 00000000006f7210 R15: 0000000000000001 x_tables: ip6_tables: TCPOPTSTRIP target: only valid for protocol 6 x_tables: ip6_tables: TCPOPTSTRIP target: only valid for protocol 6 binder: 5609:5613 transaction failed 29189/-22, size 0-0 line 2842 irq bypass consumer (token 000000000db8a5d4) registration fails: -16 binder: 5609:5620 transaction failed 29189/-22, size 0-0 line 2842 device eql entered promiscuous mode QAT: Invalid ioctl QAT: Invalid ioctl xt_addrtype: output interface limitation not valid in PREROUTING and INPUT QAT: Invalid ioctl QAT: Invalid ioctl kernel msg: ebtables bug: please report to author: entries_size too small kernel msg: ebtables bug: please report to author: entries_size too small xt_TPROXY: Can be used only in combination with either -p tcp or -p udp RDS: rds_bind could not find a transport for 172.20.0.170, load rds_tcp or rds_rdma? x_tables: ip_tables: icmp match: only valid for protocol 1 RDS: rds_bind could not find a transport for 172.20.0.170, load rds_tcp or rds_rdma? xt_cluster: this node mask cannot be higher than the total number of nodes capability: warning: `syz-executor6' uses deprecated v2 capabilities in a way that may be insecure encrypted_key: insufficient parameters specified sctp: [Deprecated]: syz-executor4 (pid 6641) Use of int in max_burst socket option. Use struct sctp_assoc_value instead syz-executor6 (6614) used greatest stack depth: 11776 bytes left sctp: [Deprecated]: syz-executor4 (pid 6659) Use of int in max_burst socket option. Use struct sctp_assoc_value instead xt_l2tp: invalid flags combination: c xt_l2tp: invalid flags combination: c Cannot find add_set index 256 as target x_tables: ip_tables: socket match: used from hooks OUTPUT, but only valid from PREROUTING/INPUT Cannot find add_set index 256 as target x_tables: ip_tables: socket match: used from hooks OUTPUT, but only valid from PREROUTING/INPUT kauditd_printk_skb: 17 callbacks suppressed audit: type=1400 audit(1519225213.296:39): avc: denied { setfcap } for pid=6724 comm="syz-executor6" capability=31 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=cap_userns permissive=1 binder_alloc: 6767: binder_alloc_buf, no vma audit: type=1400 audit(1519225213.396:40): avc: denied { call } for pid=6767 comm="syz-executor3" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=binder permissive=1 binder: 6767:6770 transaction failed 29189/-3, size 80-16 line 2957 binder: 6767:6777 BC_ACQUIRE_DONE node 7 has no pending acquire request binder: BINDER_SET_CONTEXT_MGR already set binder: 6767:6777 ioctl 40046207 0 returned -16 binder_alloc: 6767: binder_alloc_buf, no vma binder: 6767:6777 transaction failed 29189/-3, size 80-16 line 2957 binder: 6767:6770 BC_ACQUIRE_DONE u0000000000000000 no match binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 audit: type=1400 audit(1519225213.626:41): avc: denied { map } for pid=6843 comm="syz-executor0" path=2F6D656D66643A706F73FB202864656C6574656429 dev="tmpfs" ino=18959 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:tmpfs_t:s0 tclass=file permissive=1 snd_dummy snd_dummy.0: control 120:0:0:Î:0 is already present xt_connbytes: Forcing CT accounting to be enabled audit: type=1400 audit(1519225213.846:42): avc: denied { ioctl } for pid=6918 comm="syz-executor7" path="socket:[19643]" dev="sockfs" ino=19643 ioctlcmd=0x8919 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_netfilter_socket permissive=1 xt_CT: You must specify a L4 protocol, and not use inversions on it. xt_CT: You must specify a L4 protocol, and not use inversions on it. x_tables: ip_tables: rpfilter match: used from hooks OUTPUT/POSTROUTING, but only valid from PREROUTING audit: type=1400 audit(1519225214.154:43): avc: denied { map } for pid=7037 comm="syz-executor5" path="/dev/usbmon0" dev="devtmpfs" ino=9150 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:usbmon_device_t:s0 tclass=chr_file permissive=1 x_tables: ip_tables: rpfilter match: used from hooks OUTPUT/POSTROUTING, but only valid from PREROUTING netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. audit: type=1400 audit(1519225214.293:44): avc: denied { setopt } for pid=7063 comm="syz-executor6" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_netfilter_socket permissive=1 netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. audit: type=1400 audit(1519225214.310:45): avc: denied { getattr } for pid=7063 comm="syz-executor6" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_netfilter_socket permissive=1 netlink: 3 bytes leftover after parsing attributes in process `syz-executor6'. 9pnet_virtio: no channels available for device ./file0 kvm [7084]: vcpu0, guest rIP: 0xfff0 Hyper-V unhandled rdmsr: 0x40000006 kvm [7084]: vcpu0, guest rIP: 0xfff0 Hyper-V unhandled rdmsr: 0x40000006 Can not set IPV6_FL_F_REFLECT if flowlabel_consistency sysctl is enable Can not set IPV6_FL_F_REFLECT if flowlabel_consistency sysctl is enable xt_connbytes: Forcing CT accounting to be enabled binder: 7268:7276 ioctl 4b33 20000040 returned -22 binder: BINDER_SET_CONTEXT_MGR already set binder: 7268:7276 ioctl 4b33 20000040 returned -22 binder: 7268:7290 ioctl 40046207 0 returned -16 audit: type=1400 audit(1519225216.280:46): avc: denied { transfer } for pid=7563 comm="syz-executor3" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=binder permissive=1 binder: 7563:7572 ioctl c0306201 20000100 returned -14 binder: BINDER_SET_CONTEXT_MGR already set binder: 7563:7582 ioctl 40046207 0 returned -16 binder_alloc: 7563: binder_alloc_buf, no vma binder: 7563:7572 transaction failed 29189/-3, size 80-16 line 2957 binder: 7563:7585 DecRefs 0 refcount change on invalid ref 2 ret -22 binder: 7563:7572 ioctl c0306201 20000100 returned -14 binder: undelivered TRANSACTION_ERROR: 29189 binder: release 7563:7572 transaction 12 out, still active binder: unexpected work type, 4, not freed binder: undelivered TRANSACTION_COMPLETE binder: send failed reply for transaction 12, target dead audit: type=1400 audit(1519225216.454:47): avc: denied { name_bind } for pid=7607 comm="syz-executor5" src=20020 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:port_t:s0 tclass=dccp_socket permissive=1 audit: type=1400 audit(1519225216.454:48): avc: denied { node_bind } for pid=7607 comm="syz-executor5" saddr=::1 src=20020 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:node_t:s0 tclass=dccp_socket permissive=1 x_tables: ip6_tables: DNPT target: used from hooks PREROUTING/INPUT/FORWARD/OUTPUT/POSTROUTING, but only usable from PREROUTING/OUTPUT x_tables: ip6_tables: DNPT target: used from hooks PREROUTING/INPUT/FORWARD/OUTPUT/POSTROUTING, but only usable from PREROUTING/OUTPUT binder_alloc: 7863: binder_alloc_buf, no vma binder: 7863:7866 transaction failed 29189/-3, size 0-0 line 2957 binder: BINDER_SET_CONTEXT_MGR already set binder: 7863:7875 ioctl 40046207 0 returned -16 x_tables: ip6_tables: DNPT target: used from hooks PREROUTING/INPUT/FORWARD/OUTPUT/POSTROUTING, but only usable from PREROUTING/OUTPUT binder_alloc: 7863: binder_alloc_buf, no vma binder: 7863:7875 transaction failed 29189/-3, size 0-0 line 2957 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 xt_connbytes: Forcing CT accounting to be enabled xt_HMARK: hash modulus can't be zero xt_HMARK: hash modulus can't be zero *** Guest State *** CR0: actual=0xffffffff9ffffffc, shadow=0xfffffffffffffffc, gh_mask=fffffffffffffff7 CR4: actual=0x0000000000002060, shadow=0x0000000000000020, gh_mask=ffffffffffffe871 CR3 = 0x0000000000000000 PDPTR0 = 0x0000000000000003 PDPTR1 = 0x0000000000000000 PDPTR2 = 0x0000000000000000 PDPTR3 = 0x0000000000000000 RSP = 0x0000000000000000 RIP = 0x0000000000008000 RFLAGS=0x00010002 DR7 = 0x0000000000000400 Sysenter RSP=0000000000000000 CS:RIP=0000:0000000000000000 CS: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 DS: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 SS: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 ES: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 FS: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 GS: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 GDTR: limit=0x00000000, base=0x0000000000000000 LDTR: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 IDTR: limit=0x00000000, base=0x0000000000000000 TR: sel=0x0000, attr=0x10000, limit=0x00000000, base=0x0000000000000000 EFER = 0x0000000000000000 PAT = 0x0007040600070406 DebugCtl = 0x0000000000000000 DebugExceptions = 0x0000000000000000 Interruptibility = 00000008 ActivityState = 00000000 *** Host State *** RIP = 0xffffffff811cd915 RSP = 0xffff8801cd2bf3b8 CS=0010 SS=0018 DS=0000 ES=0000 FS=0000 GS=0000 TR=0040 FSBase=00007fa51183f700 GSBase=ffff8801db400000 TRBase=fffffe0000003000 GDTBase=fffffe0000001000 IDTBase=fffffe0000000000 CR0=0000000080050033 CR3=00000001cca80002 CR4=00000000001626f0 Sysenter RSP=fffffe0000002200 CS:RIP=0010:ffffffff85a01e70 EFER = 0x0000000000000d01 PAT = 0x0000000000000000 *** Control State *** PinBased=0000003f CPUBased=b5986dfa SecondaryExec=000000c2 EntryControls=0000d1ff ExitControls=0023efff ExceptionBitmap=00060042 PFECmask=00000000 PFECmatch=00000000 VMEntry: intr_info=80000306 errcode=00000000 ilen=00000000 VMExit: intr_info=00000000 errcode=00000000 ilen=00000003 reason=80000021 qualification=0000000000000000 IDTVectoring: info=00000000 errcode=00000000 TSC Offset = 0xffffffe37575c252 EPT pointer = 0x00000001ceaea01e kernel msg: ebtables bug: please report to author: Wrong len argument kernel msg: ebtables bug: please report to author: Wrong len argument xt_CT: netfilter: NOTRACK target is deprecated, use CT instead or upgrade iptables mmap: syz-executor4 (8155) uses deprecated remap_file_pages() syscall. See Documentation/vm/remap_file_pages.txt. PPPIOCDETACH file->f_count=2 kauditd_printk_skb: 4 callbacks suppressed audit: type=1400 audit(1519225218.532:53): avc: denied { write } for pid=8236 comm="syz-executor5" name="task" dev="proc" ino=23061 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=dir permissive=1 audit: type=1400 audit(1519225218.538:54): avc: denied { setattr } for pid=8236 comm="syz-executor5" name="task" dev="proc" ino=23061 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=dir permissive=1 netlink: 'syz-executor2': attribute type 1 has an invalid length. audit: type=1400 audit(1519225218.741:55): avc: denied { getopt } for pid=8266 comm="syz-executor2" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_generic_socket permissive=1 netlink: 'syz-executor2': attribute type 1 has an invalid length. binder: 8416:8421 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: binder_alloc_mmap_handler: 8416 20000000-20002000 already mapped failed -16 binder: BINDER_SET_CONTEXT_MGR already set binder: 8416:8430 ioctl 40046207 0 returned -16 binder: 8416:8430 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: 8416: binder_alloc_buf, no vma binder: 8416:8430 transaction failed 29189/-3, size 0-0 line 2957 binder: undelivered TRANSACTION_ERROR: 29189 binder: release 8416:8421 transaction 22 out, still active binder: undelivered TRANSACTION_COMPLETE binder: send failed reply for transaction 22, target dead xt_ecn: cannot match TCP bits in rule for non-tcp packets xt_ecn: cannot match TCP bits in rule for non-tcp packets binder: 8497:8506 Acquire 1 refcount change on invalid ref 0 ret -22 rpcbind: RPC call returned error 22 binder: 8497:8506 Acquire 1 refcount change on invalid ref 0 ret -22 binder: 8497:8506 BC_DEAD_BINDER_DONE 0000000000000000 not found binder: 8497:8506 Release 1 refcount change on invalid ref 3 ret -22 rpcbind: RPC call returned error 22 binder: 8497:8506 BC_REQUEST_DEATH_NOTIFICATION invalid ref 1 binder: 8497:8506 unknown command -1613667895 binder: 8497:8506 ioctl c0306201 20000280 returned -22