keychord: invalid keycode count 0 ===================================== [ BUG: bad unlock balance detected! ] 4.9.67-gf26d3c7 #106 Not tainted ------------------------------------- syz-executor4/7262 is trying to release lock ([ 67.420508] netlink: 1 bytes leftover after parsing attributes in process `syz-executor7'. mrt_lock) at: but there are no more locks to release! other info that might help us debug this: 2 locks held by syz-executor4/7262: #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: 1 PID: 7262 Comm: syz-executor4 Not tainted 4.9.67-gf26d3c7 #106 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 ffff8801c7fcf8e8 ffffffff81d906e9 ffffffff849ae8f8 ffff8801cd531800 ffffffff834dec54 ffffffff849ae8f8 ffff8801cd532088 ffff8801c7fcf918 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 device gre0 entered promiscuous mode binder: 7385:7387 ERROR: BC_REGISTER_LOOPER called without request device gre0 entered promiscuous mode binder: 7387 RLIMIT_NICE not set binder: BINDER_SET_CONTEXT_MGR already set binder: 7385:7400 ioctl 40046207 0 returned -16 binder: 7385:7387 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: 7385: binder_alloc_buf, no vma binder: 7385:7423 got reply transaction with no transaction stack binder: 7385:7423 transaction failed 29201/-71, size 24-8 line 2923 binder: 7385:7387 transaction failed 29189/-3, size 0-0 line 3130 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_COMPLETE binder: undelivered transaction 67, process died. device gre0 entered promiscuous mode netlink: 5 bytes leftover after parsing attributes in process `syz-executor7'. netlink: 5 bytes leftover after parsing attributes in process `syz-executor7'. IPv6: Can't replace route, no match found IPv6: Can't replace route, no match found binder: BINDER_SET_CONTEXT_MGR already set binder: 7754:7765 ioctl 40046207 0 returned -16 netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. SELinux: unrecognized netlink message: protocol=0 nlmsg_type=51153 sclass=netlink_route_socket pig=7884 comm=syz-executor5 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=51153 sclass=netlink_route_socket pig=7895 comm=syz-executor5 binder: 8011:8014 ERROR: BC_REGISTER_LOOPER called without request binder_alloc: 8011: binder_alloc_buf, no vma binder: 8011:8021 transaction failed 29189/-3, size 0-52 line 3130 device gre0 entered promiscuous mode binder: 8011:8031 unknown command 1400526783 binder: 8011:8031 ioctl c0306201 20002fd0 returned -22 binder: 8011:8031 ioctl 8903 20cf6000 returned -22 binder: 8011:8014 ioctl c0306201 20008fd0 returned -14 tc_ctl_action: received NO action attribs binder: 8011:8014 unknown command 1400526783 binder: 8011:8014 ioctl c0306201 20002fd0 returned -22 tc_ctl_action: received NO action attribs binder: undelivered TRANSACTION_ERROR: 29189 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=8048 comm=syz-executor1 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=8068 comm=syz-executor1 device gre0 entered promiscuous mode device lo entered promiscuous mode device lo entered promiscuous mode : renamed from syz1 SELinux: unrecognized netlink message: protocol=9 nlmsg_type=16 sclass=netlink_audit_socket pig=8121 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=9 nlmsg_type=16 sclass=netlink_audit_socket pig=8126 comm=syz-executor0 binder: 8129:8130 ERROR: BC_REGISTER_LOOPER called without request binder: BINDER_SET_CONTEXT_MGR already set binder: 8129:8137 ERROR: BC_REGISTER_LOOPER called without request binder: 8129:8130 ioctl 40046207 0 returned -16 binder_alloc: 8129: binder_alloc_buf, no vma binder: 8129:8142 transaction failed 29189/-3, size 0-0 line 3130 device lo left promiscuous mode binder: 8129:8137 got reply transaction with no transaction stack binder: 8129:8137 transaction failed 29201/-71, size 24-8 line 2923 binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_COMPLETE binder: undelivered transaction 76, process died. IPv6: NLM_F_REPLACE set, but no existing node found! IPv6: NLM_F_REPLACE set, but no existing node found! device gre0 entered promiscuous mode device gre0 entered promiscuous mode Empty option to dns_resolver key Empty option to dns_resolver key sg_write: data in/out 327644/32 bytes for SCSI command 0x4-- guessing data in; program syz-executor4 not setting count and/or reply_len properly device lo entered promiscuous mode device lo left promiscuous mode device lo entered promiscuous mode device lo left promiscuous mode devpts: called with bogus options devpts: called with bogus options device gre0 entered promiscuous mode SELinux: unrecognized netlink message: protocol=9 nlmsg_type=770 sclass=netlink_audit_socket pig=8598 comm=syz-executor2 nla_parse: 8 callbacks suppressed netlink: 11 bytes leftover after parsing attributes in process `syz-executor2'. device gre0 entered promiscuous mode SELinux: unrecognized netlink message: protocol=9 nlmsg_type=770 sclass=netlink_audit_socket pig=8598 comm=syz-executor2 SELinux: unrecognized netlink message: protocol=9 nlmsg_type=770 sclass=netlink_audit_socket pig=8619 comm=syz-executor2 netlink: 11 bytes leftover after parsing attributes in process `syz-executor2'. SELinux: unrecognized netlink message: protocol=9 nlmsg_type=770 sclass=netlink_audit_socket pig=8619 comm=syz-executor2 tmpfs: No value for mount option '' tmpfs: No value for mount option '' device lo entered promiscuous mode qtaguid: iface_stat: iface_check_stats_reset_and_adjust(lo): iface reset its stats unexpectedly netlink: 8 bytes leftover after parsing attributes in process `syz-executor1'. netlink: 11 bytes leftover after parsing attributes in process `syz-executor3'. netlink: 8 bytes leftover after parsing attributes in process `syz-executor1'. netlink: 11 bytes leftover after parsing attributes in process `syz-executor3'. netlink: 2 bytes leftover after parsing attributes in process `syz-executor0'. netlink: 2 bytes leftover after parsing attributes in process `syz-executor0'. netlink: 2 bytes leftover after parsing attributes in process `syz-executor0'. netlink: 2 bytes leftover after parsing attributes in process `syz-executor0'. sg_write: data in/out 327644/32 bytes for SCSI command 0x4-- guessing data in; program syz-executor0 not setting count and/or reply_len properly device gre0 entered promiscuous mode device gre0 entered promiscuous mode binder: 9099:9101 transaction failed 29189/-22, size 80-16 line 3007 binder_alloc: binder_alloc_mmap_handler: 9099 20000000-20002000 already mapped failed -16 device syz0 entered promiscuous mode binder: undelivered TRANSACTION_ERROR: 29189 tmpfs: No value for mount option 'bYXS[^\ҥ!j9Ԗ^m)D9@ !/KpGz]#aTi[yGJYVҰL/k!n9 GdA2$gIxWi^U$,2&o yyac`{8 SH>':6$PUS;Kr' program syz-executor7 is using a deprecated SCSI ioctl, please convert it to SG_IO sd 0:0:1:0: ioctl_internal_command: ILLEGAL REQUEST asc=0x20 ascq=0x0 program syz-executor7 is using a deprecated SCSI ioctl, please convert it to SG_IO sd 0:0:1:0: ioctl_internal_command: ILLEGAL REQUEST asc=0x20 ascq=0x0 tmpfs: No value for mount option 'bYXS[^\ҥ!j9Ԗ^m)D9@ !/KpGz]#aTi[yGJYVҰL/k!n9 GdA2$gIxWi^U$,2&o yyac`{8 SH>':6$PUS;Kr' IPVS: Creating netns size=2536 id=13 pktgen: kernel_thread() failed for cpu 0 pktgen: Cannot create thread for cpu 0 (-4) pktgen: kernel_thread() failed for cpu 1 pktgen: Cannot create thread for cpu 1 (-4) pktgen: Initialization failed for all threads binder: 9556:9563 Acquire 1 refcount change on invalid ref 0 ret -22 binder: 9556:9597 BC_REQUEST_DEATH_NOTIFICATION invalid ref 0 binder: 9556:9597 BC_CLEAR_DEATH_NOTIFICATION invalid ref 0 binder: undelivered death notification, 0000000000000000 selinux_nlmsg_perm: 4 callbacks suppressed SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=9682 comm=syz-executor7 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=9682 comm=syz-executor7 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=9682 comm=syz-executor7 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=0 sclass=netlink_route_socket pig=9698 comm=syz-executor7 device lo entered promiscuous mode device lo left promiscuous mode device lo entered promiscuous mode device lo left promiscuous mode IPv6: ADDRCONF(NETDEV_CHANGE): lo: link becomes ready qtaguid: iface_stat: create6(lo): no inet dev IPv6: ADDRCONF(NETDEV_CHANGE): lo: link becomes ready qtaguid: iface_stat: create6(lo): no inet dev binder: 9919:9921 ioctl 40046205 ffffffffffffff89 returned -22 binder: 9919:9921 Release 1 refcount change on invalid ref 4 ret -22 binder: 9919:9921 DecRefs 0 refcount change on invalid ref 0 ret -22 binder: 9919:9921 unknown command 0 binder: 9919:9921 ioctl c0306201 20000fd0 returned -22 IPVS: length: 24 != 8 binder: 9919:9921 ioctl c0306201 20001000 returned -11 binder: 9919:9921 ioctl 40046205 ffffffffffffff89 returned -22 binder: BINDER_SET_CONTEXT_MGR already set binder: 9919:9940 ioctl 40046207 0 returned -16 binder: 9919:9940 unknown command 0 binder: 9919:9940 ioctl c0306201 20000fd0 returned -22 binder: 9919:9940 ioctl c0306201 20001000 returned -11 IPVS: length: 24 != 8 device gre0 entered promiscuous mode keychord: invalid keycode count 0 nla_parse: 30 callbacks suppressed netlink: 16 bytes leftover after parsing attributes in process `syz-executor7'. keychord: invalid keycode count 0 netlink: 16 bytes leftover after parsing attributes in process `syz-executor7'. sock: sock_set_timeout: `syz-executor2' (pid 10199) tries to set negative timeout blk_update_request: I/O error, dev loop7, sector 0 Buffer I/O error on dev loop7, logical block 0, lost async page write blk_update_request: I/O error, dev loop7, sector 8 Buffer I/O error on dev loop7, logical block 1, lost async page write blk_update_request: I/O error, dev loop7, sector 16 Buffer I/O error on dev loop7, logical block 2, lost async page write blk_update_request: I/O error, dev loop7, sector 24 Buffer I/O error on dev loop7, logical block 3, lost async page write blk_update_request: I/O error, dev loop7, sector 32 Buffer I/O error on dev loop7, logical block 4, lost async page write blk_update_request: I/O error, dev loop7, sector 40 Buffer I/O error on dev loop7, logical block 5, lost async page write blk_update_request: I/O error, dev loop7, sector 48 Buffer I/O error on dev loop7, logical block 6, lost async page write blk_update_request: I/O error, dev loop7, sector 56 Buffer I/O error on dev loop7, logical block 7, lost async page write blk_update_request: I/O error, dev loop7, sector 64 Buffer I/O error on dev loop7, logical block 8, lost async page write blk_update_request: I/O error, dev loop7, sector 72 Buffer I/O error on dev loop7, logical block 9, lost async page write sg_write: data in/out 196569/89 bytes for SCSI command 0x4e-- guessing data in;