===================================== [ BUG: bad unlock balance detected! ] 4.9.67-gf26d3c7 #106 Not tainted ------------------------------------- syz-executor2/815 is trying to release lock ([ 174.707769] SELinux: unrecognized netlink message: protocol=6 nlmsg_type=0 sclass=netlink_xfrm_socket pig=828 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=6 nlmsg_type=0 sclass=netlink_xfrm_socket pig=828 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=202 sclass=netlink_route_socket pig=828 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=6 nlmsg_type=0 sclass=netlink_xfrm_socket pig=828 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=6 nlmsg_type=0 sclass=netlink_xfrm_socket pig=836 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=202 sclass=netlink_route_socket pig=828 comm=syz-executor0 mrt_lock) at: but there are no more locks to release! other info that might help us debug this: 2 locks held by syz-executor2/815: #0: (&f->f_pos_lock){+.+.+.}, at: [] __fdget_pos+0x9f/0xc0 fs/file.c:781 #1: (&p->lock){+.+.+.}, at: [] seq_read+0xdd/0x1290 fs/seq_file.c:178 stack backtrace: CPU: 0 PID: 815 Comm: syz-executor2 Not tainted 4.9.67-gf26d3c7 #106 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801a66f78e8 ffffffff81d906e9 ffffffff849ae8f8 ffff8801c84b3000 ffffffff834dec54 ffffffff849ae8f8 ffff8801c84b3888 ffff8801a66f7918 ffffffff812353f4 dffffc0000000000 ffffffff849ae8f8 00000000ffffffff Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] print_unlock_imbalance_bug+0x174/0x1a0 kernel/locking/lockdep.c:3398 [] __lock_release kernel/locking/lockdep.c:3540 [inline] [] lock_release+0x6f8/0xb80 kernel/locking/lockdep.c:3775 [] __raw_read_unlock include/linux/rwlock_api_smp.h:225 [inline] [] _raw_read_unlock+0x1a/0x50 kernel/locking/spinlock.c:255 [] ipmr_mfc_seq_stop+0xe4/0x140 net/ipv6/ip6mr.c:553 [] seq_read+0xa83/0x1290 fs/seq_file.c:283 [] proc_reg_read+0xef/0x170 fs/proc/inode.c:202 [] do_loop_readv_writev.part.17+0x141/0x1e0 fs/read_write.c:714 [] do_loop_readv_writev fs/read_write.c:880 [inline] [] do_readv_writev+0x520/0x750 fs/read_write.c:874 [] vfs_readv+0x84/0xc0 fs/read_write.c:898 [] do_readv+0xe6/0x250 fs/read_write.c:924 [] SYSC_readv fs/read_write.c:1011 [inline] [] SyS_readv+0x27/0x30 fs/read_write.c:1008 [] entry_SYSCALL_64_fastpath+0x23/0xc6 IPv6: Can't replace route, no match found IPv6: Can't replace route, no match found device gre0 entered promiscuous mode tc_ctl_action: received NO action attribs tc_ctl_action: received NO action attribs binder: 1084:1086 Acquire 1 refcount change on invalid ref 0 ret -22 binder: 1084:1086 BC_REQUEST_DEATH_NOTIFICATION invalid ref 0 FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 0 PID: 1062 Comm: syz-executor0 Not tainted 4.9.67-gf26d3c7 #106 binder: 1084:1098 Acquire 1 refcount change on invalid ref 3 ret -22 binder: 1084:1098 IncRefs 0 refcount change on invalid ref 0 ret -22 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 binder: 1084:1106 Acquire 1 refcount change on invalid ref 0 ret -22 binder: 1084:1106 BC_REQUEST_DEATH_NOTIFICATION invalid ref 0 ffff8801cee8f8e0 ffffffff81d906e9 ffff8801cee8fbc0 0000000000000000 ffff8801cb48fc10[ 175.766520] binder: BINDER_SET_CONTEXT_MGR already set binder: 1099:1109 ioctl 40046207 0 returned -16 binder: undelivered death notification, 0000000000000000 binder: undelivered death notification, 0000000000000000 ffff8801cee8fab0 ffff8801cb48fb00 ffff8801cee8fad8 ffffffff8165e307 ffff8801db221ce8 ffff8801cee8fa30 00000001c445f067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa37/0x1300 fs/userfaultfd.c:323 [] do_anonymous_page mm/memory.c:2747 [inline] [] handle_pte_fault mm/memory.c:3488 [inline] [] __handle_mm_fault mm/memory.c:3577 [inline] [] handle_mm_fault+0x1fd1/0x2530 mm/memory.c:3614 [] __do_page_fault+0x5c2/0xd40 arch/x86/mm/fault.c:1406 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1469 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1012 [] strndup_user+0x28/0xb0 mm/util.c:160 [] SYSC_request_key security/keys/keyctl.c:186 [inline] [] SyS_request_key+0xd6/0x2d0 security/keys/keyctl.c:158 [] entry_SYSCALL_64_fastpath+0x23/0xc6 FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 0 PID: 1062 Comm: syz-executor0 Not tainted 4.9.67-gf26d3c7 #106 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801cee8f8e0 ffffffff81d906e9 ffff8801cee8fbc0 0000000000000000 ffff8801cb48e410 ffff8801cee8fab0 ffff8801cb48e300 ffff8801cee8fad8 ffffffff8165e307 ffff8801cfd01800 ffff8801cee8fa30 00000001c9849067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa37/0x1300 fs/userfaultfd.c:323 [] do_anonymous_page mm/memory.c:2747 [inline] [] handle_pte_fault mm/memory.c:3488 [inline] [] __handle_mm_fault mm/memory.c:3577 [inline] [] handle_mm_fault+0x1fd1/0x2530 mm/memory.c:3614 [] __do_page_fault+0x5c2/0xd40 arch/x86/mm/fault.c:1406 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1469 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1012 [] strndup_user+0x28/0xb0 mm/util.c:160 [] SYSC_request_key security/keys/keyctl.c:186 [inline] [] SyS_request_key+0xd6/0x2d0 security/keys/keyctl.c:158 [] entry_SYSCALL_64_fastpath+0x23/0xc6 IPVS: length: 24 != 8 binder: 1229:1230 ioctl 40086602 ffffffffffff7fff returned -22 binder: 1229:1230 got reply transaction with no transaction stack binder: 1229:1230 transaction failed 29201/-71, size 2-1144397507205 line 2923 binder: 1229:1230 Release 1 refcount change on invalid ref 4 ret -22 binder: 1229:1230 IncRefs 0 refcount change on invalid ref 1 ret -22 binder: 1229:1230 Acquire 1 refcount change on invalid ref 2 ret -22 binder: 1229:1230 ERROR: BC_REGISTER_LOOPER called without request binder: 1229:1247 BC_CLEAR_DEATH_NOTIFICATION invalid ref -2 binder: 1229:1247 got new transaction with bad transaction stack, transaction 436 has target 1229:1230 binder: 1229:1247 transaction failed 29201/-71, size 48-32 line 3034 nla_parse: 9 callbacks suppressed netlink: 16 bytes leftover after parsing attributes in process `syz-executor0'. binder: 1229:1247 ioctl 40086602 ffffffffffff7fff returned -22 binder: 1229:1269 got reply transaction with no transaction stack binder: 1229:1269 transaction failed 29201/-71, size 2-1144397507205 line 2923 binder: BINDER_SET_CONTEXT_MGR already set binder: 1229:1247 ioctl 40046207 0 returned -16 binder_alloc: 1229: binder_alloc_buf, no vma binder: 1229:1269 transaction failed 29189/-3, size 0-0 line 3130 binder: 1229:1269 Release 1 refcount change on invalid ref 4 ret -22 binder: 1229:1269 IncRefs 0 refcount change on invalid ref 1 ret -22 binder: 1229:1269 Acquire 1 refcount change on invalid ref 2 ret -22 binder: 1229:1269 ERROR: BC_REGISTER_LOOPER called without request binder: undelivered TRANSACTION_ERROR: 29189 binder: release 1229:1230 transaction 436 in, still active binder: send failed reply for transaction 436 to 1229:1247 binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29189 netlink: 16 bytes leftover after parsing attributes in process `syz-executor0'. binder: 1360:1367 BC_FREE_BUFFER u0000000000000000 no match binder: 1360:1367 ioctl 40046205 ffff returned -22 sg_write: data in/out 327644/28 bytes for SCSI command 0xaa-- guessing data in; program syz-executor0 not setting count and/or reply_len properly binder: 1360:1389 BC_FREE_BUFFER u0000000000000000 no match binder: 1476:1478 ERROR: BC_REGISTER_LOOPER called without request binder: 1476:1490 got reply transaction with no transaction stack binder: 1476:1490 transaction failed 29201/-71, size 32-8 line 2923 binder_alloc: binder_alloc_mmap_handler: 1476 20000000-20002000 already mapped failed -16 device gre0 left promiscuous mode binder: BINDER_SET_CONTEXT_MGR already set binder: 1476:1490 ioctl 40046207 0 returned -16 binder: 1476:1510 ERROR: BC_REGISTER_LOOPER called without request binder: undelivered TRANSACTION_ERROR: 29201 loop_reread_partitions: partition scan of loop0 (2°]€fI¸Òæ¶Ì”B±!S,›ùDÏ') failed (rc=-13) devpts: called with bogus options devpts: called with bogus options netlink: 5 bytes leftover after parsing attributes in process `syz-executor5'. netlink: 5 bytes leftover after parsing attributes in process `syz-executor5'. loop_reread_partitions: partition scan of loop0 () failed (rc=-13) device lo entered promiscuous mode device eql entered promiscuous mode skbuff: bad partial csum: csum=65534/0 len=32 loop_reread_partitions: partition scan of loop0 (2°]€fI¸Òæ¶Ì”B±!S,›ùDÏ') failed (rc=-13) loop_reread_partitions: partition scan of loop0 () failed (rc=-13) device lo left promiscuous mode devpts: called with bogus options devpts: called with bogus options binder: 1733:1736 ioctl 8924 20002000 returned -22 binder: 1733:1736 ERROR: BC_REGISTER_LOOPER called without request binder: 1733:1752 ioctl 8924 20002000 returned -22 binder: BINDER_SET_CONTEXT_MGR already set binder: 1733:1752 ERROR: BC_REGISTER_LOOPER called without request binder: 1733:1736 ioctl 40046207 0 returned -16 binder: release 1733:1752 transaction 443 out, still active binder: undelivered TRANSACTION_COMPLETE binder: release 1733:1736 transaction 443 in, still active binder: send failed reply for transaction 443, target dead selinux_nlmsg_perm: 2 callbacks suppressed SELinux: unrecognized netlink message: protocol=0 nlmsg_type=27 sclass=netlink_route_socket pig=1790 comm=syz-executor0 netlink: 1 bytes leftover after parsing attributes in process `syz-executor4'. SELinux: unrecognized netlink message: protocol=0 nlmsg_type=27 sclass=netlink_route_socket pig=1790 comm=syz-executor0 TCP: request_sock_TCPv6: Possible SYN flooding on port 20010. Sending cookies. Check SNMP counters. netlink: 1 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 1 bytes leftover after parsing attributes in process `syz-executor2'. netlink: 1 bytes leftover after parsing attributes in process `syz-executor2'. 9pnet_virtio: no channels available for device H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H 9pnet_virtio: no channels available for device H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H¨H IPVS: Creating netns size=2536 id=54 device gre0 entered promiscuous mode device lo entered promiscuous mode device gre0 entered promiscuous mode netlink: 2 bytes leftover after parsing attributes in process `syz-executor2'. device gre0 entered promiscuous mode netlink: 2 bytes leftover after parsing attributes in process `syz-executor2'. device gre0 left promiscuous mode device gre0 entered promiscuous mode device lo entered promiscuous mode SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=2342 comm=syz-executor5 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=2370 comm=syz-executor5 FAULT_FLAG_ALLOW_RETRY missing 30 FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 0 PID: 2495 Comm: syz-executor5 Not tainted 4.9.67-gf26d3c7 #106 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801c9e97970 ffffffff81d906e9 ffff8801c9e97c50 0000000000000000 ffff8801cb48f610 ffff8801c9e97b40 ffff8801cb48f500 ffff8801c9e97b68 ffffffff8165e307 ffffffff84186fe4 ffff8801c9e97ac0 00000001d9350067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa37/0x1300 fs/userfaultfd.c:323 [] do_anonymous_page mm/memory.c:2747 [inline] [] handle_pte_fault mm/memory.c:3488 [inline] [] __handle_mm_fault mm/memory.c:3577 [inline] [] handle_mm_fault+0x1fd1/0x2530 mm/memory.c:3614 [] __do_page_fault+0x5c2/0xd40 arch/x86/mm/fault.c:1406 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1469 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1012 [] entry_SYSCALL_64_fastpath+0x23/0xc6 binder: 2535:2546 Acquire 1 refcount change on invalid ref 0 ret -22 binder: 2535:2546 BC_DEAD_BINDER_DONE 0000000000000003 not found binder: 2535:2546 DecRefs 0 refcount change on invalid ref 0 ret -22 binder: 2535:2546 DecRefs 0 refcount change on invalid ref 4 ret -22 binder: 2535:2546 BC_CLEAR_DEATH_NOTIFICATION invalid ref 0 binder: 2535:2546 got reply transaction with no transaction stack binder: 2535:2546 transaction failed 29201/-71, size 0-48 line 2923 binder: 2535:2550 Acquire 1 refcount change on invalid ref 0 ret -22 CPU: 1 PID: 2519 Comm: syz-executor5 Not tainted 4.9.67-gf26d3c7 #106 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801d540f930 ffffffff81d906e9 ffff8801d540fc10 0000000000000000 ffff8801cb48f610 ffff8801d540fb00 ffff8801cb48f500 ffff8801d540fb28 ffffffff8165e307 ffff8801d540fac8 ffff8801d540fa80 00000001d9350067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa37/0x1300 fs/userfaultfd.c:323 [] do_anonymous_page mm/memory.c:2747 [inline] [] handle_pte_fault mm/memory.c:3488 [inline] [] __handle_mm_fault mm/memory.c:3577 [inline] [] handle_mm_fault+0x1fd1/0x2530 mm/memory.c:3614 [] __do_page_fault+0x5c2/0xd40 arch/x86/mm/fault.c:1406 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1469 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1012 [] entry_SYSCALL_64_fastpath+0x23/0xc6 binder: 2571:2576 ioctl c0306201 20009fd0 returned -11 binder: 2571:2576 DecRefs 0 refcount change on invalid ref 0 ret -22 binder: 2571:2576 unknown command 0 binder: 2571:2576 ioctl c0306201 20004000 returned -22 binder: 2571:2576 ioctl c0306201 20007000 returned -14 binder: 2571:2623 DecRefs 0 refcount change on invalid ref 0 ret -22 binder: 2571:2623 unknown command 0 binder: BINDER_SET_CONTEXT_MGR already set binder: 2571:2624 ioctl 40046207 0 returned -16 binder: 2571:2623 ioctl c0306201 20004000 returned -22 binder: 2571:2624 ioctl c0306201 20007000 returned -14 tc_dump_action: action bad kind tc_dump_action: action bad kind keychord: Insufficient bytes present for keycount 30 keychord: Insufficient bytes present for keycount 30 sg_write: data in/out 156/5169 bytes for SCSI command 0x85-- guessing data in; program syz-executor4 not setting count and/or reply_len properly binder: 2998:3003 got transaction with too large buffer binder: 2998:3003 transaction failed 29201/-22, size 80-16 line 3289 binder_alloc: binder_alloc_mmap_handler: 2998 20000000-20002000 already mapped failed -16 binder: BINDER_SET_CONTEXT_MGR already set binder: 2998:3003 ioctl 40046207 0 returned -16