===================================== WARNING: bad unlock balance detected! 4.16.0-rc2+ #323 Not tainted ------------------------------------- syz-executor3/6959 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: 3 locks held by syz-executor3/6959: #0: (sk_lock-AF_INET6){+.+.}, at: [<000000003e833e2f>] lock_sock include/net/sock.h:1463 [inline] #0: (sk_lock-AF_INET6){+.+.}, at: [<000000003e833e2f>] sctp_sendmsg+0xc1e/0x35e0 net/sctp/socket.c:1723 #1: (rcu_read_lock){....}, at: [<00000000421cdf4a>] sctp_v6_xmit+0x2e5/0x630 net/sctp/ipv6.c:222 #2: (rcu_read_lock){....}, at: [<000000007471c761>] ip6_autoflowlabel net/ipv6/ip6_output.c:291 [inline] #2: (rcu_read_lock){....}, at: [<000000007471c761>] ip6_xmit+0xe9d/0x2260 net/ipv6/ip6_output.c:249 stack backtrace: CPU: 1 PID: 6959 Comm: syz-executor3 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_filter_hook+0x65/0x80 net/ipv6/netfilter/ip6table_filter.c:41 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] NF_HOOK include/linux/netfilter.h:286 [inline] ip6_xmit+0x10ec/0x2260 net/ipv6/ip6_output.c:277 sctp_v6_xmit+0x438/0x630 net/sctp/ipv6.c:225 sctp_packet_transmit+0x225e/0x3750 net/sctp/output.c:638 sctp_outq_flush+0xabb/0x4060 net/sctp/outqueue.c:911 sctp_outq_uncork+0x5a/0x70 net/sctp/outqueue.c:776 sctp_cmd_interpreter net/sctp/sm_sideeffect.c:1807 [inline] sctp_side_effects net/sctp/sm_sideeffect.c:1210 [inline] sctp_do_sm+0x4e0/0x6ed0 net/sctp/sm_sideeffect.c:1181 sctp_primitive_ASSOCIATE+0x9d/0xd0 net/sctp/primitive.c:88 sctp_sendmsg+0x13bd/0x35e0 net/sctp/socket.c:1985 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:00007fc96398ec68 EFLAGS: 00000246 ORIG_RAX: 000000000000002c RAX: ffffffffffffffda RBX: 00007fc96398f6d4 RCX: 0000000000453da9 RDX: 0000000000000001 RSI: 00000000203aa000 RDI: 0000000000000013 RBP: 000000000072bea0 R08: 0000000020749fe4 R09: 000000000000001c R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 00000000000004ba R14: 00000000006f7210 R15: 0000000000000000 RDS: rds_bind could not find a transport for 0.0.0.35, load rds_tcp or rds_rdma? RDS: rds_bind could not find a transport for 0.0.0.35, load rds_tcp or rds_rdma? audit: type=1400 audit(1519218683.441:46): avc: denied { map } for pid=7140 comm="syz-executor7" path="/dev/sg0" dev="devtmpfs" ino=9102 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:scsi_generic_device_t:s0 tclass=chr_file permissive=1 audit: type=1400 audit(1519218683.478:47): avc: denied { setopt } for pid=7163 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_generic_socket permissive=1 rfkill: input handler disabled rfkill: input handler enabled rfkill: input handler disabled rfkill: input handler enabled audit: type=1400 audit(1519218683.976:48): avc: denied { create } for pid=7314 comm="syz-executor1" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_crypto_socket permissive=1 audit: type=1400 audit(1519218684.221:49): avc: denied { map } for pid=7381 comm="syz-executor5" path="socket:[19298]" dev="sockfs" ino=19298 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=socket permissive=1 xt_CT: netfilter: NOTRACK target is deprecated, use CT instead or upgrade iptables atomic_op 000000006373322b conn xmit_atomic (null) atomic_op 00000000654dddb0 conn xmit_atomic (null) 9pnet_virtio: no channels available for device ./file0 9pnet_virtio: no channels available for device ./file0 audit: type=1400 audit(1519218685.208:50): avc: denied { map } for pid=7729 comm="syz-executor1" path="/selinux/status" dev="selinuxfs" ino=19 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:security_t:s0 tclass=file permissive=1 x_tables: ip_tables: SYNPROXY target: used from hooks INPUT/OUTPUT, but only usable from INPUT/FORWARD SELinux: failed to load policy SELinux: failed to load policy netlink: 'syz-executor3': attribute type 2 has an invalid length. netlink: 'syz-executor3': attribute type 2 has an invalid length. ieee80211 phy2: Selected rate control algorithm 'minstrel_ht' audit: type=1400 audit(1519218686.117:51): avc: denied { getopt } for pid=7999 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 ieee80211 phy3: Selected rate control algorithm 'minstrel_ht' device eql entered promiscuous mode SELinux: unrecognized netlink message: protocol=6 nlmsg_type=6251 sclass=netlink_xfrm_socket pig=8181 comm=syz-executor1 SELinux: unrecognized netlink message: protocol=6 nlmsg_type=6251 sclass=netlink_xfrm_socket pig=8190 comm=syz-executor1 binder: 8201:8209 ERROR: BC_REGISTER_LOOPER called after BC_ENTER_LOOPER binder: 8201:8209 ERROR: BC_REGISTER_LOOPER called after BC_ENTER_LOOPER sctp: [Deprecated]: syz-executor1 (pid 8262) Use of int in maxseg socket option. Use struct sctp_assoc_value instead audit: type=1400 audit(1519218686.987:52): avc: denied { setgid } for pid=8297 comm="syz-executor1" capability=6 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 netlink: 28 bytes leftover after parsing attributes in process `syz-executor7'. netlink: 28 bytes leftover after parsing attributes in process `syz-executor7'. audit: type=1400 audit(1519218687.746:53): avc: denied { create } for pid=8578 comm="syz-executor0" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_connector_socket permissive=1 xt_connbytes: Forcing CT accounting to be enabled netlink: 'syz-executor4': attribute type 2 has an invalid length. xt_SECMARK: invalid mode: 0 audit: type=1400 audit(1519218688.556:54): avc: denied { connect } for pid=8678 comm="syz-executor5" 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_SECMARK: invalid mode: 0 audit: type=1400 audit(1519218688.568:55): avc: denied { getopt } for pid=8678 comm="syz-executor5" 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 audit: type=1400 audit(1519218688.781:56): avc: denied { set_context_mgr } for pid=8744 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: BINDER_SET_CONTEXT_MGR already set binder: 8744:8747 ioctl 40046207 0 returned -16 binder_alloc: 8744: binder_alloc_buf, no vma audit: type=1400 audit(1519218688.815:57): avc: denied { call } for pid=8744 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: 8744:8747 transaction failed 29189/-3, size 40-8 line 2957 audit: type=1400 audit(1519218688.815:58): avc: denied { transfer } for pid=8744 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: undelivered TRANSACTION_ERROR: 29189 binder: release 8744:8747 transaction 4 out, still active binder: unexpected work type, 4, not freed binder: undelivered TRANSACTION_COMPLETE binder: send failed reply for transaction 4, target dead raw_sendmsg: syz-executor3 forgot to set AF_INET. Fix it! netlink: 'syz-executor2': attribute type 18 has an invalid length. audit: type=1401 audit(1519218689.161:59): op=setxattr invalid_context="bdev#security,posix_acl_access'-[eth1" netlink: 'syz-executor2': attribute type 18 has an invalid length. audit: type=1401 audit(1519218689.181:60): op=setxattr invalid_context="bdev#security,posix_acl_access'-[eth1" sctp: [Deprecated]: syz-executor3 (pid 8996) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead sctp: [Deprecated]: syz-executor3 (pid 8996) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead syz-executor7 uses obsolete (PF_INET,SOCK_PACKET) audit: type=1400 audit(1519218689.929:61): avc: denied { relabelto } for pid=9115 comm="syz-executor0" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:udev_var_run_t:s0 tclass=packet permissive=1 x_tables: ip_tables: rpfilter match: used from hooks PREROUTING/OUTPUT, but only valid from PREROUTING x_tables: ip_tables: rpfilter match: used from hooks PREROUTING/OUTPUT, but only valid from PREROUTING binder_alloc: 9287: binder_alloc_buf, no vma binder: 9287:9291 transaction failed 29189/-3, size 0-0 line 2957 binder: undelivered TRANSACTION_ERROR: 29189 xt_policy: input policy not valid in POSTROUTING and OUTPUT QAT: Invalid ioctl xt_policy: input policy not valid in POSTROUTING and OUTPUT bridge0: port 1(gretap0) entered blocking state bridge0: port 1(gretap0) entered disabled state device gretap0 entered promiscuous mode bridge0: port 1(gretap0) entered blocking state QAT: Invalid ioctl bridge0: port 1(gretap0) entered forwarding state IPv6: ADDRCONF(NETDEV_CHANGE): bridge0: link becomes ready binder: 9358:9368 got transaction to invalid handle binder: 9358:9368 transaction failed 29201/-22, size 64-40 line 2842 binder: undelivered TRANSACTION_ERROR: 29201 binder: BINDER_SET_CONTEXT_MGR already set QAT: Invalid ioctl binder: 9372:9392 ioctl 40046207 0 returned -16 QAT: Invalid ioctl xt_connbytes: Forcing CT accounting to be enabled netlink: 3 bytes leftover after parsing attributes in process `syz-executor7'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor7'. IPv6: Can't replace route, no match found sock: process `syz-executor5' is using obsolete getsockopt SO_BSDCOMPAT IPv4: Oversized IP packet from 127.0.0.1 IPv4: Oversized IP packet from 127.0.0.1 IPv4: Oversized IP packet from 127.0.0.1 IPv4: Oversized IP packet from 127.0.0.1 kauditd_printk_skb: 5 callbacks suppressed audit: type=1400 audit(1519218692.020:67): avc: denied { setopt } for pid=9616 comm="syz-executor5" 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: 188 bytes leftover after parsing attributes in process `syz-executor1'.