INIT: Entering runlevel: 2 [info] Using makefile-style concurrent boot in runlevel 2. [....] 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 'ci-upstream-next-kasan-gce-8,10.128.0.49' (ECDSA) to the list of known hosts. executing program syzkaller login: [ 615.391225] INFO: task syzkaller256657:3041 blocked for more than 120 seconds. [ 615.398733] Not tainted 4.13.0-rc6-next-20170825+ #9 [ 615.404582] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 615.412562] syzkaller256657 D23824 3041 3039 0x00000000 [ 615.418259] Call Trace: [ 615.420913] __schedule+0x8e8/0x2070 [ 615.424657] ? __sched_text_start+0x8/0x8 [ 615.428854] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 615.433920] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 615.438689] ? find_held_lock+0x39/0x1d0 [ 615.442796] ? _raw_spin_unlock_irqrestore+0x5e/0xba [ 615.447917] schedule+0x108/0x440 [ 615.451385] ? __schedule+0x2070/0x2070 [ 615.455372] ? mark_held_locks+0xb2/0x100 [ 615.459576] ? __local_bh_enable_ip+0x9d/0x160 [ 615.464169] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 615.469224] ? __lock_sock+0x1d7/0x2f0 [ 615.473120] ? trace_hardirqs_on+0xd/0x10 [ 615.477279] ? __local_bh_enable_ip+0x9d/0x160 [ 615.481873] __lock_sock+0x1dc/0x2f0 [ 615.485616] ? proto_unregister+0x5e0/0x5e0 [ 615.489956] ? finish_wait+0x490/0x490 [ 615.493871] ? lock_sock_nested+0x44/0x110 [ 615.498116] lock_sock_nested+0xf3/0x110 [ 615.502217] tcp_sendmsg+0x21/0x50 [ 615.505807] inet_sendmsg+0x11f/0x5e0 [ 615.509626] ? inet_recvmsg+0x5f0/0x5f0 [ 615.513650] ? selinux_socket_sendmsg+0x36/0x40 [ 615.518338] ? security_socket_sendmsg+0x89/0xb0 [ 615.523104] ? inet_recvmsg+0x5f0/0x5f0 [ 615.527109] sock_sendmsg+0xca/0x110 [ 615.530843] kernel_sendmsg+0x47/0x60 [ 615.534666] sock_no_sendpage+0x1cc/0x280 [ 615.538821] ? sock_no_shutdown+0x10/0x10 [ 615.543008] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 615.548051] tcp_sendpage_locked+0x10b/0x160 [ 615.552480] tcp_sendpage+0x43/0x60 [ 615.556115] ? tcp_sendpage_locked+0x160/0x160 [ 615.560709] inet_sendpage+0x1aa/0x660 [ 615.564601] ? inet_sendmsg+0x5e0/0x5e0 [ 615.568594] ? inet_sendmsg+0x5e0/0x5e0 [ 615.572574] kernel_sendpage+0x8d/0xe0 [ 615.576481] sock_sendpage+0x92/0xc0 [ 615.580212] ? kernel_sendpage+0xe0/0xe0 [ 615.584349] pipe_to_sendpage+0x290/0x3b0 [ 615.588512] ? direct_splice_actor+0x180/0x180 [ 615.593146] ? check_same_owner+0x320/0x320 [ 615.597477] ? splice_from_pipe_next.part.9+0x22a/0x2e0 [ 615.602863] __splice_from_pipe+0x343/0x750 [ 615.607194] ? direct_splice_actor+0x180/0x180 [ 615.611801] splice_from_pipe+0x1e9/0x330 [ 615.615956] ? direct_splice_actor+0x180/0x180 [ 615.620560] ? splice_shrink_spd+0xb0/0xb0 [ 615.624858] ? security_file_permission+0x89/0x1f0 [ 615.629812] generic_splice_sendpage+0x40/0x50 [ 615.634398] ? splice_from_pipe+0x330/0x330 [ 615.638741] SyS_splice+0x7d5/0x1630 [ 615.642515] ? do_writev+0xfc/0x2a0 [ 615.646173] ? compat_SyS_vmsplice+0x250/0x250 [ 615.650759] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 615.655801] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 615.660570] entry_SYSCALL_64_fastpath+0x1f/0xbe [ 615.665352] RIP: 0033:0x43fd79 [ 615.668539] RSP: 002b:00007ffc2fec72d8 EFLAGS: 00000216 ORIG_RAX: 0000000000000113 [ 615.676279] RAX: ffffffffffffffda RBX: 00000000004002c8 RCX: 000000000043fd79 [ 615.683553] RDX: 0000000000000005 RSI: 0000000000000000 RDI: 0000000000000003 [ 615.690858] RBP: 0000000000000086 R08: 0000000000000009 R09: 0000000000000004 [ 615.698139] R10: 0000000000000000 R11: 0000000000000216 R12: 00000000004016e0 [ 615.705429] R13: 0000000000401770 R14: 0000000000000000 R15: 0000000000000000 [ 615.712752] [ 615.712752] Showing all locks held in the system: [ 615.719101] 2 locks held by khungtaskd/644: [ 615.723436] #0: (rcu_read_lock){....}, at: [] watchdog+0x1c5/0xd60 [ 615.731594] #1: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0xd2/0x410 [ 615.740644] 1 lock held by rsyslogd/2931: [ 615.744805] #0: (&f->f_pos_lock){+.+.}, at: [] __fdget_pos+0x131/0x1a0 [ 615.753271] 2 locks held by getty/3013: [ 615.757255] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.765952] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.775330] 2 locks held by getty/3014: [ 615.779315] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.788033] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.797347] 2 locks held by getty/3015: [ 615.801321] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.810031] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.819339] 2 locks held by getty/3016: [ 615.823333] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.832041] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.841349] 2 locks held by getty/3017: [ 615.845326] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.854037] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.863344] 2 locks held by getty/3018: [ 615.867327] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.876038] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.885352] 2 locks held by getty/3019: [ 615.889324] #0: (&tty->ldisc_sem){++++}, at: [] ldsem_down_read+0x37/0x40 [ 615.898034] #1: (&ldata->atomic_read_lock){+.+.}, at: [] n_tty_read+0x2f2/0x1a40 [ 615.907335] 2 locks held by syzkaller256657/3041: [ 615.912184] #0: (&pipe->mutex/1){+.+.}, at: [] pipe_lock+0x56/0x70 [ 615.920321] #1: (sk_lock-AF_INET){+.+.}, at: [] tcp_sendpage+0x2e/0x60 [ 615.928762] [ 615.930390] ============================================= [ 615.930390] [ 615.937426] NMI backtrace for cpu 1 [ 615.941067] CPU: 1 PID: 644 Comm: khungtaskd Not tainted 4.13.0-rc6-next-20170825+ #9 [ 615.949006] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 615.958333] Call Trace: [ 615.960924] dump_stack+0x194/0x257 [ 615.964539] ? arch_local_irq_restore+0x53/0x53 [ 615.969193] nmi_cpu_backtrace+0x20e/0x240 [ 615.973402] ? kobject_synth_uevent+0xad0/0xad0 [ 615.978044] ? debug_check_no_locks_held+0x140/0x140 [ 615.983146] ? show_regs_print_info+0x65/0x65 [ 615.987644] ? irq_force_complete_move+0x390/0x390 [ 615.992551] nmi_trigger_cpumask_backtrace+0x122/0x180 [ 615.997805] arch_trigger_cpumask_backtrace+0x14/0x20 [ 616.002980] watchdog+0x90c/0xd60 [ 616.006415] ? reset_hung_task_detector+0xa0/0xa0 [ 616.011227] ? complete+0x18/0x80 [ 616.014673] ? __schedule+0x2070/0x2070 [ 616.018635] ? _raw_spin_unlock_irqrestore+0x31/0xba [ 616.023715] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 616.028708] ? trace_hardirqs_on+0xd/0x10 [ 616.032832] ? __kthread_parkme+0x173/0x240 [ 616.037136] kthread+0x39c/0x470 [ 616.040478] ? reset_hung_task_detector+0xa0/0xa0 [ 616.045292] ? kthread_create_on_node+0x100/0x100 [ 616.050118] ret_from_fork+0x2a/0x40 [ 616.053909] Sending NMI from CPU 1 to CPUs 0: [ 616.058414] NMI backtrace for cpu 0 [ 616.058419] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.13.0-rc6-next-20170825+ #9 [ 616.058422] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 616.058424] task: ffffffff85a2f3c0 task.stack: ffffffff85a00000 [ 616.058474] RIP: 0010:tick_irq_enter+0x1cd/0x390 [ 616.058476] RSP: 0018:ffff8801db207f68 EFLAGS: 00000046 [ 616.058480] RAX: dffffc0000000000 RBX: 0000000000000097 RCX: 0000000000000000 [ 616.058482] RDX: 1ffffffff0e0fcf0 RSI: 0000000000000000 RDI: ffffffff8707e780 [ 616.058484] RBP: ffff8801db207f88 R08: ffff88021fff901c R09: ffff88021fff9008 [ 616.058486] R10: ffff88021fff9010 R11: ffff88021fff901d R12: 0000000000063598 [ 616.058488] R13: ffff8801db21dc00 R14: 0000008f6ff14818 R15: ffffffff85a2f3c0 [ 616.058491] FS: 0000000000000000(0000) GS:ffff8801db200000(0000) knlGS:0000000000000000 [ 616.058493] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 616.058495] CR2: 00007fff380a7c98 CR3: 00000001d18fc000 CR4: 00000000001406f0 [ 616.058499] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 616.058501] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 616.058502] Call Trace: [ 616.058503] [ 616.058511] irq_enter+0xbd/0xe0 [ 616.058516] scheduler_ipi+0x8c/0x460 [ 616.058522] smp_reschedule_interrupt+0x61/0x80 [ 616.058525] reschedule_interrupt+0x9d/0xb0 [ 616.058527] [ 616.058530] RIP: 0010:native_safe_halt+0x6/0x10 [ 616.058532] RSP: 0018:ffffffff85a07ce0 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff02 [ 616.058535] RAX: dffffc0000000000 RBX: 1ffffffff0b40f9f RCX: 0000000000000000 [ 616.058537] RDX: 1ffffffff0b59188 RSI: 0000000000000001 RDI: ffffffff85ac8c40 [ 616.058539] RBP: ffffffff85a07ce0 R08: ffffffff85804a80 R09: 0000000000000000 [ 616.058541] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff85ac8c30 [ 616.058542] R13: ffffffff85a07d98 R14: 0000000000000000 R15: ffffffff85a2f3c0 [ 616.058550] default_idle+0xbf/0x460 [ 616.058554] ? __sched_text_end+0x4/0x4 [ 616.058559] ? tick_nohz_idle_enter+0xde/0x160 [ 616.058583] arch_cpu_idle+0xa/0x10 [ 616.058587] default_idle_call+0x36/0x90 [ 616.058591] do_idle+0x256/0x3b0 [ 616.058596] cpu_startup_entry+0x18/0x20 [ 616.058599] rest_init+0xed/0xf0 [ 616.058626] start_kernel+0x787/0x7ad [ 616.058630] ? mem_encrypt_init+0xb/0xb [ 616.058636] ? memcpy_orig+0x54/0x110 [ 616.058640] ? early_idt_handler_array+0x120/0x120 [ 616.058644] x86_64_start_reservations+0x2a/0x2c [ 616.058647] x86_64_start_kernel+0x13c/0x149 [ 616.058653] secondary_startup_64+0xa5/0xa5 [ 616.058657] Code: c7 c7 80 e7 07 87 48 b8 00 00 00 00 00 fc ff df 4d 89 f4 48 89 fa 4c 2b 25 71 17 a6 05 48 c1 ea 03 80 3c 02 00 0f 85 a4 01 00 00 <4c> 3b 25 fc 13 a6 05 0f 8d 14 01 00 00 e8 81 0b 0c 00 f6 c7 02 [ 616.059418] Kernel panic - not syncing: hung_task: blocked tasks [ 616.059424] CPU: 1 PID: 644 Comm: khungtaskd Not tainted 4.13.0-rc6-next-20170825+ #9 [ 616.059427] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 616.059430] Call Trace: [ 616.059439] dump_stack+0x194/0x257 [ 616.059449] ? arch_local_irq_restore+0x53/0x53 [ 616.059506] panic+0x1e4/0x41c [ 616.059514] ? refcount_error_report+0x214/0x214 [ 616.059539] watchdog+0x91d/0xd60 [ 616.059557] ? reset_hung_task_detector+0xa0/0xa0 [ 616.059564] ? complete+0x18/0x80 [ 616.059579] ? __schedule+0x2070/0x2070 [ 616.059593] ? _raw_spin_unlock_irqrestore+0x31/0xba [ 616.059603] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 616.059611] ? trace_hardirqs_on+0xd/0x10 [ 616.059617] ? __kthread_parkme+0x173/0x240