===================================== WARNING: bad unlock balance detected! 4.16.0-rc2+ #323 Not tainted ------------------------------------- syz-executor1/6765 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-executor1/6765: #0: (sk_lock-AF_INET6){+.+.}, at: [<000000008fe43a12>] lock_sock include/net/sock.h:1463 [inline] #0: (sk_lock-AF_INET6){+.+.}, at: [<000000008fe43a12>] sctp_sendmsg+0xc1e/0x35e0 net/sctp/socket.c:1723 #1: (rcu_read_lock){....}, at: [<0000000066cbf449>] sctp_v6_xmit+0x2e5/0x630 net/sctp/ipv6.c:222 #2: (rcu_read_lock){....}, at: [<00000000da82dea0>] ip6_autoflowlabel net/ipv6/ip6_output.c:291 [inline] #2: (rcu_read_lock){....}, at: [<00000000da82dea0>] ip6_xmit+0xe9d/0x2260 net/ipv6/ip6_output.c:249 stack backtrace: CPU: 1 PID: 6765 Comm: syz-executor1 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:00007faf0623cc68 EFLAGS: 00000246 ORIG_RAX: 000000000000002c RAX: ffffffffffffffda RBX: 00007faf0623d6d4 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 xt_HMARK: spi-mask and port-mask can't be combined netlink: 188 bytes leftover after parsing attributes in process `syz-executor5'. netlink: 188 bytes leftover after parsing attributes in process `syz-executor5'. kernel msg: ebtables bug: please report to author: Valid hook without chain kernel msg: ebtables bug: please report to author: Valid hook without chain capability: warning: `syz-executor2' uses 32-bit capabilities (legacy support in use) 9pnet_virtio: no channels available for device ./file0 9pnet_virtio: no channels available for device ./file0 QAT: Invalid ioctl Cannot find set identified by id 0 to match Cannot find set identified by id 0 to match QAT: Invalid ioctl netlink: 156 bytes leftover after parsing attributes in process `syz-executor5'. device eql entered promiscuous mode QAT: Invalid ioctl netlink: 'syz-executor5': attribute type 1 has an invalid length. xt_connbytes: Forcing CT accounting to be enabled TCP: request_sock_TCPv6: Possible SYN flooding on port 20030. Sending cookies. Check SNMP counters. xt_hashlimit: max count of 1 reached xt_hashlimit: max count of 1 reached l2tp_core: tunl 4: fd 20 wrong protocol, got 1, expected 17 l2tp_core: tunl 4: fd 25 wrong protocol, got 1, expected 17 PPPIOCDETACH file->f_count=2 PPPIOCDETACH file->f_count=2 ptrace attach of "/root/syz-executor3"[4221] was attempted by "/root/syz-executor3"[7438] ptrace attach of "/root/syz-executor3"[4221] was attempted by "/root/syz-executor3"[7443] 9pnet_virtio: no channels available for device ./file0 9pnet_virtio: no channels available for device ./file0 kauditd_printk_skb: 18 callbacks suppressed audit: type=1400 audit(1519229505.356:72): avc: denied { accept } for pid=7587 comm="syz-executor7" 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 xt_CT: netfilter: NOTRACK target is deprecated, use CT instead or upgrade iptables x_tables: ip_tables: icmp match: only valid for protocol 1 x_tables: ip_tables: icmp match: only valid for protocol 1 audit: type=1400 audit(1519229505.837:73): avc: denied { getopt } for pid=7750 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_generic_socket permissive=1 device eql entered promiscuous mode audit: type=1400 audit(1519229505.839:74): avc: denied { lock } for pid=7750 comm="syz-executor5" path="socket:[21291]" dev="sockfs" ino=21291 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 audit: type=1400 audit(1519229505.840:75): avc: denied { setopt } for pid=7750 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_generic_socket permissive=1 netlink: 'syz-executor4': attribute type 1 has an invalid length. netlink: 'syz-executor4': attribute type 1 has an invalid length. audit: type=1400 audit(1519229506.063:76): avc: denied { ipc_lock } for pid=7823 comm="syz-executor0" capability=14 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 audit: type=1400 audit(1519229506.383:77): avc: denied { create } for pid=7922 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_iscsi_socket permissive=1 SELinux: failed to load policy semctl(GETNCNT/GETZCNT) is since 3.16 Single Unix Specification compliant. The task syz-executor7 (8056) triggered the difference, watch for misbehavior. audit: type=1400 audit(1519229506.778:78): avc: denied { ioctl } for pid=8094 comm="syz-executor7" path="socket:[23112]" dev="sockfs" ino=23112 ioctlcmd=0x891a 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 audit: type=1400 audit(1519229506.857:79): avc: denied { map } for pid=8103 comm="syz-executor3" path="/dev/usbmon0" dev="devtmpfs" ino=1138 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 audit: type=1400 audit(1519229507.026:80): avc: denied { create } for pid=8176 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_rdma_socket permissive=1 audit: type=1400 audit(1519229507.127:81): avc: denied { map } for pid=8209 comm="syz-executor1" path="/dev/kvm" dev="devtmpfs" ino=9079 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:kvm_device_t:s0 tclass=chr_file permissive=1 binder: 8248:8262 ioctl 4040aea0 20000000 returned -22 binder: 8248:8268 ioctl 4040aea0 20000000 returned -22 binder: 8278:8280 transaction failed 29189/-22, size 0-0 line 2842 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=54821 sclass=netlink_route_socket pig=8279 comm=syz-executor4 netlink: 28 bytes leftover after parsing attributes in process `syz-executor4'. SELinux: unrecognized netlink message: protocol=0 nlmsg_type=54821 sclass=netlink_route_socket pig=8279 comm=syz-executor4 netlink: 28 bytes leftover after parsing attributes in process `syz-executor4'. binder: 8278:8285 transaction failed 29189/-22, size 0-0 line 2842 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 binder: 8326 RLIMIT_NICE not set binder: 8326 RLIMIT_NICE not set binder: BINDER_SET_CONTEXT_MGR already set binder: 8318:8326 ioctl 40046207 0 returned -16 binder: release 8318:8326 transaction 17 in, still active binder: send failed reply for transaction 17 to 8318:8336 binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_ERROR: 29189 xt_connbytes: Forcing CT accounting to be enabled device eql entered promiscuous mode QAT: Invalid ioctl QAT: Invalid ioctl binder: 8871:8876 ERROR: BC_REGISTER_LOOPER called without request binder: 8876 RLIMIT_NICE not set netlink: 'syz-executor1': attribute type 41 has an invalid length. binder: 8876 RLIMIT_NICE not set binder_alloc: binder_alloc_mmap_handler: 8871 20000000-20002000 already mapped failed -16 netlink: 'syz-executor1': attribute type 41 has an invalid length. binder: BINDER_SET_CONTEXT_MGR already set binder: 8871:8898 ERROR: BC_REGISTER_LOOPER called without request binder: 8871:8893 ioctl 40046207 0 returned -16 binder: 8898 RLIMIT_NICE not set binder: release 8871:8876 transaction 19 in, still active binder: send failed reply for transaction 19 to 8871:8893 x_tables: ip_tables: SNAT target: used from hooks OUTPUT, but only usable from INPUT/POSTROUTING binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_ERROR: 29189 netlink: 'syz-executor1': attribute type 1 has an invalid length. sctp: [Deprecated]: syz-executor4 (pid 8969) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead netlink: 'syz-executor1': attribute type 1 has an invalid length. sctp: [Deprecated]: syz-executor4 (pid 8969) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead kernel msg: ebtables bug: please report to author: counter_offset != totalcnt kernel msg: ebtables bug: please report to author: counter_offset != totalcnt sctp: [Deprecated]: syz-executor7 (pid 9025) Use of int in max_burst socket option deprecated. Use struct sctp_assoc_value instead sctp: [Deprecated]: syz-executor7 (pid 9025) Use of int in max_burst socket option deprecated. Use struct sctp_assoc_value instead rpcbind: RPC call returned error 22 rpcbind: RPC call returned error 22 TCP: request_sock_TCPv6: Possible SYN flooding on port 20030. Sending cookies. Check SNMP counters. binder: undelivered death notification, 0000000000000000 binder: BINDER_SET_CONTEXT_MGR already set binder: 9188:9194 ioctl 40046207 0 returned -16 binder: undelivered death notification, 0000000000000000 TCP: request_sock_TCPv6: Possible SYN flooding on port 20030. Sending cookies. Check SNMP counters. QAT: Invalid ioctl QAT: Invalid ioctl PPPIOCDETACH file->f_count=2 PPPIOCDETACH file->f_count=2 kauditd_printk_skb: 9 callbacks suppressed audit: type=1400 audit(1519229510.695:91): avc: denied { transfer } for pid=9451 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=binder permissive=1 binder: BINDER_SET_CONTEXT_MGR already set binder_alloc: 9451: binder_alloc_buf, no vma binder: 9451:9455 transaction failed 29189/-3, size 40-8 line 2957 binder: 9451:9460 ioctl 40046207 0 returned -16 binder: undelivered TRANSACTION_ERROR: 29189 binder: release 9451:9455 transaction 24 out, still active binder: unexpected work type, 4, not freed binder: undelivered TRANSACTION_COMPLETE binder: send failed reply for transaction 24, target dead FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 1 PID: 9462 Comm: syz-executor6 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 handle_userfault+0xbd9/0x2500 fs/userfaultfd.c:430 do_anonymous_page mm/memory.c:3163 [inline] handle_pte_fault mm/memory.c:3977 [inline] __handle_mm_fault+0x32a3/0x3ce0 mm/memory.c:4103 handle_mm_fault+0x35c/0x970 mm/memory.c:4140 __do_page_fault+0x5c9/0xc90 arch/x86/mm/fault.c:1426 do_page_fault+0xee/0x730 arch/x86/mm/fault.c:1501 page_fault+0x62/0x90 arch/x86/entry/entry_64.S:1122 RIP: 0010:fault_in_pages_readable arch/x86/include/asm/smap.h:58 [inline] RIP: 0010:iov_iter_fault_in_readable+0x1aa/0x420 lib/iov_iter.c:421 RSP: 0018:ffff8801aed37630 EFLAGS: 00010246 RAX: 0000000000010000 RBX: 0000000020012fb7 RCX: ffffffff825c9071 RDX: 0000000000000107 RSI: ffffc90004b5d000 RDI: ffff8801aed37c48 RBP: ffff8801aed37710 R08: 1ffff1003535a07a R09: 1ffff10035da6ebb R10: ffff8801aed37560 R11: ffffffff8814d108 R12: 1ffff10035da6ec9 R13: ffff8801aed376e8 R14: 0000000000000000 R15: ffff8801aed37c40 generic_perform_write+0x200/0x600 mm/filemap.c:3128 __generic_file_write_iter+0x366/0x5b0 mm/filemap.c:3263 generic_file_write_iter+0x399/0x790 mm/filemap.c:3291 call_write_iter include/linux/fs.h:1781 [inline] do_iter_readv_writev+0x55c/0x830 fs/read_write.c:653 do_iter_write+0x154/0x540 fs/read_write.c:932 vfs_writev+0x18a/0x340 fs/read_write.c:977 do_writev+0xfc/0x2a0 fs/read_write.c:1012 SYSC_writev fs/read_write.c:1085 [inline] SyS_writev+0x27/0x30 fs/read_write.c:1082 do_syscall_64+0x280/0x940 arch/x86/entry/common.c:287 entry_SYSCALL_64_after_hwframe+0x42/0xb7 RIP: 0033:0x453da9 RSP: 002b:00007f6b63092c68 EFLAGS: 00000246 ORIG_RAX: 0000000000000014 RAX: ffffffffffffffda RBX: 00007f6b630936d4 RCX: 0000000000453da9 RDX: 0000000000000001 RSI: 0000000020003fd8 RDI: 0000000000000014 RBP: 000000000072bea0 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000663 R14: 00000000006f99e8 R15: 0000000000000000 sctp: [Deprecated]: syz-executor6 (pid 9514) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead sctp: [Deprecated]: syz-executor6 (pid 9527) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead WARNING: CPU: 1 PID: 1783 at drivers/staging/android/ion/ion.c:122 ion_buffer_destroy+0xd4/0x190 drivers/staging/android/ion/ion.c:122