[info] Using makefile-style concurrent boot in runlevel 2. [ 24.435963] audit: type=1800 audit(1540152139.572:21): pid=5401 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="bootlogs" dev="sda1" ino=2419 res=0 [ 24.464526] audit: type=1800 audit(1540152139.572:22): pid=5401 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="motd" dev="sda1" ino=2447 res=0 [....] Starting enhanced syslogd: rsyslogd[?25l[?1c7[ ok 8[?25h[?0c. [....] 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.10.24' (ECDSA) to the list of known hosts. 2018/10/21 20:02:32 parsed 1 programs 2018/10/21 20:02:33 executed programs: 0 syzkaller login: [ 38.486901] IPVS: ftp: loaded support on port[0] = 21 [ 38.739343] bridge0: port 1(bridge_slave_0) entered blocking state [ 38.745971] bridge0: port 1(bridge_slave_0) entered disabled state [ 38.753972] device bridge_slave_0 entered promiscuous mode [ 38.772122] bridge0: port 2(bridge_slave_1) entered blocking state [ 38.778563] bridge0: port 2(bridge_slave_1) entered disabled state [ 38.785429] device bridge_slave_1 entered promiscuous mode [ 38.802691] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 38.820082] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 38.868194] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 38.888417] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 38.962249] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 38.969763] team0: Port device team_slave_0 added [ 38.985803] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 38.993171] team0: Port device team_slave_1 added [ 39.009698] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 39.030347] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 39.050071] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 39.069818] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 39.212708] bridge0: port 2(bridge_slave_1) entered blocking state [ 39.219147] bridge0: port 2(bridge_slave_1) entered forwarding state [ 39.225866] bridge0: port 1(bridge_slave_0) entered blocking state [ 39.232260] bridge0: port 1(bridge_slave_0) entered forwarding state [ 39.732004] 8021q: adding VLAN 0 to HW filter on device bond0 [ 39.784314] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 39.834272] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 39.840468] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 39.848168] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 39.894830] 8021q: adding VLAN 0 to HW filter on device team0 [ 42.391897] [ 42.393545] ===================================== [ 42.398386] WARNING: bad unlock balance detected! [ 42.403217] 4.19.0-rc8-next-20181019+ #98 Not tainted [ 42.408383] ------------------------------------- [ 42.413202] syz-executor0/6464 is trying to release lock (&file->mut) at: [ 42.420120] [] ucma_destroy_id+0x2cb/0x550 [ 42.425891] but there are no more locks to release! [ 42.430882] [ 42.430882] other info that might help us debug this: [ 42.437535] 1 lock held by syz-executor0/6464: [ 42.442096] #0: 00000000411e1cbe (&file->mut){+.+.}, at: ucma_destroy_id+0x26b/0x550 [ 42.450220] [ 42.450220] stack backtrace: [ 42.454708] CPU: 0 PID: 6464 Comm: syz-executor0 Not tainted 4.19.0-rc8-next-20181019+ #98 [ 42.463090] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 42.472424] Call Trace: [ 42.474998] dump_stack+0x244/0x39d [ 42.478616] ? dump_stack_print_info.cold.1+0x20/0x20 [ 42.483802] ? vprintk_func+0x85/0x181 [ 42.487672] ? ucma_destroy_id+0x2cb/0x550 [ 42.491892] print_unlock_imbalance_bug.cold.48+0xcc/0xd8 [ 42.497412] lock_release+0x758/0xa10 [ 42.501196] ? ucma_destroy_id+0x2cb/0x550 [ 42.505431] ? lock_downgrade+0x900/0x900 [ 42.509565] ? radix_tree_insert+0x870/0x870 [ 42.513974] ? __radix_tree_delete+0x29c/0x500 [ 42.518563] ? idr_preload+0x50/0x50 [ 42.522263] __mutex_unlock_slowpath+0x102/0x8c0 [ 42.527012] ? radix_tree_insert+0x870/0x870 [ 42.531419] ? wait_for_completion+0x8a0/0x8a0 [ 42.535996] ? radix_tree_delete_item+0x188/0x350 [ 42.540823] ? radix_tree_lookup+0x30/0x30 [ 42.545047] mutex_unlock+0xd/0x10 [ 42.548573] ucma_destroy_id+0x2cb/0x550 [ 42.552618] ? ucma_close+0x310/0x310 [ 42.556404] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 42.561926] ? _copy_from_user+0xdf/0x150 [ 42.566058] ? ucma_close+0x310/0x310 [ 42.569857] ucma_write+0x365/0x460 [ 42.573501] ? ucma_open+0x3f0/0x3f0 [ 42.577209] __vfs_write+0x119/0x9f0 [ 42.580908] ? debug_lockdep_rcu_enabled+0x77/0x90 [ 42.585823] ? ucma_open+0x3f0/0x3f0 [ 42.589543] ? kernel_read+0x120/0x120 [ 42.593434] ? apparmor_path_rmdir+0x30/0x30 [ 42.597827] ? trace_hardirqs_off_caller+0x300/0x300 [ 42.602916] ? apparmor_file_permission+0x24/0x30 [ 42.607744] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 42.613269] ? security_file_permission+0x1c2/0x220 [ 42.618270] ? rw_verify_area+0x118/0x360 [ 42.622406] vfs_write+0x1fc/0x560 [ 42.625945] ksys_write+0x101/0x260 [ 42.629560] ? __ia32_sys_read+0xb0/0xb0 [ 42.633605] ? trace_hardirqs_off_caller+0x300/0x300 [ 42.638702] __x64_sys_write+0x73/0xb0 [ 42.642576] do_syscall_64+0x1b9/0x820 [ 42.646447] ? entry_SYSCALL_64_after_hwframe+0x3e/0xbe [ 42.651795] ? syscall_return_slowpath+0x5e0/0x5e0 [ 42.656711] ? trace_hardirqs_on_caller+0x310/0x310 [ 42.661715] ? prepare_exit_to_usermode+0x3b0/0x3b0 [ 42.666720] ? post_copy_siginfo_from_user.isra.25.part.26+0x250/0x250 [ 42.673369] ? __switch_to_asm+0x40/0x70 [ 42.677428] ? __switch_to_asm+0x34/0x70 [ 42.681480] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 42.686316] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 42.691495] RIP: 0033:0x457569 [ 42.694690] Code: fd b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 cb b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00 [ 42.713578] RSP: 002b:00007f6876e61c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 42.721294] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457569 [ 42.728558] RDX: 0000000000000018 RSI: 0000000020000280 RDI: 0000000000000005 [ 42.735807] RBP: 000000000072bfa0 R08: 0000000000000000 R09: 0000000000000000 [ 42.743056] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f6876e626d4 [ 42.750308] R13: 00000000004cb4f8 R14: 00000000004d8b68 R15: 00000000ffffffff