[info] Using makefile-style concurrent boot in runlevel 2. [ 24.447515] audit: type=1800 audit(1542832797.274:21): pid=5808 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.475053] audit: type=1800 audit(1542832797.284:22): pid=5808 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 syzkaller login: [ 33.593698] sshd (5947) used greatest stack depth: 15632 bytes left Warning: Permanently added '10.128.0.86' (ECDSA) to the list of known hosts. 2018/11/21 20:41:30 parsed 1 programs 2018/11/21 20:41:32 executed programs: 0 [ 119.946725] IPVS: ftp: loaded support on port[0] = 21 [ 120.212905] bridge0: port 1(bridge_slave_0) entered blocking state [ 120.220902] bridge0: port 1(bridge_slave_0) entered disabled state [ 120.228247] device bridge_slave_0 entered promiscuous mode [ 120.248548] bridge0: port 2(bridge_slave_1) entered blocking state [ 120.255467] bridge0: port 2(bridge_slave_1) entered disabled state [ 120.262706] device bridge_slave_1 entered promiscuous mode [ 120.281988] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 120.302376] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 120.356506] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 120.378948] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 120.461215] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 120.469955] team0: Port device team_slave_0 added [ 120.488903] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 120.496436] team0: Port device team_slave_1 added [ 120.516082] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 120.539082] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 120.557891] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 120.578110] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 120.722457] bridge0: port 2(bridge_slave_1) entered blocking state [ 120.728988] bridge0: port 2(bridge_slave_1) entered forwarding state [ 120.735985] bridge0: port 1(bridge_slave_0) entered blocking state [ 120.742617] bridge0: port 1(bridge_slave_0) entered forwarding state [ 121.251363] 8021q: adding VLAN 0 to HW filter on device bond0 [ 121.303445] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 121.354128] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 121.360386] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 121.368503] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 121.413175] 8021q: adding VLAN 0 to HW filter on device team0 [ 121.733640] [ 121.735306] ====================================================== [ 121.741619] WARNING: possible circular locking dependency detected [ 121.748015] 4.20.0-rc1-next-20181109+ #110 Not tainted [ 121.753277] ------------------------------------------------------ [ 121.759583] syz-executor0/6233 is trying to acquire lock: [ 121.765118] 000000005145b7a6 (sb_writers#3){.+.+}, at: mnt_want_write+0x3f/0xc0 [ 121.772568] [ 121.772568] but task is already holding lock: [ 121.778683] 00000000730a103b (&iint->mutex){+.+.}, at: process_measurement+0x438/0x1bf0 [ 121.786843] [ 121.786843] which lock already depends on the new lock. [ 121.786843] [ 121.795319] [ 121.795319] the existing dependency chain (in reverse order) is: [ 121.802927] [ 121.802927] -> #1 (&iint->mutex){+.+.}: [ 121.808396] __mutex_lock+0x166/0x16f0 [ 121.812887] mutex_lock_nested+0x16/0x20 [ 121.817474] process_measurement+0x438/0x1bf0 [ 121.822570] ima_file_check+0xe5/0x130 [ 121.826992] path_openat+0x134a/0x5150 [ 121.831387] do_filp_open+0x255/0x380 [ 121.835706] do_sys_open+0x568/0x700 [ 121.840085] __x64_sys_open+0x7e/0xc0 [ 121.844407] do_syscall_64+0x1b9/0x820 [ 121.848901] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 121.854706] [ 121.854706] -> #0 (sb_writers#3){.+.+}: [ 121.860169] lock_acquire+0x1ed/0x520 [ 121.864481] __sb_start_write+0x214/0x370 [ 121.869140] mnt_want_write+0x3f/0xc0 [ 121.873452] ovl_want_write+0x76/0xa0 [ 121.877774] ovl_open_maybe_copy_up+0x12c/0x190 [ 121.882951] ovl_open+0xb3/0x260 [ 121.886831] do_dentry_open+0x499/0x1250 [ 121.891406] dentry_open+0x143/0x1d0 [ 121.895642] ima_calc_file_hash+0x324/0x570 [ 121.900632] ima_collect_measurement+0x619/0x730 [ 121.906045] process_measurement+0x11fd/0x1bf0 [ 121.911260] ima_file_check+0xe5/0x130 [ 121.915814] path_openat+0x134a/0x5150 [ 121.920212] do_filp_open+0x255/0x380 [ 121.924522] do_sys_open+0x568/0x700 [ 121.928744] __x64_sys_open+0x7e/0xc0 [ 121.933053] do_syscall_64+0x1b9/0x820 [ 121.937451] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 121.943231] [ 121.943231] other info that might help us debug this: [ 121.943231] [ 121.951365] Possible unsafe locking scenario: [ 121.951365] [ 121.957411] CPU0 CPU1 [ 121.962065] ---- ---- [ 121.966827] lock(&iint->mutex); [ 121.970275] lock(sb_writers#3); [ 121.976243] lock(&iint->mutex); [ 121.982348] lock(sb_writers#3); [ 121.985791] [ 121.985791] *** DEADLOCK *** [ 121.985791] [ 121.991843] 1 lock held by syz-executor0/6233: [ 121.996408] #0: 00000000730a103b (&iint->mutex){+.+.}, at: process_measurement+0x438/0x1bf0 [ 122.005002] [ 122.005002] stack backtrace: [ 122.009751] CPU: 0 PID: 6233 Comm: syz-executor0 Not tainted 4.20.0-rc1-next-20181109+ #110 [ 122.018230] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 122.027581] Call Trace: [ 122.030169] dump_stack+0x244/0x39d [ 122.033783] ? dump_stack_print_info.cold.1+0x20/0x20 [ 122.039319] ? vprintk_func+0x85/0x181 [ 122.043211] print_circular_bug.isra.35.cold.56+0x1bd/0x27d [ 122.048911] ? save_trace+0xe0/0x290 [ 122.052621] __lock_acquire+0x3399/0x4c20 [ 122.056968] ? kasan_check_write+0x14/0x20 [ 122.061388] ? mark_held_locks+0x130/0x130 [ 122.065624] ? kmem_cache_alloc_trace+0x152/0x750 [ 122.070778] ? apparmor_file_alloc_security+0x17b/0xac0 [ 122.076137] ? security_file_alloc+0x4c/0xa0 [ 122.080656] ? __alloc_file+0x12a/0x470 [ 122.084659] ? alloc_empty_file+0x72/0x170 [ 122.088967] ? dentry_open+0x71/0x1d0 [ 122.093065] ? ima_file_check+0xe5/0x130 [ 122.097123] ? path_openat+0x134a/0x5150 [ 122.101179] ? do_filp_open+0x255/0x380 [ 122.105143] ? do_sys_open+0x568/0x700 [ 122.109023] ? __x64_sys_open+0x7e/0xc0 [ 122.112993] ? do_syscall_64+0x1b9/0x820 [ 122.117049] ? entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 122.122493] ? graph_lock+0x270/0x270 [ 122.126292] ? graph_lock+0x270/0x270 [ 122.130086] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 122.135621] ? check_preemption_disabled+0x48/0x280 [ 122.140634] lock_acquire+0x1ed/0x520 [ 122.144432] ? mnt_want_write+0x3f/0xc0 [ 122.148482] ? lock_release+0xa10/0xa10 [ 122.152455] ? perf_trace_sched_process_exec+0x860/0x860 [ 122.157904] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 122.163441] ? fsnotify+0x50e/0xef0 [ 122.167159] __sb_start_write+0x214/0x370 [ 122.171309] ? mnt_want_write+0x3f/0xc0 [ 122.175285] mnt_want_write+0x3f/0xc0 [ 122.179080] ovl_want_write+0x76/0xa0 [ 122.182874] ovl_open_maybe_copy_up+0x12c/0x190 [ 122.187532] ovl_open+0xb3/0x260 [ 122.190985] do_dentry_open+0x499/0x1250 [ 122.195035] ? ovl_llseek+0x110/0x110 [ 122.198828] ? chown_common+0x730/0x730 [ 122.202801] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 122.208457] ? percpu_counter_add_batch+0x141/0x190 [ 122.213485] dentry_open+0x143/0x1d0 [ 122.217206] ima_calc_file_hash+0x324/0x570 [ 122.221532] ima_collect_measurement+0x619/0x730 [ 122.226302] ? ima_get_action+0xa0/0xa0 [ 122.230269] process_measurement+0x11fd/0x1bf0 [ 122.234856] ? ima_add_template_entry.cold.4+0x3c/0x3c [ 122.240142] ? find_held_lock+0x36/0x1c0 [ 122.244195] ? lock_downgrade+0x900/0x900 [ 122.248332] ? check_preemption_disabled+0x48/0x280 [ 122.253344] ? kasan_check_read+0x11/0x20 [ 122.257485] ? rcu_dynticks_curr_cpu_in_eqs+0xa2/0x170 [ 122.262751] ? rcu_read_unlock_special+0x370/0x370 [ 122.267678] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 122.273208] ? refcount_sub_and_test_checked+0x203/0x310 [ 122.278651] ? refcount_dec_if_one+0x180/0x180 [ 122.283221] ? aa_capable+0xe30/0xe30 [ 122.287020] ? __sanitizer_cov_trace_const_cmp8+0x18/0x20 [ 122.292557] ? ovl_open+0xc9/0x260 [ 122.296088] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 122.301617] ? do_dentry_open+0x945/0x1250 [ 122.305843] ? __sanitizer_cov_trace_const_cmp1+0x1a/0x20 [ 122.311371] ? apparmor_task_getsecid+0x96/0xd0 [ 122.316351] ima_file_check+0xe5/0x130 [ 122.320236] ? process_measurement+0x1bf0/0x1bf0 [ 122.325001] ? __sanitizer_cov_trace_switch+0x53/0x90 [ 122.330186] path_openat+0x134a/0x5150 [ 122.334065] ? rcu_softirq_qs+0x20/0x20 [ 122.338029] ? unwind_dump+0x190/0x190 [ 122.341910] ? path_lookupat.isra.43+0xc00/0xc00 [ 122.346657] ? unwind_get_return_address+0x61/0xa0 [ 122.351590] ? __sanitizer_cov_trace_cmp4+0x16/0x20 [ 122.356596] ? expand_files.part.8+0x571/0x9a0 [ 122.361346] ? find_held_lock+0x36/0x1c0 [ 122.365408] ? __alloc_fd+0x347/0x6e0 [ 122.369211] ? lock_downgrade+0x900/0x900 [ 122.373482] ? getname+0x19/0x20 [ 122.376859] ? kasan_check_read+0x11/0x20 [ 122.381185] ? do_raw_spin_unlock+0xa7/0x330 [ 122.385602] ? do_raw_spin_trylock+0x270/0x270 [ 122.390327] ? __lock_is_held+0xb5/0x140 [ 122.394384] ? __check_object_size+0xb1/0x782 [ 122.398887] ? _raw_spin_unlock+0x2c/0x50 [ 122.403042] ? __alloc_fd+0x347/0x6e0 [ 122.406846] do_filp_open+0x255/0x380 [ 122.410833] ? may_open_dev+0x100/0x100 [ 122.414993] ? get_unused_fd_flags+0x122/0x1a0 [ 122.419827] ? __alloc_fd+0x6e0/0x6e0 [ 122.423640] do_sys_open+0x568/0x700 [ 122.427356] ? filp_open+0x80/0x80 [ 122.431056] ? trace_hardirqs_off_caller+0x300/0x300 [ 122.436279] __x64_sys_open+0x7e/0xc0 [ 122.440080] do_syscall_64+0x1b9/0x820 [ 122.443965] ? entry_SYSCALL_64_after_hwframe+0x3e/0xbe [ 122.449323] ? syscall_return_slowpath+0x5e0/0x5e0 [ 122.454246] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 122.459094] ? trace_hardirqs_on_caller+0x310/0x310 [ 122.464110] ? prepare_exit_to_usermode+0x3b0/0x3b0 [ 122.469115] ? prepare_exit_to_usermode+0x291/0x3b0 [ 122.474125] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 122.478976] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 122.484159] RIP: 0033:0x457569 [ 122.487346] 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 [ 122.506325] RSP: 002b:00007fff21e66638 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 [ 122.514209] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457569 [ 122.521478] RDX: 0000000000000040 RSI: 0000000000000003 RDI: 0000000020000780 [ 122.528740] RBP: 000000000072bf00 R08: 0000000000000000 R09: 0000000000000000 [ 122.535996] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000021fb914 [ 122.543251] R13: 00000000004f0bdf R14: 00000000004d4c48 R15: 00000000ffffffff [