[ 37.819003] audit: type=1800 audit(1550506371.883:26): pid=7613 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="mcstrans" dev="sda1" ino=2457 res=0 [ 37.838879] audit: type=1800 audit(1550506371.883:27): pid=7613 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="restorecond" dev="sda1" ino=2436 res=0 [ 37.864225] audit: type=1800 audit(1550506371.953:28): pid=7613 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="ssh" dev="sda1" ino=2417 res=0 [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. [ 38.576939] audit: type=1800 audit(1550506372.673:29): pid=7613 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="rc.local" dev="sda1" ino=2432 res=0 Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.0.138' (ECDSA) to the list of known hosts. 2019/02/18 16:13:01 parsed 1 programs 2019/02/18 16:13:04 executed programs: 0 syzkaller login: [ 50.286227] IPVS: ftp: loaded support on port[0] = 21 [ 50.346804] chnl_net:caif_netlink_parms(): no params data found [ 50.376859] bridge0: port 1(bridge_slave_0) entered blocking state [ 50.383462] bridge0: port 1(bridge_slave_0) entered disabled state [ 50.390722] device bridge_slave_0 entered promiscuous mode [ 50.397908] bridge0: port 2(bridge_slave_1) entered blocking state [ 50.404291] bridge0: port 2(bridge_slave_1) entered disabled state [ 50.411335] device bridge_slave_1 entered promiscuous mode [ 50.428141] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 50.437016] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 50.452910] team0: Port device team_slave_0 added [ 50.459300] team0: Port device team_slave_1 added [ 50.527024] device hsr_slave_0 entered promiscuous mode [ 50.564662] device hsr_slave_1 entered promiscuous mode [ 50.631823] bridge0: port 2(bridge_slave_1) entered blocking state [ 50.638277] bridge0: port 2(bridge_slave_1) entered forwarding state [ 50.645119] bridge0: port 1(bridge_slave_0) entered blocking state [ 50.651450] bridge0: port 1(bridge_slave_0) entered forwarding state [ 50.680366] 8021q: adding VLAN 0 to HW filter on device bond0 [ 50.691445] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 50.700745] bridge0: port 1(bridge_slave_0) entered disabled state [ 50.709669] bridge0: port 2(bridge_slave_1) entered disabled state [ 50.716745] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 50.727243] 8021q: adding VLAN 0 to HW filter on device team0 [ 50.736422] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 50.744062] bridge0: port 1(bridge_slave_0) entered blocking state [ 50.750460] bridge0: port 1(bridge_slave_0) entered forwarding state [ 50.759415] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 50.767882] bridge0: port 2(bridge_slave_1) entered blocking state [ 50.774215] bridge0: port 2(bridge_slave_1) entered forwarding state [ 50.793574] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 50.803538] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 50.816442] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 50.824345] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 50.832291] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 50.839927] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 50.847442] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 50.854379] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 50.872708] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 52.616109] [ 52.617767] ===================================== [ 52.622584] WARNING: bad unlock balance detected! [ 52.627404] 5.0.0-rc7 #77 Not tainted [ 52.631180] ------------------------------------- [ 52.636043] syz-executor.0/8232 is trying to release lock (&file->mut) at: [ 52.643058] [] ucma_destroy_id+0x24c/0x4a0 [ 52.648832] but there are no more locks to release! [ 52.653822] [ 52.653822] other info that might help us debug this: [ 52.660468] 1 lock held by syz-executor.0/8232: [ 52.665110] #0: 000000004cb52257 (&file->mut){+.+.}, at: ucma_destroy_id+0x1e9/0x4a0 [ 52.673069] [ 52.673069] stack backtrace: [ 52.677542] CPU: 0 PID: 8232 Comm: syz-executor.0 Not tainted 5.0.0-rc7 #77 [ 52.684628] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 52.693966] Call Trace: [ 52.696535] dump_stack+0x172/0x1f0 [ 52.700151] ? ucma_destroy_id+0x24c/0x4a0 [ 52.704383] print_unlock_imbalance_bug.cold+0x114/0x123 [ 52.709819] ? ucma_destroy_id+0x24c/0x4a0 [ 52.714047] lock_release+0x67e/0xa00 [ 52.717831] ? lock_downgrade+0x810/0x810 [ 52.721978] ? mutex_trylock+0x1e0/0x1e0 [ 52.726023] __mutex_unlock_slowpath+0x8e/0x6b0 [ 52.730690] ? __sanitizer_cov_trace_const_cmp1+0x1a/0x20 [ 52.736287] ? wait_for_completion+0x440/0x440 [ 52.740873] mutex_unlock+0xd/0x10 [ 52.744391] ucma_destroy_id+0x24c/0x4a0 [ 52.748446] ? ucma_close+0x320/0x320 [ 52.752231] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 52.757750] ? _copy_from_user+0xdd/0x150 [ 52.761877] ucma_write+0x2da/0x3c0 [ 52.765528] ? ucma_close+0x320/0x320 [ 52.769323] ? ucma_open+0x290/0x290 [ 52.773053] ? __fget+0x340/0x540 [ 52.776490] __vfs_write+0x116/0x8e0 [ 52.780188] ? lock_downgrade+0x810/0x810 [ 52.784338] ? ucma_open+0x290/0x290 [ 52.788048] ? kernel_read+0x120/0x120 [ 52.791931] ? debug_lockdep_rcu_enabled+0x71/0xa0 [ 52.796843] ? common_file_perm+0x1d6/0x6f0 [ 52.801147] ? apparmor_file_permission+0x25/0x30 [ 52.805976] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 52.811505] ? security_file_permission+0x94/0x320 [ 52.816419] ? rw_verify_area+0x118/0x360 [ 52.820546] vfs_write+0x20c/0x580 [ 52.824069] ksys_write+0xea/0x1f0 [ 52.827588] ? __ia32_sys_read+0xb0/0xb0 [ 52.831646] ? do_fast_syscall_32+0xd1/0xc98 [ 52.836038] ? entry_SYSENTER_compat+0x70/0x7f [ 52.840601] ? do_fast_syscall_32+0xd1/0xc98 [ 52.844994] __ia32_sys_write+0x71/0xb0 [ 52.848952] do_fast_syscall_32+0x281/0xc98 [ 52.853253] entry_SYSENTER_compat+0x70/0x7f [ 52.857673] RIP: 0023:0xf7fc6869 [ 52.861041] Code: 85 d2 74 02 89 0a 5b 5d c3 8b 04 24 c3 8b 14 24 c3 8b 3c 24 c3 90 90 90 90 90 90 90 90 90 90 90 90 51 52 55 89 e5 0f 34 cd 80 <5d> 5a 59 c3 90 90 90 90 eb 0d 90 90 90 90 90 90 90 90 90 90 90 90 [ 52.879927] RSP: 002b:00000000f7fa10cc EFLAGS: 00000296 ORIG_RAX: 0000000000000004 [ 52.887653] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 0000000020000180 [ 52.894924] RDX: 0000000000000018 RSI: 0000000000000000 RDI: 0000000000000000 [ 52.902180] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 52.909431] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 52.916681] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000