[ 41.833176] audit: type=1800 audit(1560819295.136:30): pid=7693 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=collect_data cause=failed(directio) comm="startpar" name="mcstrans" dev="sda1" ino=2490 res=0 Starting mcstransd: [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting file context maintaining daemon: restorecond[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. Debian GNU/Linux 7 syzkaller ttyS0 syzkaller login: [ 64.260597] kauditd_printk_skb: 4 callbacks suppressed [ 64.260613] audit: type=1400 audit(1560819317.586:35): avc: denied { map } for pid=7866 comm="bash" path="/bin/bash" dev="sda1" ino=1457 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:file_t:s0 tclass=file permissive=1 Warning: Permanently added '10.128.0.93' (ECDSA) to the list of known hosts. [ 70.847817] audit: type=1400 audit(1560819324.176:36): avc: denied { map } for pid=7878 comm="syz-execprog" path="/root/syz-execprog" dev="sda1" ino=1426 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:object_r:user_home_t:s0 tclass=file permissive=1 2019/06/18 00:55:25 parsed 1 programs [ 71.714437] audit: type=1400 audit(1560819325.036:37): avc: denied { map } for pid=7878 comm="syz-execprog" path="/sys/kernel/debug/kcov" dev="debugfs" ino=14928 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:debugfs_t:s0 tclass=file permissive=1 2019/06/18 00:55:26 executed programs: 0 [ 73.425177] IPVS: ftp: loaded support on port[0] = 21 [ 73.489006] chnl_net:caif_netlink_parms(): no params data found [ 73.524938] bridge0: port 1(bridge_slave_0) entered blocking state [ 73.531649] bridge0: port 1(bridge_slave_0) entered disabled state [ 73.539222] device bridge_slave_0 entered promiscuous mode [ 73.547216] bridge0: port 2(bridge_slave_1) entered blocking state [ 73.553706] bridge0: port 2(bridge_slave_1) entered disabled state [ 73.560994] device bridge_slave_1 entered promiscuous mode [ 73.577284] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 73.586448] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 73.603387] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 73.611293] team0: Port device team_slave_0 added [ 73.616893] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 73.624177] team0: Port device team_slave_1 added [ 73.629602] IPv6: ADDRCONF(NETDEV_UP): bridge_slave_0: link is not ready [ 73.637436] IPv6: ADDRCONF(NETDEV_UP): bridge_slave_1: link is not ready [ 73.687397] device hsr_slave_0 entered promiscuous mode [ 73.725517] device hsr_slave_1 entered promiscuous mode [ 73.775637] IPv6: ADDRCONF(NETDEV_UP): hsr_slave_0: link is not ready [ 73.782559] IPv6: ADDRCONF(NETDEV_UP): hsr_slave_1: link is not ready [ 73.797098] bridge0: port 2(bridge_slave_1) entered blocking state [ 73.803502] bridge0: port 2(bridge_slave_1) entered forwarding state [ 73.810504] bridge0: port 1(bridge_slave_0) entered blocking state [ 73.816910] bridge0: port 1(bridge_slave_0) entered forwarding state [ 73.849429] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 73.855899] 8021q: adding VLAN 0 to HW filter on device bond0 [ 73.864206] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 73.873083] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 73.892437] bridge0: port 1(bridge_slave_0) entered disabled state [ 73.899889] bridge0: port 2(bridge_slave_1) entered disabled state [ 73.907699] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 73.918795] IPv6: ADDRCONF(NETDEV_UP): team0: link is not ready [ 73.925296] 8021q: adding VLAN 0 to HW filter on device team0 [ 73.934763] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 73.942630] bridge0: port 1(bridge_slave_0) entered blocking state [ 73.948991] bridge0: port 1(bridge_slave_0) entered forwarding state [ 73.959217] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 73.967505] bridge0: port 2(bridge_slave_1) entered blocking state [ 73.973834] bridge0: port 2(bridge_slave_1) entered forwarding state [ 73.989633] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 73.997386] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 74.008762] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 74.021206] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 74.032519] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 74.043251] IPv6: ADDRCONF(NETDEV_UP): hsr0: link is not ready [ 74.049790] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 74.057848] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 74.066695] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 74.079296] IPv6: ADDRCONF(NETDEV_UP): vxcan1: link is not ready [ 74.089182] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 74.099791] audit: type=1400 audit(1560819327.426:38): avc: denied { associate } for pid=7896 comm="syz-executor.0" name="syz0" scontext=unconfined_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=filesystem permissive=1 [ 74.162688] [ 74.164329] ====================================================== [ 74.170644] WARNING: possible circular locking dependency detected [ 74.176944] 4.19.52 #24 Not tainted [ 74.180575] ------------------------------------------------------ [ 74.186879] syz-executor.0/7902 is trying to acquire lock: [ 74.192480] 00000000e0975a2b (sb_writers#4){.+.+}, at: mnt_want_write+0x3f/0xc0 [ 74.199960] [ 74.199960] but task is already holding lock: [ 74.205914] 00000000cdf0a95b (&iint->mutex){+.+.}, at: process_measurement+0x354/0x1560 [ 74.214053] [ 74.214053] which lock already depends on the new lock. [ 74.214053] [ 74.222351] [ 74.222351] the existing dependency chain (in reverse order) is: [ 74.229954] [ 74.229954] -> #1 (&iint->mutex){+.+.}: [ 74.235416] __mutex_lock+0xf7/0x1300 [ 74.239737] mutex_lock_nested+0x16/0x20 [ 74.244319] process_measurement+0x354/0x1560 [ 74.249339] ima_file_check+0xc5/0x110 [ 74.253750] path_openat+0x1130/0x4690 [ 74.258161] do_filp_open+0x1a1/0x280 [ 74.262480] do_sys_open+0x3fe/0x550 [ 74.266727] __x64_sys_open+0x7e/0xc0 [ 74.271042] do_syscall_64+0xfd/0x620 [ 74.275351] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 74.281042] [ 74.281042] -> #0 (sb_writers#4){.+.+}: [ 74.286499] lock_acquire+0x16f/0x3f0 [ 74.290809] __sb_start_write+0x20b/0x360 [ 74.295468] mnt_want_write+0x3f/0xc0 [ 74.299773] ovl_want_write+0x76/0xa0 [ 74.304078] ovl_maybe_copy_up+0x122/0x180 [ 74.308816] ovl_open+0xb8/0x270 [ 74.312691] do_dentry_open+0x4c3/0x1200 [ 74.317276] dentry_open+0x132/0x1d0 [ 74.321501] ima_calc_file_hash+0x684/0x970 [ 74.326333] ima_collect_measurement+0x50f/0x5c0 [ 74.331617] process_measurement+0xeca/0x1560 [ 74.336715] ima_file_check+0xc5/0x110 [ 74.341108] path_openat+0x1130/0x4690 [ 74.345498] do_filp_open+0x1a1/0x280 [ 74.349802] do_sys_open+0x3fe/0x550 [ 74.354019] __x64_sys_open+0x7e/0xc0 [ 74.358330] do_syscall_64+0xfd/0x620 [ 74.362632] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 74.368335] [ 74.368335] other info that might help us debug this: [ 74.368335] [ 74.376456] Possible unsafe locking scenario: [ 74.376456] [ 74.382489] CPU0 CPU1 [ 74.387131] ---- ---- [ 74.391788] lock(&iint->mutex); [ 74.395231] lock(sb_writers#4); [ 74.401199] lock(&iint->mutex); [ 74.407149] lock(sb_writers#4); [ 74.410584] [ 74.410584] *** DEADLOCK *** [ 74.410584] [ 74.416633] 1 lock held by syz-executor.0/7902: [ 74.421275] #0: 00000000cdf0a95b (&iint->mutex){+.+.}, at: process_measurement+0x354/0x1560 [ 74.429853] [ 74.429853] stack backtrace: [ 74.434337] CPU: 0 PID: 7902 Comm: syz-executor.0 Not tainted 4.19.52 #24 [ 74.441239] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 74.450572] Call Trace: [ 74.453174] dump_stack+0x172/0x1f0 [ 74.456793] print_circular_bug.isra.0.cold+0x1cc/0x28f [ 74.462144] __lock_acquire+0x2e6d/0x48f0 [ 74.466285] ? mark_held_locks+0x100/0x100 [ 74.470508] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 74.476031] ? avc_has_perm+0x404/0x610 [ 74.480000] ? avc_has_perm_noaudit+0x570/0x570 [ 74.484660] lock_acquire+0x16f/0x3f0 [ 74.488457] ? mnt_want_write+0x3f/0xc0 [ 74.492428] __sb_start_write+0x20b/0x360 [ 74.496569] ? mnt_want_write+0x3f/0xc0 [ 74.500555] mnt_want_write+0x3f/0xc0 [ 74.504362] ovl_want_write+0x76/0xa0 [ 74.508160] ovl_maybe_copy_up+0x122/0x180 [ 74.512386] ovl_open+0xb8/0x270 [ 74.515743] do_dentry_open+0x4c3/0x1200 [ 74.519792] ? check_preemption_disabled+0x48/0x290 [ 74.525762] ? ovl_llseek+0x3b0/0x3b0 [ 74.529554] ? chown_common+0x5c0/0x5c0 [ 74.533538] dentry_open+0x132/0x1d0 [ 74.537268] ima_calc_file_hash+0x684/0x970 [ 74.541585] ? __sanitizer_cov_trace_const_cmp1+0x1a/0x20 [ 74.547217] ima_collect_measurement+0x50f/0x5c0 [ 74.551965] ? ima_get_action+0xa0/0xa0 [ 74.555932] process_measurement+0xeca/0x1560 [ 74.560428] ? ima_add_template_entry.cold+0x48/0x48 [ 74.565609] ? mark_held_locks+0x100/0x100 [ 74.569836] ? chown_common+0x5c0/0x5c0 [ 74.573815] ? selinux_task_getsecid+0x16f/0x2d0 [ 74.578562] ? find_held_lock+0x35/0x130 [ 74.582612] ? selinux_task_getsecid+0x16f/0x2d0 [ 74.587360] ? lock_downgrade+0x810/0x810 [ 74.591506] ? kasan_check_read+0x11/0x20 [ 74.595642] ? selinux_task_getsecid+0x196/0x2d0 [ 74.600390] ima_file_check+0xc5/0x110 [ 74.604265] ? process_measurement+0x1560/0x1560 [ 74.610839] ? inode_permission+0xb4/0x560 [ 74.615064] path_openat+0x1130/0x4690 [ 74.618945] ? __lock_acquire+0x6eb/0x48f0 [ 74.623163] ? getname+0x1a/0x20 [ 74.626513] ? do_sys_open+0x2c9/0x550 [ 74.630405] ? path_lookupat.isra.0+0x8d0/0x8d0 [ 74.636394] ? __alloc_fd+0x44d/0x560 [ 74.640185] do_filp_open+0x1a1/0x280 [ 74.643969] ? may_open_dev+0x100/0x100 [ 74.647929] ? kasan_check_read+0x11/0x20 [ 74.652064] ? do_raw_spin_unlock+0x57/0x270 [ 74.656484] ? _raw_spin_unlock+0x2d/0x50 [ 74.660622] ? __alloc_fd+0x44d/0x560 [ 74.664584] do_sys_open+0x3fe/0x550 [ 74.668462] ? filp_open+0x80/0x80 [ 74.672009] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 74.676757] ? do_syscall_64+0x26/0x620 [ 74.680715] ? entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 74.686084] ? do_syscall_64+0x26/0x620 [ 74.690047] __x64_sys_open+0x7e/0xc0 [ 74.693833] do_syscall_64+0xfd/0x620 [ 74.697621] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 74.702792] RIP: 0033:0x4592c9 [ 74.705968] Code: fd b7 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 b7 fb ff c3 66 2e 0f 1f 84 00 00 00 00 [ 74.724858] RSP: 002b:00007ffcebdbf618 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 [ 74.732563] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00000000004592c9 [ 74.739835] RDX: 0000000000000000 RSI: 0000000000000007 RDI: 0000000020000040 [ 74.747091] RBP: 000000000075bf20 R08: 0000000000000000 R09: 0000000000000000 [ 74.754347] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000117d914 [ 74.761612] R13: 00000000004c581f R14: 00000000004d9e30 R15: 00000000ffffffff