[....] Starting enhanced syslogd: rsyslogd[?25l[?1c7[ ok 8[?25h[?0c. [ 62.063569][ T23] audit: type=1800 audit(1575122903.286:25): pid=8792 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="cron" dev="sda1" ino=2414 res=0 [ 62.083393][ T23] audit: type=1800 audit(1575122903.286:26): pid=8792 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="mcstrans" dev="sda1" ino=2457 res=0 [ 62.104572][ T23] audit: type=1800 audit(1575122903.296:27): pid=8792 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="restorecond" dev="sda1" ino=2436 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. Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.0.198' (ECDSA) to the list of known hosts. 2019/11/30 14:08:31 parsed 1 programs 2019/11/30 14:08:32 executed programs: 0 syzkaller login: [ 71.752035][ T8959] IPVS: ftp: loaded support on port[0] = 21 [ 71.814760][ T8959] chnl_net:caif_netlink_parms(): no params data found [ 71.844071][ T8959] bridge0: port 1(bridge_slave_0) entered blocking state [ 71.852025][ T8959] bridge0: port 1(bridge_slave_0) entered disabled state [ 71.860016][ T8959] device bridge_slave_0 entered promiscuous mode [ 71.867978][ T8959] bridge0: port 2(bridge_slave_1) entered blocking state [ 71.875526][ T8959] bridge0: port 2(bridge_slave_1) entered disabled state [ 71.883308][ T8959] device bridge_slave_1 entered promiscuous mode [ 71.899825][ T8959] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 71.910696][ T8959] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 71.929005][ T8959] team0: Port device team_slave_0 added [ 71.936483][ T8959] team0: Port device team_slave_1 added [ 72.001618][ T8959] device hsr_slave_0 entered promiscuous mode [ 72.050701][ T8959] device hsr_slave_1 entered promiscuous mode [ 72.133918][ T8959] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 72.192199][ T8959] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 72.241595][ T8959] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 72.292007][ T8959] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 72.340775][ T8959] bridge0: port 2(bridge_slave_1) entered blocking state [ 72.351546][ T8959] bridge0: port 2(bridge_slave_1) entered forwarding state [ 72.359229][ T8959] bridge0: port 1(bridge_slave_0) entered blocking state [ 72.366369][ T8959] bridge0: port 1(bridge_slave_0) entered forwarding state [ 72.403673][ T8959] 8021q: adding VLAN 0 to HW filter on device bond0 [ 72.415310][ T138] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 72.436195][ T138] bridge0: port 1(bridge_slave_0) entered disabled state [ 72.455054][ T138] bridge0: port 2(bridge_slave_1) entered disabled state [ 72.464257][ T138] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 72.476956][ T8959] 8021q: adding VLAN 0 to HW filter on device team0 [ 72.487717][ T1099] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 72.496502][ T1099] bridge0: port 1(bridge_slave_0) entered blocking state [ 72.503629][ T1099] bridge0: port 1(bridge_slave_0) entered forwarding state [ 72.520470][ T138] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 72.528790][ T138] bridge0: port 2(bridge_slave_1) entered blocking state [ 72.535881][ T138] bridge0: port 2(bridge_slave_1) entered forwarding state [ 72.546409][ T1099] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 72.556491][ T1099] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 72.573622][ T8959] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 72.584378][ T8959] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 72.597810][ T8963] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 72.606394][ T8963] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 72.615068][ T8963] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 72.624905][ T8963] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 72.641615][ T138] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 72.649015][ T138] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 72.660926][ T8959] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 74.911402][ T9373] [ 74.913754][ T9373] ===================================== [ 74.919267][ T9373] WARNING: bad unlock balance detected! [ 74.924785][ T9373] 5.4.0-syzkaller #0 Not tainted [ 74.929688][ T9373] ------------------------------------- [ 74.935202][ T9373] syz-executor.0/9373 is trying to release lock (&file->mut) at: [ 74.942901][ T9373] [] ucma_destroy_id+0x24a/0x490 [ 74.949380][ T9373] but there are no more locks to release! [ 74.955065][ T9373] [ 74.955065][ T9373] other info that might help us debug this: [ 74.963095][ T9373] 1 lock held by syz-executor.0/9373: [ 74.968432][ T9373] #0: ffff88808ba55a60 (&file->mut){+.+.}, at: ucma_destroy_id+0x1e7/0x490 [ 74.977082][ T9373] [ 74.977082][ T9373] stack backtrace: [ 74.982982][ T9373] CPU: 1 PID: 9373 Comm: syz-executor.0 Not tainted 5.4.0-syzkaller #0 [ 74.991202][ T9373] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 75.001241][ T9373] Call Trace: [ 75.004510][ T9373] dump_stack+0x197/0x210 [ 75.008811][ T9373] ? ucma_destroy_id+0x24a/0x490 [ 75.013722][ T9373] print_unlock_imbalance_bug.cold+0x114/0x123 [ 75.019848][ T9373] ? ucma_destroy_id+0x24a/0x490 [ 75.024754][ T9373] lock_release+0x5f2/0x960 [ 75.029225][ T9373] ? lock_downgrade+0x920/0x920 [ 75.034064][ T9373] ? ucma_destroy_id+0x1e7/0x490 [ 75.038992][ T9373] ? ucma_destroy_id+0x1c0/0x490 [ 75.043913][ T9373] ? mutex_trylock+0x2f0/0x2f0 [ 75.048653][ T9373] ? ucma_destroy_id+0x1c0/0x490 [ 75.053594][ T9373] __mutex_unlock_slowpath+0x86/0x6a0 [ 75.058943][ T9373] ? lock_downgrade+0x920/0x920 [ 75.063770][ T9373] ? wait_for_completion+0x440/0x440 [ 75.069057][ T9373] mutex_unlock+0x1b/0x30 [ 75.073363][ T9373] ucma_destroy_id+0x24a/0x490 [ 75.078097][ T9373] ? ucma_close+0x310/0x310 [ 75.082574][ T9373] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 75.088785][ T9373] ? _copy_from_user+0x12c/0x1a0 [ 75.093697][ T9373] ucma_write+0x2d7/0x3c0 [ 75.098002][ T9373] ? ucma_close+0x310/0x310 [ 75.102475][ T9373] ? ucma_open+0x290/0x290 [ 75.106863][ T9373] ? apparmor_file_permission+0x25/0x30 [ 75.112379][ T9373] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 75.118595][ T9373] ? security_file_permission+0x8f/0x380 [ 75.124199][ T9373] __vfs_write+0x8a/0x110 [ 75.128507][ T9373] ? ucma_open+0x290/0x290 [ 75.132896][ T9373] vfs_write+0x268/0x5d0 [ 75.137110][ T9373] ksys_write+0x220/0x290 [ 75.141411][ T9373] ? __ia32_sys_read+0xb0/0xb0 [ 75.146162][ T9373] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 75.151605][ T9373] ? do_fast_syscall_32+0xd1/0xe16 [ 75.156696][ T9373] ? entry_SYSENTER_compat+0x70/0x7f [ 75.161965][ T9373] ? do_fast_syscall_32+0xd1/0xe16 [ 75.167049][ T9373] __ia32_sys_write+0x71/0xb0 [ 75.173093][ T9373] do_fast_syscall_32+0x27b/0xe16 [ 75.178089][ T9373] entry_SYSENTER_compat+0x70/0x7f [ 75.183174][ T9373] RIP: 0023:0xf7fe3a39 [ 75.187214][ T9373] Code: 00 00 00 89 d3 5b 5e 5f 5d c3 b8 80 96 98 00 eb c4 8b 04 24 c3 8b 1c 24 c3 8b 34 24 c3 8b 3c 24 c3 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 [ 75.206844][ T9373] RSP: 002b:00000000f7fbe0cc EFLAGS: 00000296 ORIG_RAX: 0000000000000004 [ 75.215236][ T9373] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000020000140 [ 75.223183][ T9373] RDX: 0000000000000018 RSI: 0000000000000000 RDI: 0000000000000000 [ 75.231140][ T9373] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 75.239088][ T9373] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 75.247034][ T9373] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [ 75.256692][ T9373] ================================================================== [ 75.265116][ T9373] BUG: KASAN: use-after-free in __mutex_unlock_slowpath+0x93/0x6a0 [ 75.272978][ T9373] Read of size 8 at addr ffff88808ba55600 by task syz-executor.0/9373 [ 75.281107][ T9373] [ 75.283423][ T9373] CPU: 1 PID: 9373 Comm: syz-executor.0 Not tainted 5.4.0-syzkaller #0 [ 75.291675][ T9373] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 75.301727][ T9373] Call Trace: [ 75.305019][ T9373] dump_stack+0x197/0x210 [ 75.309387][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.314931][ T9373] print_address_description.constprop.0.cold+0xd4/0x30b [ 75.321943][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.327470][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.332993][ T9373] __kasan_report.cold+0x1b/0x41 [ 75.337911][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.343433][ T9373] kasan_report+0x12/0x20 [ 75.347747][ T9373] check_memory_region+0x134/0x1a0 [ 75.352833][ T9373] __kasan_check_read+0x11/0x20 [ 75.357658][ T9373] __mutex_unlock_slowpath+0x93/0x6a0 [ 75.363015][ T9373] ? lock_downgrade+0x920/0x920 [ 75.367839][ T9373] ? wait_for_completion+0x440/0x440 [ 75.373098][ T9373] mutex_unlock+0x1b/0x30 [ 75.377401][ T9373] ucma_destroy_id+0x24a/0x490 [ 75.382139][ T9373] ? ucma_close+0x310/0x310 [ 75.386636][ T9373] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 75.392864][ T9373] ? _copy_from_user+0x12c/0x1a0 [ 75.397777][ T9373] ucma_write+0x2d7/0x3c0 [ 75.402088][ T9373] ? ucma_close+0x310/0x310 [ 75.406568][ T9373] ? ucma_open+0x290/0x290 [ 75.410958][ T9373] ? apparmor_file_permission+0x25/0x30 [ 75.416477][ T9373] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 75.422691][ T9373] ? security_file_permission+0x8f/0x380 [ 75.428297][ T9373] __vfs_write+0x8a/0x110 [ 75.432600][ T9373] ? ucma_open+0x290/0x290 [ 75.436992][ T9373] vfs_write+0x268/0x5d0 [ 75.441208][ T9373] ksys_write+0x220/0x290 [ 75.445509][ T9373] ? __ia32_sys_read+0xb0/0xb0 [ 75.450266][ T9373] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 75.455697][ T9373] ? do_fast_syscall_32+0xd1/0xe16 [ 75.460781][ T9373] ? entry_SYSENTER_compat+0x70/0x7f [ 75.466039][ T9373] ? do_fast_syscall_32+0xd1/0xe16 [ 75.471124][ T9373] __ia32_sys_write+0x71/0xb0 [ 75.475784][ T9373] do_fast_syscall_32+0x27b/0xe16 [ 75.480789][ T9373] entry_SYSENTER_compat+0x70/0x7f [ 75.485872][ T9373] RIP: 0023:0xf7fe3a39 [ 75.489924][ T9373] Code: 00 00 00 89 d3 5b 5e 5f 5d c3 b8 80 96 98 00 eb c4 8b 04 24 c3 8b 1c 24 c3 8b 34 24 c3 8b 3c 24 c3 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 [ 75.509507][ T9373] RSP: 002b:00000000f7fbe0cc EFLAGS: 00000296 ORIG_RAX: 0000000000000004 [ 75.517891][ T9373] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000020000140 [ 75.525846][ T9373] RDX: 0000000000000018 RSI: 0000000000000000 RDI: 0000000000000000 [ 75.533794][ T9373] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 75.541754][ T9373] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 75.549714][ T9373] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [ 75.557675][ T9373] [ 75.559977][ T9373] Allocated by task 9376: [ 75.564288][ T9373] save_stack+0x23/0x90 [ 75.568416][ T9373] __kasan_kmalloc.constprop.0+0xcf/0xe0 [ 75.574022][ T9373] kasan_kmalloc+0x9/0x10 [ 75.578336][ T9373] kmem_cache_alloc_trace+0x158/0x790 [ 75.583685][ T9373] ucma_open+0x4f/0x290 [ 75.587817][ T9373] misc_open+0x395/0x4c0 [ 75.592053][ T9373] chrdev_open+0x245/0x6b0 [ 75.596456][ T9373] do_dentry_open+0x4e6/0x1380 [ 75.601192][ T9373] vfs_open+0xa0/0xd0 [ 75.605148][ T9373] path_openat+0x10e4/0x46d0 [ 75.609710][ T9373] do_filp_open+0x1a1/0x280 [ 75.614185][ T9373] do_sys_open+0x3fe/0x5d0 [ 75.618577][ T9373] __ia32_compat_sys_openat+0x98/0xf0 [ 75.623928][ T9373] do_fast_syscall_32+0x27b/0xe16 [ 75.628929][ T9373] entry_SYSENTER_compat+0x70/0x7f [ 75.634020][ T9373] [ 75.636429][ T9373] Freed by task 9367: [ 75.640388][ T9373] save_stack+0x23/0x90 [ 75.644519][ T9373] __kasan_slab_free+0x102/0x150 [ 75.650384][ T9373] kasan_slab_free+0xe/0x10 [ 75.654858][ T9373] kfree+0x10a/0x2c0 [ 75.658725][ T9373] ucma_close+0x275/0x310 [ 75.663029][ T9373] __fput+0x2ff/0x890 [ 75.666996][ T9373] ____fput+0x16/0x20 [ 75.670963][ T9373] task_work_run+0x145/0x1c0 [ 75.675528][ T9373] exit_to_usermode_loop+0x316/0x380 [ 75.680797][ T9373] do_fast_syscall_32+0xbbd/0xe16 [ 75.685796][ T9373] entry_SYSENTER_compat+0x70/0x7f [ 75.690873][ T9373] [ 75.693192][ T9373] The buggy address belongs to the object at ffff88808ba55600 [ 75.693192][ T9373] which belongs to the cache kmalloc-256 of size 256 [ 75.707232][ T9373] The buggy address is located 0 bytes inside of [ 75.707232][ T9373] 256-byte region [ffff88808ba55600, ffff88808ba55700) [ 75.720302][ T9373] The buggy address belongs to the page: [ 75.725912][ T9373] page:ffffea00022e9540 refcount:1 mapcount:0 mapping:ffff8880aa4008c0 index:0x0 [ 75.735045][ T9373] raw: 00fffe0000000200 ffffea00025d9048 ffffea0002240488 ffff8880aa4008c0 [ 75.743605][ T9373] raw: 0000000000000000 ffff88808ba55000 0000000100000008 0000000000000000 [ 75.752200][ T9373] page dumped because: kasan: bad access detected [ 75.758591][ T9373] [ 75.760901][ T9373] Memory state around the buggy address: [ 75.766509][ T9373] ffff88808ba55500: fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc [ 75.774633][ T9373] ffff88808ba55580: fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc [ 75.782669][ T9373] >ffff88808ba55600: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb [ 75.790713][ T9373] ^ [ 75.794752][ T9373] ffff88808ba55680: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb [ 75.802789][ T9373] ffff88808ba55700: fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc [ 75.810826][ T9373] ================================================================== [ 75.820960][ T9373] Kernel panic - not syncing: panic_on_warn set ... [ 75.827578][ T9373] CPU: 1 PID: 9373 Comm: syz-executor.0 Tainted: G B 5.4.0-syzkaller #0 [ 75.837179][ T9373] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 75.847216][ T9373] Call Trace: [ 75.850487][ T9373] dump_stack+0x197/0x210 [ 75.854796][ T9373] panic+0x2e3/0x75c [ 75.858668][ T9373] ? add_taint.cold+0x16/0x16 [ 75.863323][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.869030][ T9373] ? preempt_schedule+0x4b/0x60 [ 75.873874][ T9373] ? ___preempt_schedule+0x16/0x18 [ 75.879007][ T9373] ? trace_hardirqs_on+0x5e/0x240 [ 75.884053][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.889581][ T9373] end_report+0x47/0x4f [ 75.893714][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.899255][ T9373] __kasan_report.cold+0xe/0x41 [ 75.904081][ T9373] ? __mutex_unlock_slowpath+0x93/0x6a0 [ 75.909602][ T9373] kasan_report+0x12/0x20 [ 75.913938][ T9373] check_memory_region+0x134/0x1a0 [ 75.919027][ T9373] __kasan_check_read+0x11/0x20 [ 75.923853][ T9373] __mutex_unlock_slowpath+0x93/0x6a0 [ 75.929207][ T9373] ? lock_downgrade+0x920/0x920 [ 75.934032][ T9373] ? wait_for_completion+0x440/0x440 [ 75.939307][ T9373] mutex_unlock+0x1b/0x30 [ 75.943618][ T9373] ucma_destroy_id+0x24a/0x490 [ 75.948363][ T9373] ? ucma_close+0x310/0x310 [ 75.952846][ T9373] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 75.959073][ T9373] ? _copy_from_user+0x12c/0x1a0 [ 75.963995][ T9373] ucma_write+0x2d7/0x3c0 [ 75.968304][ T9373] ? ucma_close+0x310/0x310 [ 75.972781][ T9373] ? ucma_open+0x290/0x290 [ 75.977172][ T9373] ? apparmor_file_permission+0x25/0x30 [ 75.982701][ T9373] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 75.988943][ T9373] ? security_file_permission+0x8f/0x380 [ 75.994563][ T9373] __vfs_write+0x8a/0x110 [ 75.998878][ T9373] ? ucma_open+0x290/0x290 [ 76.003284][ T9373] vfs_write+0x268/0x5d0 [ 76.007503][ T9373] ksys_write+0x220/0x290 [ 76.011810][ T9373] ? __ia32_sys_read+0xb0/0xb0 [ 76.016550][ T9373] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 76.021994][ T9373] ? do_fast_syscall_32+0xd1/0xe16 [ 76.027082][ T9373] ? entry_SYSENTER_compat+0x70/0x7f [ 76.032354][ T9373] ? do_fast_syscall_32+0xd1/0xe16 [ 76.040419][ T9373] __ia32_sys_write+0x71/0xb0 [ 76.045082][ T9373] do_fast_syscall_32+0x27b/0xe16 [ 76.050094][ T9373] entry_SYSENTER_compat+0x70/0x7f [ 76.055189][ T9373] RIP: 0023:0xf7fe3a39 [ 76.059241][ T9373] Code: 00 00 00 89 d3 5b 5e 5f 5d c3 b8 80 96 98 00 eb c4 8b 04 24 c3 8b 1c 24 c3 8b 34 24 c3 8b 3c 24 c3 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 [ 76.078826][ T9373] RSP: 002b:00000000f7fbe0cc EFLAGS: 00000296 ORIG_RAX: 0000000000000004 [ 76.087220][ T9373] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000020000140 [ 76.095172][ T9373] RDX: 0000000000000018 RSI: 0000000000000000 RDI: 0000000000000000 [ 76.103119][ T9373] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 76.111070][ T9373] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 76.119027][ T9373] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [ 76.128371][ T9373] Kernel Offset: disabled [ 76.132690][ T9373] Rebooting in 86400 seconds..