[....] Starting enhanced syslogd: rsyslogd[?25l[?1c7[ ok 8[?25h[?0c. [ 67.006420][ T26] audit: type=1800 audit(1571364989.913:25): pid=8601 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="cron" dev="sda1" ino=2414 res=0 [ 67.049022][ T26] audit: type=1800 audit(1571364989.913:26): pid=8601 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="mcstrans" dev="sda1" ino=2457 res=0 [ 67.087401][ T26] audit: type=1800 audit(1571364989.913:27): pid=8601 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.146' (ECDSA) to the list of known hosts. syzkaller login: [ 74.868832][ T8756] IPVS: ftp: loaded support on port[0] = 21 [ 74.931419][ T8756] chnl_net:caif_netlink_parms(): no params data found [ 74.957458][ T8756] bridge0: port 1(bridge_slave_0) entered blocking state [ 74.965448][ T8756] bridge0: port 1(bridge_slave_0) entered disabled state [ 74.973439][ T8756] device bridge_slave_0 entered promiscuous mode [ 74.981811][ T8756] bridge0: port 2(bridge_slave_1) entered blocking state [ 74.989212][ T8756] bridge0: port 2(bridge_slave_1) entered disabled state [ 74.996849][ T8756] device bridge_slave_1 entered promiscuous mode [ 75.014483][ T8756] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 75.025015][ T8756] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 75.044361][ T8756] team0: Port device team_slave_0 added [ 75.051579][ T8756] team0: Port device team_slave_1 added [ 75.141315][ T8756] device hsr_slave_0 entered promiscuous mode [ 75.209321][ T8756] device hsr_slave_1 entered promiscuous mode [ 75.296859][ T8756] bridge0: port 2(bridge_slave_1) entered blocking state [ 75.304392][ T8756] bridge0: port 2(bridge_slave_1) entered forwarding state [ 75.312362][ T8756] bridge0: port 1(bridge_slave_0) entered blocking state [ 75.319471][ T8756] bridge0: port 1(bridge_slave_0) entered forwarding state [ 75.353958][ T8756] 8021q: adding VLAN 0 to HW filter on device bond0 [ 75.365159][ T17] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 75.385215][ T17] bridge0: port 1(bridge_slave_0) entered disabled state [ 75.393872][ T17] bridge0: port 2(bridge_slave_1) entered disabled state [ 75.402760][ T17] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 75.414414][ T8756] 8021q: adding VLAN 0 to HW filter on device team0 [ 75.425628][ T2806] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 75.434255][ T2806] bridge0: port 1(bridge_slave_0) entered blocking state [ 75.441386][ T2806] bridge0: port 1(bridge_slave_0) entered forwarding state [ 75.461551][ T17] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 75.470462][ T17] bridge0: port 2(bridge_slave_1) entered blocking state [ 75.477807][ T17] bridge0: port 2(bridge_slave_1) entered forwarding state [ 75.486103][ T17] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 75.494657][ T17] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 75.503641][ T17] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 75.515060][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 75.526956][ T8756] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 75.538749][ T8756] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 75.547402][ T2806] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready executing program [ 75.564232][ T8756] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 75.649012][ T8766] [ 75.651396][ T8766] ====================================================== [ 75.658391][ T8766] WARNING: possible circular locking dependency detected [ 75.665401][ T8766] 5.4.0-rc3-next-20191017 #0 Not tainted [ 75.671007][ T8766] ------------------------------------------------------ [ 75.678001][ T8766] syz-executor848/8766 is trying to acquire lock: [ 75.684387][ T8766] ffff8880934c8d58 (&mm->mmap_sem#2){++++}, at: __might_fault+0xfb/0x1e0 [ 75.692963][ T8766] [ 75.692963][ T8766] but task is already holding lock: [ 75.700315][ T8766] ffff88809a65cd00 (&rp->fetch_lock){+.+.}, at: mon_bin_fetch+0x37/0x340 [ 75.708712][ T8766] [ 75.708712][ T8766] which lock already depends on the new lock. [ 75.708712][ T8766] [ 75.719144][ T8766] [ 75.719144][ T8766] the existing dependency chain (in reverse order) is: [ 75.728144][ T8766] [ 75.728144][ T8766] -> #1 (&rp->fetch_lock){+.+.}: [ 75.735250][ T8766] __mutex_lock+0x156/0x13c0 [ 75.740337][ T8766] mutex_lock_nested+0x16/0x20 [ 75.745606][ T8766] mon_bin_vma_fault+0x73/0x2d0 [ 75.750960][ T8766] __do_fault+0x111/0x540 [ 75.755794][ T8766] __handle_mm_fault+0xcab/0x3fd0 [ 75.761328][ T8766] handle_mm_fault+0x3b2/0xa90 [ 75.766590][ T8766] __do_page_fault+0x536/0xdd0 [ 75.771852][ T8766] do_page_fault+0x38/0x590 [ 75.776855][ T8766] page_fault+0x39/0x40 [ 75.781514][ T8766] [ 75.781514][ T8766] -> #0 (&mm->mmap_sem#2){++++}: [ 75.788616][ T8766] __lock_acquire+0x2596/0x4a00 [ 75.793964][ T8766] lock_acquire+0x190/0x410 [ 75.798974][ T8766] __might_fault+0x15e/0x1e0 [ 75.804068][ T8766] mon_bin_fetch+0x26f/0x340 [ 75.809157][ T8766] mon_bin_ioctl+0x21e/0xc80 [ 75.814245][ T8766] do_vfs_ioctl+0xdb6/0x13e0 [ 75.819335][ T8766] ksys_ioctl+0xab/0xd0 [ 75.823986][ T8766] __x64_sys_ioctl+0x73/0xb0 [ 75.829165][ T8766] do_syscall_64+0xfa/0x760 [ 75.834178][ T8766] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 75.840572][ T8766] [ 75.840572][ T8766] other info that might help us debug this: [ 75.840572][ T8766] [ 75.850863][ T8766] Possible unsafe locking scenario: [ 75.850863][ T8766] [ 75.858316][ T8766] CPU0 CPU1 [ 75.863671][ T8766] ---- ---- [ 75.869012][ T8766] lock(&rp->fetch_lock); [ 75.873408][ T8766] lock(&mm->mmap_sem#2); [ 75.880316][ T8766] lock(&rp->fetch_lock); [ 75.887224][ T8766] lock(&mm->mmap_sem#2); [ 75.891625][ T8766] [ 75.891625][ T8766] *** DEADLOCK *** [ 75.891625][ T8766] [ 75.899749][ T8766] 1 lock held by syz-executor848/8766: [ 75.905180][ T8766] #0: ffff88809a65cd00 (&rp->fetch_lock){+.+.}, at: mon_bin_fetch+0x37/0x340 [ 75.914021][ T8766] [ 75.914021][ T8766] stack backtrace: [ 75.919893][ T8766] CPU: 0 PID: 8766 Comm: syz-executor848 Not tainted 5.4.0-rc3-next-20191017 #0 [ 75.928890][ T8766] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 75.938935][ T8766] Call Trace: [ 75.942219][ T8766] dump_stack+0x172/0x1f0 [ 75.946532][ T8766] print_circular_bug.isra.0.cold+0x163/0x172 [ 75.952664][ T8766] check_noncircular+0x32e/0x3e0 [ 75.957580][ T8766] ? print_circular_bug.isra.0+0x230/0x230 [ 75.963370][ T8766] ? mark_held_locks+0xa4/0xf0 [ 75.968124][ T8766] ? alloc_list_entry+0xc0/0xc0 [ 75.972966][ T8766] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 75.979187][ T8766] ? find_first_zero_bit+0x9a/0xc0 [ 75.984291][ T8766] __lock_acquire+0x2596/0x4a00 [ 75.989119][ T8766] ? __lock_acquire+0x16f2/0x4a00 [ 75.994220][ T8766] ? mark_held_locks+0xf0/0xf0 [ 75.999068][ T8766] lock_acquire+0x190/0x410 [ 76.003551][ T8766] ? __might_fault+0xfb/0x1e0 [ 76.008208][ T8766] __might_fault+0x15e/0x1e0 [ 76.012775][ T8766] ? __might_fault+0xfb/0x1e0 [ 76.017451][ T8766] mon_bin_fetch+0x26f/0x340 [ 76.022039][ T8766] mon_bin_ioctl+0x21e/0xc80 [ 76.026619][ T8766] ? mon_bin_get_event+0x450/0x450 [ 76.031720][ T8766] ? mon_bin_get_event+0x450/0x450 [ 76.036812][ T8766] do_vfs_ioctl+0xdb6/0x13e0 [ 76.041380][ T8766] ? compat_ioctl_preallocate+0x210/0x210 [ 76.047335][ T8766] ? __fget+0x37f/0x550 [ 76.051470][ T8766] ? ksys_dup3+0x3e0/0x3e0 [ 76.055879][ T8766] ? tomoyo_file_ioctl+0x23/0x30 [ 76.060799][ T8766] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 76.067117][ T8766] ? security_file_ioctl+0x8d/0xc0 [ 76.072210][ T8766] ksys_ioctl+0xab/0xd0 [ 76.076343][ T8766] __x64_sys_ioctl+0x73/0xb0 [ 76.080914][ T8766] do_syscall_64+0xfa/0x760 [ 76.085402][ T8766] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 76.092102][ T8766] RIP: 0033:0x44b659 [ 76.096072][ T8766] Code: e8 9c de 02 00 48 83 c4 18 c3 0f 1f 80 00 00 00 00 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 5b d0 fb ff c3 66 2e 0f 1f 84 00 00 00 00 [ 76.115660][ T8766] RSP: 002b:00007f2ef8d39ce8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 [ 76.125178][ T8766] RAX: ffffffffffffffda RBX: 00000000006dec58 RCX: 000000000044b659 [ 76.133132][ T8766] RDX: 0000000020000140 RSI: 00000000c0109207 RDI: 0000000000000003 [ 76.141080][ T8766] RBP: 00000000006dec50 R08: 00007f2ef8d3a700 R09: 0000000000000000 [ 76.149037][ T8766] R10: 00007f2ef8d3a700 R11: 0000000000000246 R12: 00000000006dec5c [ 76.156989][ T8766] R13: 00007fff6ac6dc2f R14: 00007f2ef8d3a9c0 R15: 000000000000002d [ 76.300859][ T8767] kobject: 'batman_adv' (000000008ff4b676): kobject_uevent_env [ 76.308434][ T8767] kobject: 'batman_adv' (000000008ff4b676): kobject_uevent_env: filter function caused the event to drop! [ 76.320348][ T8767] kobject: 'batman_adv' (000000008ff4b676): kobject_cleanup, parent 0000000084bde3ed [ 76.329819][ T8767] kobject: 'batman_adv' (000000008ff4b676): calling ktype release [ 76.339220][ T8767] kobject: (000000008ff4b676): dynamic_kobj_release [ 76.345846][ T8767] kobject: 'batman_adv': free name [ 76.351222][ T8767] kobject: 'rx-0' (0000000095079202): kobject_cleanup, parent 00000000d6b2c122 [ 76.360227][ T8767] kobject: 'rx-0' (0000000095079202): auto cleanup 'remove' event [ 76.368117][ T8767] kobject: 'rx-0' (0000000095079202): kobject_uevent_env [ 76.375428][ T8767] kobject: 'rx-0' (0000000095079202): fill_kobj_path: path = '/devices/virtual/net/syz_tun/queues/rx-0' [ 76.386684][ T8767] kobject: 'rx-0' (0000000095079202): auto cleanup kobject_del [ 76.394294][ T8767] kobject: 'rx-0' (0000000095079202): calling ktype release [ 76.401609][ T8767] kobject: 'rx-0': free name [ 76.406233][ T8767] kobject: 'tx-0' (0000000098722938): kobject_cleanup, parent 00000000d6b2c122 [ 76.415190][ T8767] kobject: 'tx-0' (0000000098722938): auto cleanup 'remove' event [ 76.423620][ T8767] kobject: 'tx-0' (0000000098722938): kobject_uevent_env [ 76.430715][ T8767] kobject: 'tx-0' (0000000098722938): fill_kobj_path: path = '/devices/virtual/net/syz_tun/queues/tx-0' [ 76.442112][ T8767] kobject: 'tx-0' (0000000098722938): auto cleanup kobject_del [ 76.449719][ T8767] kobject: 'tx-0' (0000000098722938): calling ktype release [ 76.457010][ T8767] kobject: 'tx-0': free name [ 76.461692][ T8767] kobject: 'queues' (00000000d6b2c122): kobject_cleanup, parent 0000000084bde3ed [ 76.470806][ T8767] kobject: 'queues' (00000000d6b2c122): calling ktype release [ 76.478283][ T8767] kobject: 'queues' (00000000d6b2c122): kset_release [ 76.485013][ T8767] kobject: 'queues': free name [ 76.490000][ T8767] kobject: 'syz_tun' (000000000257a849): kobject_uevent_env [ 76.497295][ T8767] kobject: 'syz_tun' (000000000257a849): fill_kobj_path: path = '/devices/virtual/net/syz_tun' [ 76.539242][ T8767] kobject: 'syz_tun' (000000000257a849): kobject_cleanup, parent 0000000084bde3ed [ 76.548483][ T8767] kobject: 'syz_tun' (000