[info] Using makefile-style concurrent boot in runlevel 2. [ 24.891499] audit: type=1800 audit(1542816542.200:21): pid=5801 uid=0 auid=4294967295 ses=4294967295 subj=_ op=collect_data cause=failed(directio) comm="startpar" name="bootlogs" dev="sda1" ino=2419 res=0 [ 24.918510] audit: type=1800 audit(1542816542.200:22): pid=5801 uid=0 auid=4294967295 ses=4294967295 subj=_ 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.0.63' (ECDSA) to the list of known hosts. 2018/11/21 16:09:37 parsed 1 programs 2018/11/21 16:09:38 executed programs: 0 syzkaller login: [ 61.452378] IPVS: ftp: loaded support on port[0] = 21 [ 61.692978] bridge0: port 1(bridge_slave_0) entered blocking state [ 61.700028] bridge0: port 1(bridge_slave_0) entered disabled state [ 61.707229] device bridge_slave_0 entered promiscuous mode [ 61.726160] bridge0: port 2(bridge_slave_1) entered blocking state [ 61.732801] bridge0: port 2(bridge_slave_1) entered disabled state [ 61.739767] device bridge_slave_1 entered promiscuous mode [ 61.757503] IPv6: ADDRCONF(NETDEV_UP): veth0_to_bridge: link is not ready [ 61.777034] IPv6: ADDRCONF(NETDEV_UP): veth1_to_bridge: link is not ready [ 61.825130] bond0: Enslaving bond_slave_0 as an active interface with an up link [ 61.846464] bond0: Enslaving bond_slave_1 as an active interface with an up link [ 61.922982] IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready [ 61.930434] team0: Port device team_slave_0 added [ 61.948157] IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready [ 61.955326] team0: Port device team_slave_1 added [ 61.972679] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 61.994145] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 62.013214] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 62.032252] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 62.181856] bridge0: port 2(bridge_slave_1) entered blocking state [ 62.188379] bridge0: port 2(bridge_slave_1) entered forwarding state [ 62.195568] bridge0: port 1(bridge_slave_0) entered blocking state [ 62.201950] bridge0: port 1(bridge_slave_0) entered forwarding state [ 62.704684] 8021q: adding VLAN 0 to HW filter on device bond0 [ 62.756413] IPv6: ADDRCONF(NETDEV_UP): veth0: link is not ready [ 62.805525] IPv6: ADDRCONF(NETDEV_UP): veth1: link is not ready [ 62.812190] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 62.819259] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 62.867918] 8021q: adding VLAN 0 to HW filter on device team0 [ 63.175588] [ 63.177430] ====================================================== [ 63.183732] WARNING: possible circular locking dependency detected [ 63.190144] 4.20.0-rc3+ #122 Not tainted [ 63.194183] ------------------------------------------------------ [ 63.200488] syz-executor0/6225 is trying to acquire lock: [ 63.206006] 000000001881f73a (sb_writers#3){.+.+}, at: mnt_want_write+0x3f/0xc0 [ 63.213453] [ 63.213453] but task is already holding lock: [ 63.219586] 00000000c37872d6 (&iint->mutex){+.+.}, at: process_measurement+0x438/0x1bf0 [ 63.227719] [ 63.227719] which lock already depends on the new lock. [ 63.227719] [ 63.236020] [ 63.236020] the existing dependency chain (in reverse order) is: [ 63.243627] [ 63.243627] -> #1 (&iint->mutex){+.+.}: [ 63.249079] __mutex_lock+0x166/0x16f0 [ 63.253470] mutex_lock_nested+0x16/0x20 [ 63.258046] process_measurement+0x438/0x1bf0 [ 63.263046] ima_file_check+0xe5/0x130 [ 63.267441] path_openat+0x134a/0x5150 [ 63.271835] do_filp_open+0x255/0x380 [ 63.276136] do_sys_open+0x568/0x700 [ 63.280552] __x64_sys_open+0x7e/0xc0 [ 63.284856] do_syscall_64+0x1b9/0x820 [ 63.289249] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 63.294949] [ 63.294949] -> #0 (sb_writers#3){.+.+}: [ 63.300639] lock_acquire+0x1ed/0x520 [ 63.304943] __sb_start_write+0x214/0x370 [ 63.309601] mnt_want_write+0x3f/0xc0 [ 63.313899] ovl_want_write+0x76/0xa0 [ 63.318201] ovl_open_maybe_copy_up+0x12c/0x190 [ 63.323380] ovl_open+0xb3/0x260 [ 63.327882] do_dentry_open+0x499/0x1250 [ 63.332447] dentry_open+0x143/0x1d0 [ 63.336815] ima_calc_file_hash+0x324/0x570 [ 63.341653] ima_collect_measurement+0x619/0x730 [ 63.346911] process_measurement+0x11fd/0x1bf0 [ 63.352000] ima_file_check+0xe5/0x130 [ 63.356416] path_openat+0x134a/0x5150 [ 63.360812] do_filp_open+0x255/0x380 [ 63.365121] do_sys_open+0x568/0x700 [ 63.369454] __x64_sys_open+0x7e/0xc0 [ 63.373782] do_syscall_64+0x1b9/0x820 [ 63.378181] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 63.383956] [ 63.383956] other info that might help us debug this: [ 63.383956] [ 63.392091] Possible unsafe locking scenario: [ 63.392091] [ 63.398147] CPU0 CPU1 [ 63.402797] ---- ---- [ 63.407442] lock(&iint->mutex); [ 63.410873] lock(sb_writers#3); [ 63.416860] lock(&iint->mutex); [ 63.422873] lock(sb_writers#3); [ 63.426321] [ 63.426321] *** DEADLOCK *** [ 63.426321] [ 63.432378] 1 lock held by syz-executor0/6225: [ 63.436943] #0: 00000000c37872d6 (&iint->mutex){+.+.}, at: process_measurement+0x438/0x1bf0 [ 63.445522] [ 63.445522] stack backtrace: [ 63.450017] CPU: 0 PID: 6225 Comm: syz-executor0 Not tainted 4.20.0-rc3+ #122 [ 63.457276] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 63.466924] Call Trace: [ 63.469519] dump_stack+0x244/0x39d [ 63.473130] ? dump_stack_print_info.cold.1+0x20/0x20 [ 63.478317] ? vprintk_func+0x85/0x181 [ 63.482201] print_circular_bug.isra.35.cold.54+0x1bd/0x27d [ 63.487911] ? save_trace+0xe0/0x290 [ 63.491614] __lock_acquire+0x3399/0x4c20 [ 63.495748] ? kasan_check_read+0x11/0x20 [ 63.499884] ? mark_held_locks+0x130/0x130 [ 63.504110] ? depot_save_stack+0x292/0x470 [ 63.508550] ? trace_hardirqs_off_caller+0x310/0x310 [ 63.513646] ? _raw_spin_unlock_irqrestore+0x6d/0xd0 [ 63.518744] ? depot_save_stack+0x292/0x470 [ 63.523056] ? smk_access+0x53b/0x700 [ 63.526849] ? save_stack+0xa9/0xd0 [ 63.530474] ? zap_class+0x640/0x640 [ 63.534178] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 63.539710] ? smack_log+0x423/0x590 [ 63.543413] ? smk_access_entry+0x310/0x310 [ 63.547719] ? do_sys_open+0x568/0x700 [ 63.551594] lock_acquire+0x1ed/0x520 [ 63.555381] ? mnt_want_write+0x3f/0xc0 [ 63.559345] ? lock_release+0xa00/0xa00 [ 63.563317] ? perf_trace_sched_process_exec+0x860/0x860 [ 63.568759] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 63.574461] ? fsnotify+0x50e/0xef0 [ 63.578083] __sb_start_write+0x214/0x370 [ 63.582215] ? mnt_want_write+0x3f/0xc0 [ 63.586212] mnt_want_write+0x3f/0xc0 [ 63.590009] ovl_want_write+0x76/0xa0 [ 63.593807] ovl_open_maybe_copy_up+0x12c/0x190 [ 63.598460] ovl_open+0xb3/0x260 [ 63.601812] do_dentry_open+0x499/0x1250 [ 63.605866] ? ovl_llseek+0x110/0x110 [ 63.609661] ? chown_common+0x730/0x730 [ 63.613693] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 63.619226] ? percpu_counter_add_batch+0x141/0x190 [ 63.624232] dentry_open+0x143/0x1d0 [ 63.627932] ima_calc_file_hash+0x324/0x570 [ 63.632254] ima_collect_measurement+0x619/0x730 [ 63.637160] ? ima_get_action+0xa0/0xa0 [ 63.641137] process_measurement+0x11fd/0x1bf0 [ 63.645807] ? ima_add_template_entry.cold.4+0x3c/0x3c [ 63.651204] ? file_ra_state_init+0xd3/0x1e0 [ 63.655598] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 63.661119] ? find_held_lock+0x36/0x1c0 [ 63.665333] ? smack_task_getsecid+0x1e6/0x3c0 [ 63.669918] ? lock_downgrade+0x900/0x900 [ 63.674053] ? check_preemption_disabled+0x48/0x280 [ 63.679059] ? rcu_read_unlock_special+0x1c0/0x1c0 [ 63.683974] ? kasan_check_read+0x11/0x20 [ 63.688230] ? rcu_dynticks_curr_cpu_in_eqs+0xa2/0x170 [ 63.693495] ? rcu_softirq_qs+0x20/0x20 [ 63.697466] ? smack_task_getsecid+0x20d/0x3c0 [ 63.702041] ? smack_cred_getsecid+0x230/0x230 [ 63.706605] ? ovl_llseek+0x110/0x110 [ 63.710402] ima_file_check+0xe5/0x130 [ 63.714269] ? process_measurement+0x1bf0/0x1bf0 [ 63.719012] ? __sanitizer_cov_trace_switch+0x53/0x90 [ 63.724206] path_openat+0x134a/0x5150 [ 63.728084] ? rcu_softirq_qs+0x20/0x20 [ 63.732047] ? unwind_dump+0x190/0x190 [ 63.735921] ? path_lookupat.isra.43+0xc00/0xc00 [ 63.740653] ? unwind_get_return_address+0x61/0xa0 [ 63.745564] ? __sanitizer_cov_trace_cmp4+0x16/0x20 [ 63.750572] ? expand_files.part.8+0x571/0x9a0 [ 63.755158] ? find_held_lock+0x36/0x1c0 [ 63.759213] ? __alloc_fd+0x347/0x6e0 [ 63.762996] ? lock_downgrade+0x900/0x900 [ 63.767124] ? getname+0x19/0x20 [ 63.770589] ? kasan_check_read+0x11/0x20 [ 63.774720] ? do_raw_spin_unlock+0xa7/0x330 [ 63.779107] ? do_raw_spin_trylock+0x270/0x270 [ 63.783680] ? __lock_is_held+0xb5/0x140 [ 63.787725] ? __check_object_size+0xb1/0x782 [ 63.792213] ? _raw_spin_unlock+0x2c/0x50 [ 63.796484] ? __alloc_fd+0x347/0x6e0 [ 63.800278] do_filp_open+0x255/0x380 [ 63.804117] ? may_open_dev+0x100/0x100 [ 63.808092] ? get_unused_fd_flags+0x122/0x1a0 [ 63.812668] ? __alloc_fd+0x6e0/0x6e0 [ 63.816459] do_sys_open+0x568/0x700 [ 63.820157] ? filp_open+0x80/0x80 [ 63.823690] ? trace_hardirqs_off_caller+0x310/0x310 [ 63.828776] __x64_sys_open+0x7e/0xc0 [ 63.832557] do_syscall_64+0x1b9/0x820 [ 63.836437] ? entry_SYSCALL_64_after_hwframe+0x3e/0xbe [ 63.841946] ? syscall_return_slowpath+0x5e0/0x5e0 [ 63.847011] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 63.851949] ? trace_hardirqs_on_caller+0x310/0x310 [ 63.857100] ? prepare_exit_to_usermode+0x3b0/0x3b0 [ 63.862280] ? prepare_exit_to_usermode+0x291/0x3b0 [ 63.867431] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 63.872265] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 63.877441] RIP: 0033:0x457569 [ 63.880624] 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 [ 63.899613] RSP: 002b:00007ffd93abed08 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 [ 63.907306] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457569 [ 63.914563] RDX: 0000000000000040 RSI: 0000000000000003 RDI: 0000000020000780 [ 63.921816] RBP: 000000000072bf00 R08: 0000000000000000 R09: 0000000000000000 [