[info] Using makefile-style concurrent boot in runlevel 2. [ 25.308554] audit: type=1800 audit(1543365587.406:21): pid=5827 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="startpar" name="bootlogs" dev="sda1" ino=2419 res=0 [ 25.328441] audit: type=1800 audit(1543365587.426:22): pid=5827 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.14' (ECDSA) to the list of known hosts. executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program syzkaller login: [ 76.984494] [ 76.986155] ===================================================== [ 76.992368] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 76.999100] 4.20.0-rc4+ #255 Not tainted [ 77.003203] ----------------------------------------------------- [ 77.009487] syz-executor422/6262 [HC0[0]:SC0[0]:HE0:SE1] is trying to acquire: [ 77.016835] 000000003024d06f (&ctx->fd_wqh){....}, at: aio_poll+0x760/0x1420 [ 77.024158] [ 77.024158] and this task is already holding: [ 77.030123] 000000002d56904f (&(&ctx->ctx_lock)->rlock){..-.}, at: aio_poll+0x738/0x1420 [ 77.038342] which would create a new lock dependency: [ 77.043506] (&(&ctx->ctx_lock)->rlock){..-.} -> (&ctx->fd_wqh){....} [ 77.050074] [ 77.050074] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 77.058111] (&(&ctx->ctx_lock)->rlock){..-.} [ 77.058118] [ 77.058118] ... which became SOFTIRQ-irq-safe at: [ 77.068905] lock_acquire+0x1ed/0x520 [ 77.072780] _raw_spin_lock_irq+0x61/0x80 [ 77.077005] free_ioctx_users+0xbc/0x710 [ 77.081142] percpu_ref_switch_to_atomic_rcu+0x563/0x730 [ 77.086680] rcu_process_callbacks+0x100a/0x1ac0 [ 77.091512] __do_softirq+0x308/0xb7e [ 77.095385] irq_exit+0x17f/0x1c0 [ 77.098908] smp_apic_timer_interrupt+0x1cb/0x760 [ 77.103819] apic_timer_interrupt+0xf/0x20 [ 77.108154] native_safe_halt+0x6/0x10 [ 77.112125] default_idle+0xbf/0x490 [ 77.115913] arch_cpu_idle+0x10/0x20 [ 77.119700] default_idle_call+0x6d/0x90 [ 77.123828] do_idle+0x49b/0x5c0 [ 77.127268] cpu_startup_entry+0x18/0x20 [ 77.131400] rest_init+0x243/0x372 [ 77.135010] arch_call_rest_init+0xe/0x1b [ 77.139224] start_kernel+0x9f0/0xa2b [ 77.143095] x86_64_start_reservations+0x2e/0x30 [ 77.147927] x86_64_start_kernel+0x76/0x79 [ 77.152229] secondary_startup_64+0xa4/0xb0 [ 77.156616] [ 77.156616] to a SOFTIRQ-irq-unsafe lock: [ 77.162221] (&ctx->fault_pending_wqh){+.+.} [ 77.162228] [ 77.162228] ... which became SOFTIRQ-irq-unsafe at: [ 77.173148] ... [ 77.173162] lock_acquire+0x1ed/0x520 [ 77.178903] _raw_spin_lock+0x2d/0x40 [ 77.182773] userfaultfd_release+0x63e/0x8d0 [ 77.188207] __fput+0x385/0xa30 [ 77.191553] ____fput+0x15/0x20 [ 77.194904] task_work_run+0x1e8/0x2a0 [ 77.198881] get_signal+0x1558/0x1980 [ 77.202747] do_signal+0x9c/0x21c0 [ 77.206357] exit_to_usermode_loop+0x2e5/0x380 [ 77.211010] do_fast_syscall_32+0xcd5/0xfb2 [ 77.215403] entry_SYSENTER_compat+0x70/0x7f [ 77.219875] [ 77.219875] other info that might help us debug this: [ 77.219875] [ 77.228155] Chain exists of: [ 77.228155] &(&ctx->ctx_lock)->rlock --> &ctx->fd_wqh --> &ctx->fault_pending_wqh [ 77.228155] [ 77.240427] Possible interrupt unsafe locking scenario: [ 77.240427] [ 77.247337] CPU0 CPU1 [ 77.251984] ---- ---- [ 77.256625] lock(&ctx->fault_pending_wqh); [ 77.261122] local_irq_disable(); [ 77.267173] lock(&(&ctx->ctx_lock)->rlock); [ 77.274175] lock(&ctx->fd_wqh); [ 77.280183] [ 77.282923] lock(&(&ctx->ctx_lock)->rlock); [ 77.287572] [ 77.287572] *** DEADLOCK *** [ 77.287572] [ 77.293624] 1 lock held by syz-executor422/6262: [ 77.298355] #0: 000000002d56904f (&(&ctx->ctx_lock)->rlock){..-.}, at: aio_poll+0x738/0x1420 [ 77.307015] [ 77.307015] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 77.316109] -> (&(&ctx->ctx_lock)->rlock){..-.} { [ 77.320929] IN-SOFTIRQ-W at: [ 77.324206] lock_acquire+0x1ed/0x520 [ 77.329652] _raw_spin_lock_irq+0x61/0x80 [ 77.335437] free_ioctx_users+0xbc/0x710 [ 77.341139] percpu_ref_switch_to_atomic_rcu+0x563/0x730 [ 77.348279] rcu_process_callbacks+0x100a/0x1ac0 [ 77.354693] __do_softirq+0x308/0xb7e [ 77.360129] irq_exit+0x17f/0x1c0 [ 77.365213] smp_apic_timer_interrupt+0x1cb/0x760 [ 77.371688] apic_timer_interrupt+0xf/0x20 [ 77.377561] native_safe_halt+0x6/0x10 [ 77.383084] default_idle+0xbf/0x490 [ 77.388436] arch_cpu_idle+0x10/0x20 [ 77.393788] default_idle_call+0x6d/0x90 [ 77.399482] do_idle+0x49b/0x5c0 [ 77.404481] cpu_startup_entry+0x18/0x20 [ 77.410183] rest_init+0x243/0x372 [ 77.415416] arch_call_rest_init+0xe/0x1b [ 77.421208] start_kernel+0x9f0/0xa2b [ 77.426654] x86_64_start_reservations+0x2e/0x30 [ 77.433052] x86_64_start_kernel+0x76/0x79 [ 77.438922] secondary_startup_64+0xa4/0xb0 [ 77.445023] INITIAL USE at: [ 77.448259] lock_acquire+0x1ed/0x520 [ 77.453612] _raw_spin_lock_irq+0x61/0x80 [ 77.459313] free_ioctx_users+0xbc/0x710 [ 77.464928] percpu_ref_switch_to_atomic_rcu+0x563/0x730 [ 77.471932] rcu_process_callbacks+0x100a/0x1ac0 [ 77.478240] __do_softirq+0x308/0xb7e [ 77.483584] irq_exit+0x17f/0x1c0 [ 77.488581] smp_apic_timer_interrupt+0x1cb/0x760 [ 77.494976] apic_timer_interrupt+0xf/0x20 [ 77.500761] native_safe_halt+0x6/0x10 [ 77.506201] default_idle+0xbf/0x490 [ 77.511461] arch_cpu_idle+0x10/0x20 [ 77.516721] default_idle_call+0x6d/0x90 [ 77.522330] do_idle+0x49b/0x5c0 [ 77.527242] cpu_startup_entry+0x18/0x20 [ 77.532845] rest_init+0x243/0x372 [ 77.537931] arch_call_rest_init+0xe/0x1b [ 77.543619] start_kernel+0x9f0/0xa2b [ 77.548964] x86_64_start_reservations+0x2e/0x30 [ 77.555261] x86_64_start_kernel+0x76/0x79 [ 77.561041] secondary_startup_64+0xa4/0xb0 [ 77.566902] } [ 77.568691] ... key at: [] __key.51037+0x0/0x40 [ 77.575422] ... acquired at: [ 77.578517] lock_acquire+0x1ed/0x520 [ 77.582572] _raw_spin_lock+0x2d/0x40 [ 77.586542] aio_poll+0x760/0x1420 [ 77.590237] io_submit_one+0xa49/0xf80 [ 77.594279] __ia32_compat_sys_io_submit+0x1b4/0x580 [ 77.599539] do_fast_syscall_32+0x34d/0xfb2 [ 77.604030] entry_SYSENTER_compat+0x70/0x7f [ 77.608712] [ 77.610325] [ 77.610325] the dependencies between the lock to be acquired [ 77.610328] and SOFTIRQ-irq-unsafe lock: [ 77.621711] -> (&ctx->fault_pending_wqh){+.+.} { [ 77.626540] HARDIRQ-ON-W at: [ 77.629965] lock_acquire+0x1ed/0x520 [ 77.635596] _raw_spin_lock+0x2d/0x40 [ 77.641211] userfaultfd_release+0x63e/0x8d0 [ 77.647430] __fput+0x385/0xa30 [ 77.652514] ____fput+0x15/0x20 [ 77.657602] task_work_run+0x1e8/0x2a0 [ 77.663301] get_signal+0x1558/0x1980 [ 77.668915] do_signal+0x9c/0x21c0 [ 77.674264] exit_to_usermode_loop+0x2e5/0x380 [ 77.680658] do_fast_syscall_32+0xcd5/0xfb2 [ 77.686916] entry_SYSENTER_compat+0x70/0x7f [ 77.693145] SOFTIRQ-ON-W at: [ 77.696505] lock_acquire+0x1ed/0x520 [ 77.702115] _raw_spin_lock+0x2d/0x40 [ 77.707727] userfaultfd_release+0x63e/0x8d0 [ 77.713940] __fput+0x385/0xa30 [ 77.719021] ____fput+0x15/0x20 [ 77.724158] task_work_run+0x1e8/0x2a0 [ 77.729865] get_signal+0x1558/0x1980 [ 77.735474] do_signal+0x9c/0x21c0 [ 77.740822] exit_to_usermode_loop+0x2e5/0x380 [ 77.747210] do_fast_syscall_32+0xcd5/0xfb2 [ 77.753341] entry_SYSENTER_compat+0x70/0x7f [ 77.759556] INITIAL USE at: [ 77.762828] lock_acquire+0x1ed/0x520 [ 77.768354] _raw_spin_lock+0x2d/0x40 [ 77.773876] userfaultfd_ctx_read+0x4f3/0x2180 [ 77.780179] userfaultfd_read+0x1e2/0x2c0 [ 77.786177] __vfs_read+0x117/0x9b0 [ 77.791525] vfs_read+0x17f/0x3c0 [ 77.796699] ksys_read+0x101/0x260 [ 77.801958] __ia32_sys_read+0x71/0xb0 [ 77.807571] do_fast_syscall_32+0x34d/0xfb2 [ 77.813612] entry_SYSENTER_compat+0x70/0x7f [ 77.819735] } [ 77.821616] ... key at: [] __key.44641+0x0/0x40 [ 77.828477] ... acquired at: [ 77.831667] _raw_spin_lock+0x2d/0x40 [ 77.835628] userfaultfd_ctx_read+0x4f3/0x2180 [ 77.840366] userfaultfd_read+0x1e2/0x2c0 [ 77.844668] __vfs_read+0x117/0x9b0 [ 77.848448] vfs_read+0x17f/0x3c0 [ 77.852060] ksys_read+0x101/0x260 [ 77.855752] __ia32_sys_read+0x71/0xb0 [ 77.859792] do_fast_syscall_32+0x34d/0xfb2 [ 77.864267] entry_SYSENTER_compat+0x70/0x7f [ 77.868822] [ 77.870427] -> (&ctx->fd_wqh){....} { [ 77.874209] INITIAL USE at: [ 77.877386] lock_acquire+0x1ed/0x520 [ 77.882730] _raw_spin_lock_irq+0x61/0x80 [ 77.888423] userfaultfd_ctx_read+0x2e4/0x2180 [ 77.894551] userfaultfd_read+0x1e2/0x2c0 [ 77.900241] __vfs_read+0x117/0x9b0 [ 77.905422] vfs_read+0x17f/0x3c0 [ 77.910422] ksys_read+0x101/0x260 [ 77.915514] __ia32_sys_read+0x71/0xb0 [ 77.921009] do_fast_syscall_32+0x34d/0xfb2 [ 77.926887] entry_SYSENTER_compat+0x70/0x7f [ 77.932922] } [ 77.934716] ... key at: [] __key.44644+0x0/0x40 [ 77.941448] ... acquired at: [ 77.944549] lock_acquire+0x1ed/0x520 [ 77.948509] _raw_spin_lock+0x2d/0x40 [ 77.952466] aio_poll+0x760/0x1420 [ 77.956174] io_submit_one+0xa49/0xf80 [ 77.960220] __ia32_compat_sys_io_submit+0x1b4/0x580 [ 77.965477] do_fast_syscall_32+0x34d/0xfb2 [ 77.969954] entry_SYSENTER_compat+0x70/0x7f [ 77.974674] [ 77.976281] [ 77.976281] stack backtrace: [ 77.980764] CPU: 1 PID: 6262 Comm: syz-executor422 Not tainted 4.20.0-rc4+ #255 [ 77.988186] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 77.997519] Call Trace: [ 78.000097] dump_stack+0x244/0x39d [ 78.003710] ? dump_stack_print_info.cold.1+0x20/0x20 [ 78.008898] ? print_shortest_lock_dependencies.cold.55+0x18e/0x211 [ 78.015298] ? vprintk_func+0x85/0x181 [ 78.019173] check_usage.cold.58+0x6d5/0xad1 [ 78.023567] ? check_usage_forwards+0x3d0/0x3d0 [ 78.028217] ? __lock_acquire+0x62f/0x4c20 [ 78.032435] ? __schedule+0x8d7/0x21d0 [ 78.036305] ? lockdep_on+0x50/0x50 [ 78.039916] ? mark_held_locks+0x130/0x130 [ 78.044136] ? __lock_acquire+0x62f/0x4c20 [ 78.048348] ? zap_class+0x640/0x640 [ 78.052044] ? trace_event_raw_event_lock_acquire+0x440/0x440 [ 78.057913] __lock_acquire+0x238a/0x4c20 [ 78.062045] ? __lock_acquire+0x238a/0x4c20 [ 78.066353] ? mark_held_locks+0x130/0x130 [ 78.070570] ? zap_class+0x640/0x640 [ 78.074267] ? _raw_spin_unlock_irqrestore+0x82/0xd0 [ 78.079355] ? find_held_lock+0x36/0x1c0 [ 78.083402] ? add_wait_queue+0x1b9/0x2b0 [ 78.087530] ? _raw_spin_unlock_irqrestore+0x82/0xd0 [ 78.092614] ? _raw_spin_unlock_irqrestore+0x82/0xd0 [ 78.097700] ? lockdep_hardirqs_on+0x3bb/0x5b0 [ 78.102339] ? trace_hardirqs_on+0xbd/0x310 [ 78.106666] ? kasan_check_read+0x11/0x20 [ 78.110815] ? trace_hardirqs_off_caller+0x310/0x310 [ 78.115909] ? rcu_softirq_qs+0x20/0x20 [ 78.119870] ? _raw_spin_unlock_irqrestore+0x6d/0xd0 [ 78.124969] ? add_wait_queue+0x1b9/0x2b0 [ 78.129336] ? __wake_up_locked_key_bookmark+0x20/0x20 [ 78.134753] lock_acquire+0x1ed/0x520 [ 78.138545] ? aio_poll+0x760/0x1420 [ 78.142253] ? lock_release+0xa00/0xa00 [ 78.146219] ? kasan_check_read+0x11/0x20 [ 78.150348] ? do_raw_spin_lock+0x14f/0x350 [ 78.154651] ? __ia32_sys_eventfd+0x40/0x40 [ 78.158959] ? rwlock_bug.part.2+0x90/0x90 [ 78.163182] ? trace_hardirqs_on+0x310/0x310 [ 78.167651] ? __save_stack_trace+0x8d/0xf0 [ 78.171976] _raw_spin_lock+0x2d/0x40 [ 78.175767] ? aio_poll+0x760/0x1420 [ 78.179472] aio_poll+0x760/0x1420 [ 78.182997] ? free_ioctx_users+0x710/0x710 [ 78.187406] ? kmem_cache_alloc+0x12e/0x730 [ 78.191724] ? aio_setup_rw+0x170/0x170 [ 78.195686] ? zap_class+0x640/0x640 [ 78.199428] ? mark_held_locks+0x130/0x130 [ 78.203653] ? zap_class+0x640/0x640 [ 78.207351] ? find_held_lock+0x36/0x1c0 [ 78.211397] ? find_held_lock+0x36/0x1c0 [ 78.215446] ? __might_fault+0x12b/0x1e0 [ 78.219494] ? lock_downgrade+0x900/0x900 [ 78.223626] ? lock_release+0xa00/0xa00 [ 78.227582] ? perf_trace_sched_process_exec+0x860/0x860 [ 78.233021] ? kasan_check_read+0x11/0x20 [ 78.237151] ? rcu_dynticks_curr_cpu_in_eqs+0xa2/0x170 [ 78.242411] io_submit_one+0xa49/0xf80 [ 78.246287] ? io_submit_one+0xa49/0xf80 [ 78.250329] ? aio_poll+0x1420/0x1420 [ 78.254109] ? __might_fault+0x12b/0x1e0 [ 78.258211] ? lock_downgrade+0x900/0x900 [ 78.262357] ? perf_trace_sched_process_exec+0x860/0x860 [ 78.267816] ? lock_release+0xa00/0xa00 [ 78.271781] ? perf_trace_sched_process_exec+0x860/0x860 [ 78.277226] __ia32_compat_sys_io_submit+0x1b4/0x580 [ 78.282316] ? __ia32_sys_io_submit+0x570/0x570 [ 78.287039] ? trace_hardirqs_on+0xbd/0x310 [ 78.291363] ? entry_SYSENTER_compat+0x70/0x7f [ 78.295933] ? trace_hardirqs_off_caller+0x310/0x310 [ 78.301196] ? find_held_lock+0x36/0x1c0 [ 78.305249] do_fast_syscall_32+0x34d/0xfb2 [ 78.309564] ? __ia32_sys_io_submit+0x570/0x570 [ 78.314218] ? do_fast_syscall_32+0x34d/0xfb2 [ 78.318702] ? do_int80_syscall_32+0x890/0x890 [ 78.323315] ? entry_SYSENTER_compat+0x68/0x7f [ 78.327892] ? trace_hardirqs_off_caller+0xbb/0x310 [ 78.332900] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 78.337727] ? trace_hardirqs_on_caller+0x310/0x310 [ 78.342726] ? prepare_exit_to_usermode+0x3b0/0x3b0 [ 78.347727] ? post_copy_siginfo_from_user.isra.25.part.26+0x250/0x250 [ 78.354383] ? __switch_to_asm+0x40/0x70 [ 78.358477] ? __switch_to_asm+0x34/0x70 [ 78.362537] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 78.367371] entry_SYSENTER_compat+0x70/0x7f [ 78.371769] RIP: 0023:0xf7f25a29 [ 78.375121] Code: 85 d2 74 02 89 0a 5b 5d c3 8b 04 24 c3 8b 14 24 c3 8b 3c 24 c3 90 90 90 90 90 90 90 90 90 90 90 90 51 52 55 89 e5 0f 34 cd 80 <5d> 5a 59 c3 90 90 90 90 eb 0d 90 90 90 90 90 90 90 90 90 90 90 90 [ 78.394014] RSP: 002b:00000000f7eff1fc EFLAGS: 00000293 ORIG_RAX: 00000000000000f8 [ 78.401707] RAX: ffffffffffffffda RBX: 00000000f7f00000 RCX: 0000000000000001 [ 78.408960] RDX: 0000000020000b00 RSI: 0000000000000000 RDI: 0000000000000000 [ 78.416212] RBP: 00000000003d0f00 R08: 0000000000000000 R09: 0000000000000000 [ 78.423460] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 78.430709] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program executing program