[ 40.282492] audit: type=1800 audit(1546268001.035:32): pid=7818 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=collect_data cause=failed(directio) comm="startpar" name="ssh" dev="sda1" ino=2417 res=0 Starting mcstransd: [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting file context maintaining daemon: restorecond[?25l[?1c7[ ok 8[?25h[?0c. Debian GNU/Linux 7 syzkaller ttyS0 syzkaller login: [ 45.842183] kauditd_printk_skb: 2 callbacks suppressed [ 45.842196] audit: type=1400 audit(1546268006.685:35): avc: denied { map } for pid=7992 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. [ 168.169198] audit: type=1400 audit(1546268129.015:36): avc: denied { map } for pid=8004 comm="syz-executor280" path="/root/syz-executor280467238" 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 [ 168.209944] IPVS: ftp: loaded support on port[0] = 21 executing program [ 168.243232] audit: type=1400 audit(1546268129.085:37): avc: denied { associate } for pid=8005 comm="syz-executor280" name="syz0" scontext=unconfined_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=filesystem permissive=1 [ 168.377953] audit: type=1400 audit(1546268129.225:38): avc: denied { create } for pid=8006 comm="syz-executor280" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_generic_socket permissive=1 [ 168.402414] audit: type=1400 audit(1546268129.225:39): avc: denied { write } for pid=8006 comm="syz-executor280" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_generic_socket permissive=1 [ 168.426336] audit: type=1400 audit(1546268129.235:40): avc: denied { read } for pid=8006 comm="syz-executor280" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_generic_socket permissive=1 executing program [ 168.702775] sched: DL replenish lagged too much 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 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 [ 220.066245] WARNING: CPU: 0 PID: 9019 at kernel/sched/deadline.c:628 enqueue_task_dl+0x2355/0x3dc0 [ 220.076564] Kernel panic - not syncing: panic_on_warn set ... [ 220.082430] CPU: 0 PID: 9019 Comm: syz-executor280 Not tainted 4.20.0+ #1 [ 220.089333] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 220.098662] Call Trace: [ 220.101231] dump_stack+0x1db/0x2d0 [ 220.104842] ? dump_stack_print_info.cold+0x20/0x20 [ 220.109843] ? enqueue_task_dl+0x2350/0x3dc0 [ 220.114230] panic+0x2cb/0x589 [ 220.117420] ? add_taint.cold+0x16/0x16 [ 220.121392] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.125791] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 220.131306] ? __probe_kernel_read+0x1f4/0x250 [ 220.135870] ? __warn.cold+0x5/0x4b [ 220.139473] ? __warn+0xe8/0x1d0 [ 220.142824] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.147215] __warn.cold+0x20/0x4b [ 220.150734] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.155121] report_bug+0x263/0x2b0 [ 220.158731] do_error_trap+0x11b/0x200 [ 220.162598] do_invalid_op+0x37/0x50 [ 220.166376] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.170767] invalid_op+0x14/0x20 [ 220.174200] RIP: 0010:enqueue_task_dl+0x2355/0x3dc0 [ 220.179199] Code: 3c 02 00 0f 85 ba 05 00 00 49 8b b5 50 0a 00 00 e9 53 fa ff ff e8 fb f2 64 00 48 8d 4d d8 e9 48 dd ff ff 0f 0b e9 92 f1 ff ff <0f> 0b e9 18 f1 ff ff 4c 89 ef 4c 89 95 28 ff ff ff 4c 89 85 30 ff [ 220.198077] RSP: 0018:ffff88809eebfaf8 EFLAGS: 00010002 [ 220.203415] RAX: 0000000000000002 RBX: 1ffff11013dd7f6a RCX: dffffc0000000000 [ 220.210662] RDX: 000000333cf09f75 RSI: 0000000000000004 RDI: ffff8880ae62d850 [ 220.218001] RBP: ffff88809eebfbf8 R08: ffff88807fb0a538 R09: ffff88807fb0a2fc [ 220.225247] R10: ffff88807fb0a580 R11: ffff8880ae62dc7b R12: ffff88807fb0a2c0 [ 220.232515] R13: ffff8880ae62ce00 R14: ffff8880ae62ce00 R15: ffff88807fb0a58c [ 220.239771] ? enqueue_task_dl+0x1b7c/0x3dc0 [ 220.244159] ? add_lock_to_list.isra.0+0x450/0x450 [ 220.249068] ? switched_from_dl+0xf70/0xf70 [ 220.253381] ? __lock_is_held+0xb6/0x140 [ 220.257446] enqueue_task+0xb9/0x380 [ 220.261145] __sched_setscheduler+0xe32/0x1fe0 [ 220.265715] ? cpu_cgroup_fork+0x130/0x130 [ 220.269943] ? kasan_check_read+0x11/0x20 [ 220.274072] ? rcu_dynticks_curr_cpu_in_eqs+0xa2/0x170 [ 220.279329] ? rcu_softirq_qs+0x20/0x20 [ 220.283287] __x64_sys_sched_setattr+0x1af/0x2f0 [ 220.288020] ? __ia32_sys_sched_setparam+0x80/0x80 [ 220.292927] ? trace_hardirqs_on+0xbd/0x310 [ 220.297231] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 220.301970] do_syscall_64+0x1a3/0x800 [ 220.305839] ? syscall_return_slowpath+0x5f0/0x5f0 [ 220.310748] ? prepare_exit_to_usermode+0x3b0/0x3b0 [ 220.315740] ? __switch_to_asm+0x34/0x70 [ 220.319782] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 220.324608] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 220.329776] RIP: 0033:0x44c829 [ 220.332947] Code: e8 8c d8 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 eb c9 fb ff c3 66 2e 0f 1f 84 00 00 00 00 [ 220.351931] RSP: 002b:00007f28685e8ce8 EFLAGS: 00000246 ORIG_RAX: 000000000000013a [ 220.359628] RAX: ffffffffffffffda RBX: 00000000006e49f8 RCX: 000000000044c829 [ 220.366874] RDX: 0000000000000000 RSI: 0000000020000000 RDI: 0000000000000000 [ 220.374123] RBP: 00000000006e49f0 R08: 0000000000000000 R09: 0000000000000000 [ 220.381397] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006e49fc [ 220.388649] R13: 00007ffd1981c8af R14: 00007f28685e99c0 R15: 0000000000000001 [ 220.395905] [ 220.395909] ====================================================== [ 220.395913] WARNING: possible circular locking dependency detected [ 220.395916] 4.20.0+ #1 Not tainted [ 220.395920] ------------------------------------------------------ [ 220.395924] syz-executor280/9019 is trying to acquire lock: [ 220.395926] 000000001aef527c ((console_sem).lock){-.-.}, at: down_trylock+0x13/0x70 [ 220.395936] [ 220.395939] but task is already holding lock: [ 220.395942] 000000000ba17b09 (&rq->lock){-.-.}, at: task_rq_lock+0xc8/0x290 [ 220.395951] [ 220.395955] which lock already depends on the new lock. [ 220.395956] [ 220.395958] [ 220.395962] the existing dependency chain (in reverse order) is: [ 220.395964] [ 220.395965] -> #2 (&rq->lock){-.-.}: [ 220.395975] _raw_spin_lock+0x2f/0x40 [ 220.395978] task_fork_fair+0xb5/0x7a0 [ 220.395981] sched_fork+0x437/0xb90 [ 220.395984] copy_process+0x1ff6/0x8730 [ 220.395987] _do_fork+0x1a9/0x1170 [ 220.395990] kernel_thread+0x34/0x40 [ 220.395992] rest_init+0x28/0x37b [ 220.395995] arch_call_rest_init+0xe/0x1b [ 220.395998] start_kernel+0x882/0x8bd [ 220.396002] x86_64_start_reservations+0x29/0x2b [ 220.396005] x86_64_start_kernel+0x77/0x7b [ 220.396008] secondary_startup_64+0xa4/0xb0 [ 220.396009] [ 220.396011] -> #1 (&p->pi_lock){-.-.}: [ 220.396021] _raw_spin_lock_irqsave+0x95/0xcd [ 220.396024] try_to_wake_up+0xb9/0x1480 [ 220.396027] wake_up_process+0x10/0x20 [ 220.396030] __up.isra.0+0x1c0/0x2a0 [ 220.396033] up+0x13e/0x1c0 [ 220.396035] __up_console_sem+0xb7/0x1c0 [ 220.396039] console_unlock+0x778/0x11e0 [ 220.396041] con_write+0xa2/0xb0 [ 220.396044] n_tty_write+0x497/0x1220 [ 220.396047] tty_write+0x45b/0x7a0 [ 220.396050] __vfs_write+0x116/0xb40 [ 220.396053] vfs_write+0x20c/0x580 [ 220.396055] ksys_write+0x105/0x260 [ 220.396058] __x64_sys_write+0x73/0xb0 [ 220.396061] do_syscall_64+0x1a3/0x800 [ 220.396065] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 220.396067] [ 220.396068] -> #0 ((console_sem).lock){-.-.}: [ 220.396078] lock_acquire+0x1db/0x570 [ 220.396082] _raw_spin_lock_irqsave+0x95/0xcd [ 220.396084] down_trylock+0x13/0x70 [ 220.396088] __down_trylock_console_sem+0xa8/0x210 [ 220.396091] console_trylock+0x15/0xa0 [ 220.396094] vprintk_emit+0x351/0x960 [ 220.396097] vprintk_default+0x28/0x30 [ 220.396099] vprintk_func+0x7e/0x189 [ 220.396102] printk+0xba/0xed [ 220.396105] __warn+0x9e/0x1d0 [ 220.396107] report_bug+0x263/0x2b0 [ 220.396110] do_error_trap+0x11b/0x200 [ 220.396113] do_invalid_op+0x37/0x50 [ 220.396116] invalid_op+0x14/0x20 [ 220.396119] enqueue_task_dl+0x2355/0x3dc0 [ 220.396122] enqueue_task+0xb9/0x380 [ 220.396125] __sched_setscheduler+0xe32/0x1fe0 [ 220.396128] __x64_sys_sched_setattr+0x1af/0x2f0 [ 220.396131] do_syscall_64+0x1a3/0x800 [ 220.396135] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 220.396137] [ 220.396140] other info that might help us debug this: [ 220.396142] [ 220.396144] Chain exists of: [ 220.396145] (console_sem).lock --> &p->pi_lock --> &rq->lock [ 220.396158] [ 220.396161] Possible unsafe locking scenario: [ 220.396163] [ 220.396166] CPU0 CPU1 [ 220.396169] ---- ---- [ 220.396170] lock(&rq->lock); [ 220.396177] lock(&p->pi_lock); [ 220.396184] lock(&rq->lock); [ 220.396190] lock((console_sem).lock); [ 220.396195] [ 220.396198] *** DEADLOCK *** [ 220.396199] [ 220.396202] 3 locks held by syz-executor280/9019: [ 220.396204] #0: 0000000014b8e16d (rcu_read_lock){....}, at: __x64_sys_sched_setattr+0x144/0x2f0 [ 220.396216] #1: 00000000b31ff59d (&p->pi_lock){-.-.}, at: task_rq_lock+0x6a/0x290 [ 220.396228] #2: 000000000ba17b09 (&rq->lock){-.-.}, at: task_rq_lock+0xc8/0x290 [ 220.396240] [ 220.396242] stack backtrace: [ 220.396247] CPU: 0 PID: 9019 Comm: syz-executor280 Not tainted 4.20.0+ #1 [ 220.396252] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 220.396254] Call Trace: [ 220.396257] dump_stack+0x1db/0x2d0 [ 220.396261] ? dump_stack_print_info.cold+0x20/0x20 [ 220.396264] ? print_stack_trace+0x77/0xb0 [ 220.396266] ? vprintk_func+0x86/0x189 [ 220.396270] print_circular_bug.isra.0.cold+0x1cc/0x28f [ 220.396273] __lock_acquire+0x3014/0x4a30 [ 220.396276] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396279] ? mark_held_locks+0x100/0x100 [ 220.396283] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 220.396286] ? put_dec_trunc8+0x273/0x300 [ 220.396289] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 220.396292] ? number+0x956/0xc80 [ 220.396295] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396298] ? pointer+0x177/0x900 [ 220.396302] ? __sanitizer_cov_trace_const_cmp1+0x1a/0x20 [ 220.396305] ? add_lock_to_list.isra.0+0x450/0x450 [ 220.396308] ? pvclock_read_flags+0x160/0x160 [ 220.396311] lock_acquire+0x1db/0x570 [ 220.396314] ? down_trylock+0x13/0x70 [ 220.396317] ? lock_release+0xc40/0xc40 [ 220.396320] ? trace_hardirqs_on_caller+0x310/0x310 [ 220.396323] ? trace_hardirqs_off+0xb8/0x310 [ 220.396326] _raw_spin_lock_irqsave+0x95/0xcd [ 220.396329] ? down_trylock+0x13/0x70 [ 220.396332] ? vprintk_emit+0x351/0x960 [ 220.396335] down_trylock+0x13/0x70 [ 220.396338] ? vprintk_emit+0x351/0x960 [ 220.396341] __down_trylock_console_sem+0xa8/0x210 [ 220.396344] console_trylock+0x15/0xa0 [ 220.396347] vprintk_emit+0x351/0x960 [ 220.396350] ? wake_up_klogd+0x180/0x180 [ 220.396353] ? check_stack_object+0x114/0x160 [ 220.396357] ? __sanitizer_cov_trace_switch+0x49/0x80 [ 220.396360] ? __check_object_size+0xa3/0x77a [ 220.396363] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396366] vprintk_default+0x28/0x30 [ 220.396369] vprintk_func+0x7e/0x189 [ 220.396379] ? add_lock_to_list.isra.0+0x450/0x450 [ 220.396382] printk+0xba/0xed [ 220.396385] ? kmsg_dump_rewind_nolock+0xe4/0xe4 [ 220.396389] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396392] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 220.396395] ? __probe_kernel_read+0x1f4/0x250 [ 220.396399] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396401] __warn+0x9e/0x1d0 [ 220.396404] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396407] report_bug+0x263/0x2b0 [ 220.396410] do_error_trap+0x11b/0x200 [ 220.396413] do_invalid_op+0x37/0x50 [ 220.396416] ? enqueue_task_dl+0x2355/0x3dc0 [ 220.396418] invalid_op+0x14/0x20 [ 220.396422] RIP: 0010:enqueue_task_dl+0x2355/0x3dc0 [ 220.396432] Code: 3c 02 00 0f 85 ba 05 00 00 49 8b b5 50 0a 00 00 e9 53 fa ff ff e8 fb f2 64 00 48 8d 4d d8 e9 48 dd ff ff 0f 0b e9 92 f1 ff ff <0f> 0b e9 18 f1 ff ff 4c 89 ef 4c 89 95 28 ff ff ff 4c 89 85 30 ff [ 220.396435] RSP: 0018:ffff88809eebfaf8 EFLAGS: 00010002 [ 220.396441] RAX: 0000000000000002 RBX: 1ffff11013dd7f6a RCX: dffffc0000000000 [ 220.396446] RDX: 000000333cf09f75 RSI: 0000000000000004 RDI: ffff8880ae62d850 [ 220.396450] RBP: ffff88809eebfbf8 R08: ffff88807fb0a538 R09: ffff88807fb0a2fc [ 220.396455] R10: ffff88807fb0a580 R11: ffff8880ae62dc7b R12: ffff88807fb0a2c0 [ 220.396459] R13: ffff8880ae62ce00 R14: ffff8880ae62ce00 R15: ffff88807fb0a58c [ 220.396463] ? enqueue_task_dl+0x1b7c/0x3dc0 [ 220.396466] ? add_lock_to_list.isra.0+0x450/0x450 [ 220.396469] ? switched_from_dl+0xf70/0xf70 [ 220.396472] ? __lock_is_held+0xb6/0x140 [ 220.396475] enqueue_task+0xb9/0x380 [ 220.396478] __sched_setscheduler+0xe32/0x1fe0 [ 220.396481] ? cpu_cgroup_fork+0x130/0x130 [ 220.396484] ? kasan_check_read+0x11/0x20 [ 220.396488] ? rcu_dynticks_curr_cpu_in_eqs+0xa2/0x170 [ 220.396491] ? rcu_softirq_qs+0x20/0x20 [ 220.396494] __x64_sys_sched_setattr+0x1af/0x2f0 [ 220.396497] ? __ia32_sys_sched_setparam+0x80/0x80 [ 220.396500] ? trace_hardirqs_on+0xbd/0x310 [ 220.396504] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 220.396507] do_syscall_64+0x1a3/0x800 [ 220.396510] ? syscall_return_slowpath+0x5f0/0x5f0 [ 220.396513] ? prepare_exit_to_usermode+0x3b0/0x3b0 [ 220.396516] ? __switch_to_asm+0x34/0x70 [ 220.396520] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 220.396523] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 220.396526] RIP: 0033:0x44c829 [ 220.396536] Code: e8 8c d8 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 eb c9 fb ff c3 66 2e 0f 1f 84 00 00 00 00 [ 220.396539] RSP: 002b:00007f28685e8ce8 EFLAGS: 00000246 ORIG_RAX: 000000000000013a [ 220.396546] RAX: ffffffffffffffda RBX: 00000000006e49f8 RCX: 000000000044c829 [ 220.396551] RDX: 0000000000000000 RSI: 0000000020000000 RDI: 0000000000000000 [ 220.396555] RBP: 00000000006e49f0 R08: 0000000000000000 R09: 0000000000000000 [ 220.396560] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006e49fc [ 220.396564] R13: 00007ffd1981c8af R14: 00007f28685e99c0 R15: 0000000000000001 [ 221.532403] Shutting down cpus with NMI [ 222.383900] Kernel Offset: disabled [ 222.387519] Rebooting in 86400 seconds..