===================================== [ BUG: bad unlock balance detected! ] 4.9.79-g71f1469 #25 Not tainted ------------------------------------- syz-executor3/6445 is trying to release lock (mrt_lock) at: [] ipmr_mfc_seq_stop+0xe4/0x140 net/ipv6/ip6mr.c:553 but there are no more locks to release! other info that might help us debug this: 2 locks held by syz-executor3/6445: #0: (sb_writers#7){.+.+.+}, at: [] file_start_write include/linux/fs.h:2621 [inline] #0: (sb_writers#7){.+.+.+}, at: [] do_sendfile+0x9ff/0xd30 fs/read_write.c:1400 #1: (&p->lock){+.+.+.}, at: [] seq_read+0xdd/0x1290 fs/seq_file.c:178 stack backtrace: CPU: 0 PID: 6445 Comm: syz-executor3 Not tainted 4.9.79-g71f1469 #25 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801d4eb72a8 ffffffff81d94829 ffffffff849b6cb8 ffff8801b0a86000 ffffffff834e8ee4 ffffffff849b6cb8 ffff8801b0a86888 ffff8801d4eb72d8 ffffffff81237df4 dffffc0000000000 ffffffff849b6cb8 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 [] kernel_readv fs/splice.c:363 [inline] [] default_file_splice_read+0x43f/0x7a0 fs/splice.c:435 [] do_splice_to+0x10a/0x160 fs/splice.c:899 [] splice_direct_to_actor+0x24d/0x800 fs/splice.c:971 [] do_splice_direct+0x1a7/0x270 fs/splice.c:1080 [] do_sendfile+0x54b/0xd30 fs/read_write.c:1401 [] SYSC_sendfile64 fs/read_write.c:1456 [inline] [] SyS_sendfile64+0xd1/0x160 fs/read_write.c:1448 [] entry_SYSCALL_64_fastpath+0x29/0xe8 binder: BINDER_SET_CONTEXT_MGR already set binder: 6486:6505 ioctl 40046207 0 returned -16 binder: 6486:6495 BC_REQUEST_DEATH_NOTIFICATION invalid ref 0 binder: 6486:6505 Release 1 refcount change on invalid ref 0 ret -22 audit: type=1400 audit(1517448524.059:27): avc: denied { create } for pid=6513 comm="syz-executor4" 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 audit: type=1400 audit(1517448524.089:28): avc: denied { write } for pid=6513 comm="syz-executor4" path="socket:[14624]" dev="sockfs" ino=14624 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 device gre0 entered promiscuous mode device gre0 entered promiscuous mode device gre0 entered promiscuous mode binder: 6844:6851 transaction failed 29189/-22, size 56-8 line 3004 binder: undelivered TRANSACTION_ERROR: 29189 FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 1 PID: 7075 Comm: syz-executor1 Not tainted 4.9.79-g71f1469 #25 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801c26f7600 ffffffff81d94829 ffff8801c26f78e0 0000000000000000 ffff8801ca901190 ffff8801c26f77d0 ffff8801ca901080 ffff8801c26f77f8 ffffffff816621ca ffff8801c26f76b0 ffff8801c26f7750 00000001d4aa3067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa3a/0x1310 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:1407 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1470 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1055 [] import_iovec+0xc8/0x3c0 lib/iov_iter.c:1243 [] process_vm_rw+0x106/0x210 mm/process_vm_access.c:274 [] SYSC_process_vm_readv mm/process_vm_access.c:299 [inline] [] SyS_process_vm_readv+0x47/0x60 mm/process_vm_access.c:295 [] entry_SYSCALL_64_fastpath+0x29/0xe8 sd 0:0:1:0: [sg0] tag#355 FAILED Result: hostbyte=DID_ABORT driverbyte=DRIVER_OK sd 0:0:1:0: [sg0] tag#355 CDB: Test Unit Ready sd 0:0:1:0: [sg0] tag#355 CDB[00]: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 sd 0:0:1:0: [sg0] tag#355 CDB[10]: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 sd 0:0:1:0: [sg0] tag#355 CDB[20]: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 sd 0:0:1:0: [sg0] tag#355 CDB[30]: 00 00 00 FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 0 PID: 7075 Comm: syz-executor1 Not tainted 4.9.79-g71f1469 #25 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801c26f7600 ffffffff81d94829 ffff8801c26f78e0 0000000000000000 ffff8801b6734110 ffff8801c26f77d0 ffff8801b6734000 ffff8801c26f77f8 ffffffff816621ca 0000000041b58ab3 ffff8801c26f7750 00000001c9153067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa3a/0x1310 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:1407 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1470 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1055 [] import_iovec+0xc8/0x3c0 lib/iov_iter.c:1243 [] process_vm_rw+0x106/0x210 mm/process_vm_access.c:274 [] SYSC_process_vm_readv mm/process_vm_access.c:299 [inline] [] SyS_process_vm_readv+0x47/0x60 mm/process_vm_access.c:295 [] entry_SYSCALL_64_fastpath+0x29/0xe8 binder_alloc: 7161: binder_alloc_buf, no vma binder: 7161:7162 transaction failed 29189/-3, size 56-8 line 3127 binder: BINDER_SET_CONTEXT_MGR already set binder: 7161:7162 ioctl 40046207 0 returned -16 binder_alloc: 7161: binder_alloc_buf, no vma binder: 7161:7173 transaction failed 29189/-3, size 56-8 line 3127 binder: 7161:7181 BC_CLEAR_DEATH_NOTIFICATION invalid ref 3 binder: 7161:7181 got reply transaction with no transaction stack binder: 7161:7181 transaction failed 29201/-71, size 72-8 line 2920 binder: undelivered TRANSACTION_ERROR: 29189 capability: warning: `syz-executor5' uses deprecated v2 capabilities in a way that may be insecure FAULT_FLAG_ALLOW_RETRY missing 30 CPU: 0 PID: 7316 Comm: syz-executor1 Not tainted 4.9.79-g71f1469 #25 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801ce1378a0 ffffffff81d94829 ffff8801ce137b80 0000000000000000 ffff8801b6734410 ffff8801ce137a70 ffff8801b6734300 ffff8801ce137a98 ffffffff816621ca ffff8801cdef6000 ffff8801ce1379f0 00000001c153e067 Call Trace: [] __dump_stack lib/dump_stack.c:15 [inline] [] dump_stack+0xc1/0x128 lib/dump_stack.c:51 [] handle_userfault+0xa3a/0x1310 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:1407 [] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1470 [] page_fault+0x28/0x30 arch/x86/entry/entry_64.S:1055 [] entry_SYSCALL_64_fastpath+0x29/0xe8 binder: undelivered death notification, 0000000000000000 binder: BINDER_SET_CONTEXT_MGR already set binder: 7370:7379 ioctl 40046207 0 returned -16 binder: undelivered death notification, 0000000000000000 audit: type=1400 audit(1517448527.609:29): avc: denied { net_raw } for pid=7425 comm="syz-executor1" capability=13 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(1517448527.639:30): avc: denied { net_admin } for pid=4125 comm="syz-executor6" capability=12 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(1517448527.649:31): avc: denied { dac_override } for pid=7424 comm="syz-executor5" capability=1 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(1517448527.679:32): avc: denied { net_raw } for pid=7464 comm="syz-executor4" capability=13 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(1517448527.709:33): avc: denied { net_admin } for pid=4122 comm="syz-executor7" capability=12 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(1517448527.729:34): avc: denied { dac_override } for pid=7424 comm="syz-executor5" capability=1 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(1517448527.929:35): avc: denied { sys_admin } for pid=7493 comm="syz-executor7" capability=21 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(1517448527.959:36): avc: denied { setuid } for pid=7486 comm="syz-executor3" capability=7 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(1517448527.979:37): avc: denied { create } for pid=7517 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_generic_socket permissive=1 syz-executor5 (7518) used greatest stack depth: 23632 bytes left keychord: Insufficient bytes present for keycount 0 keychord: Insufficient bytes present for keycount 0 device eql entered promiscuous mode mip6: mip6_destopt_init_state: state's mode is not 2: 0 mip6: mip6_destopt_init_state: state's mode is not 2: 0 binder: 7903:7909 ERROR: BC_REGISTER_LOOPER called without request binder: 7903:7909 ioctl c0306201 20008fd0 returned -14 binder: 7903:7909 got reply transaction with no transaction stack binder: 7903:7909 transaction failed 29201/-71, size 0-0 line 2920 binder_alloc: binder_alloc_mmap_handler: 7903 20000000-20002000 already mapped failed -16 netlink: 64 bytes leftover after parsing attributes in process `syz-executor6'. binder: BINDER_SET_CONTEXT_MGR already set binder: 7903:7909 ioctl 40046207 0 returned -16 binder: 7903:7909 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: 7903: binder_alloc_buf, no vma binder: 7903:7920 transaction failed 29189/-3, size 0-0 line 3127 binder: 7903:7920 got reply transaction with no transaction stack binder: 7903:7920 transaction failed 29201/-71, size 0-0 line 2920 binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29201 binder: release 7903:7920 transaction 31 out, still active binder: 7956:7967 sending u0000000000000000 node 35, cookie mismatch 0000000000000004 != 0000000000000000 binder: 7956:7967 transaction failed 29201/-22, size 24-8 line 3206 binder: send failed reply for transaction 31, target dead binder: BINDER_SET_CONTEXT_MGR already set binder: 7956:7967 ioctl 40046207 0 returned -16 binder_alloc: 7956: binder_alloc_buf, no vma binder: 7956:7987 transaction failed 29189/-3, size 24-8 line 3127 PF_BRIDGE: RTM_SETLINK with unknown ifindex PF_BRIDGE: RTM_SETLINK with unknown ifindex 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 binder: 8259:8260 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: binder_alloc_mmap_handler: 8259 20000000-20002000 already mapped failed -16 binder: BINDER_SET_CONTEXT_MGR already set binder: 8259:8277 ioctl 40046207 0 returned -16 binder: 8259:8260 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: 8259: binder_alloc_buf, no vma binder: 8259:8270 transaction failed 29189/-3, size 0-0 line 3127 binder: undelivered TRANSACTION_ERROR: 29189 binder: release 8259:8260 transaction 39 out, still active binder: undelivered TRANSACTION_COMPLETE binder: send failed reply for transaction 39, target dead binder: 8349:8355 transaction failed 29189/-22, size 0-0 line 3004 binder: 8349:8370 transaction failed 29189/-22, size 0-0 line 3004 device eql entered promiscuous mode binder_alloc: 8411: binder_alloc_buf, no vma binder: 8411:8412 transaction failed 29189/-3, size 56-8 line 3127 binder: BINDER_SET_CONTEXT_MGR already set binder: 8411:8412 ioctl 40046207 0 returned -16 binder_alloc: 8411: binder_alloc_buf, no vma binder: 8411:8424 transaction failed 29189/-3, size 56-8 line 3127 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 TCP: request_sock_TCPv6: Possible SYN flooding on port 20030. Sending cookies. Check SNMP counters. device gre0 entered promiscuous mode binder: 8602:8603 transaction failed 29201/-22, size 0-0 line 3127 binder: 8623:8626 transaction failed 29201/-28, size 0-0 line 3127 binder: BINDER_SET_CONTEXT_MGR already set binder: 8623:8636 ioctl 40046207 0 returned -16 binder_alloc: 8623: binder_alloc_buf, no vma binder: 8623:8626 transaction failed 29189/-3, size 0-0 line 3127 binder: 8623:8644 got transaction to invalid handle binder: 8623:8644 transaction failed 29201/-22, size 0-16 line 3004 binder: BINDER_SET_CONTEXT_MGR already set binder: 8602:8603 ioctl 40046207 0 returned -16 binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29189 audit_printk_skb: 1223 callbacks suppressed audit: type=1400 audit(1517448532.209:445): avc: denied { net_admin } for pid=4138 comm="syz-executor4" capability=12 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(1517448532.209:446): avc: denied { net_admin } for pid=4126 comm="syz-executor0" capability=12 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(1517448532.239:447): avc: denied { net_admin } for pid=4133 comm="syz-executor2" capability=12 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(1517448532.239:448): avc: denied { net_admin } for pid=4133 comm="syz-executor2" capability=12 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(1517448532.239:449): avc: denied { net_admin } for pid=4133 comm="syz-executor2" capability=12 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(1517448532.239:450): avc: denied { net_admin } for pid=4133 comm="syz-executor2" capability=12 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(1517448532.239:451): avc: denied { net_admin } for pid=4133 comm="syz-executor2" capability=12 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(1517448532.239:452): avc: denied { sys_admin } for pid=8705 comm="syz-executor6" capability=21 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(1517448532.239:453): avc: denied { sys_admin } for pid=8705 comm="syz-executor6" capability=21 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(1517448532.239:454): avc: denied { net_admin } for pid=4119 comm="syz-executor3" capability=12 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 dummy0: renamed from gre0 binder: 8842:8847 got transaction with invalid offset (0, min 0 max 0) or object. binder: 8842:8847 transaction failed 29201/-22, size 0-8 line 3190 binder_alloc: binder_alloc_mmap_handler: 8842 20265000-20279000 already mapped failed -16 binder: BINDER_SET_CONTEXT_MGR already set binder_alloc: 8842: binder_alloc_buf, no vma binder: 8842:8866 transaction failed 29189/-3, size 0-8 line 3127 binder: 8842:8847 ioctl 40046207 0 returned -16 binder: BINDER_SET_CONTEXT_MGR already set binder: 8934:8946 ioctl 40046207 0 returned -16