Pseudo-terminal will not be allocated because stdin is not a terminal. Warning: Permanently added '[ssh-serialport.googleapis.com]:9600,[216.239.38.127]:9600' (RSA) to the list of known hosts. Warning: Permanently added 'ci-upstream-kasan-gce-7,10.128.0.38' (ECDSA) to the list of known hosts. 2017/07/22 21:22:32 parsed 1 programs 2017/07/22 21:22:32 executed programs: 0 serialport: Connected to syzkaller.us-central1-c.ci-upstream-kasan-gce-7 port 1 (session ID: 4a352f44b2867b5bb9244c7c65d9f47b605d816392bb2ea97e030b4f32c50934, active connections: 1). 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 2017/07/22 21:22:37 executed programs: 848 2017/07/22 21:22:42 executed programs: 1610 syzkaller login: [ 54.568647] [ 54.569112] ================================ [ 54.569785] WARNING: inconsistent lock state [ 54.570396] 4.13.0-rc1+ #1 Not tainted [ 54.570915] -------------------------------- [ 54.571513] inconsistent {IN-SOFTIRQ-W} -> {SOFTIRQ-ON-W} usage. [ 54.572323] syz-executor4/9538 [HC0[0]:SC0[0]:HE1:SE1] takes: [ 54.573100] (slock-AF_INET){+.?...}, at: [] sk_clone_lock+0x438/0x11e0 [ 54.574201] {IN-SOFTIRQ-W} state was registered at: [ 54.574944] __lock_acquire+0xcbb/0x3dc0 [ 54.575510] lock_acquire+0x1d5/0x580 [ 54.576033] _raw_spin_lock+0x2a/0x40 [ 54.576569] sk_clone_lock+0x438/0x11e0 [ 54.577119] inet_csk_clone_lock+0x92/0x4f0 [ 54.577707] tcp_create_openreq_child+0x7e/0x1d70 [ 54.578382] tcp_v4_syn_recv_sock+0x113/0x1150 [ 54.579023] tcp_check_req+0xe7f/0x1620 [ 54.579577] tcp_v4_rcv+0x168e/0x2df0 [ 54.580101] ip_local_deliver_finish+0x2e2/0xba0 [ 54.580760] ip_local_deliver+0x1ce/0x6d0 [ 54.581326] ip_rcv_finish+0x8db/0x19c0 [ 54.581867] ip_rcv+0xc3f/0x17d0 [ 54.582335] __netif_receive_skb_core+0x1b05/0x3230 [ 54.583013] __netif_receive_skb+0x2c/0x1b0 [ 54.583601] netif_receive_skb_internal+0x16a/0x1a50 [ 54.584288] napi_gro_receive+0x3d0/0x500 [ 54.584856] receive_buf+0xaef/0x5720 [ 54.585376] virtnet_poll+0x50b/0xab0 [ 54.585895] net_rx_action+0x792/0x1910 [ 54.586454] __do_softirq+0x2f5/0xba3 [ 54.586976] irq_exit+0x1cc/0x200 [ 54.587470] do_IRQ+0xf6/0x190 [ 54.587911] ret_from_intr+0x0/0x1e [ 54.588409] native_safe_halt+0x6/0x10 [ 54.589951] default_idle+0xbf/0x460 [ 54.593721] arch_cpu_idle+0xa/0x10 [ 54.597397] default_idle_call+0x36/0x90 [ 54.601511] do_idle+0x256/0x3b0 [ 54.604972] cpu_startup_entry+0x18/0x20 [ 54.609088] start_secondary+0x277/0x310 [ 54.613203] verify_cpu+0x0/0xf1 [ 54.616620] irq event stamp: 4712 [ 54.620041] hardirqs last enabled at (4710): [] kmem_cache_free+0xd7/0x240 [ 54.628672] hardirqs last disabled at (4711): [] __local_bh_enable_ip+0x5c/0x160 [ 54.637736] softirqs last enabled at (4712): [] tun_rx_batched.isra.42+0x5bd/0x860 [ 54.647062] softirqs last disabled at (4668): [] tun_rx_batched.isra.42+0x5bd/0x860 [ 54.656383] [ 54.656383] other info that might help us debug this: [ 54.663014] Possible unsafe locking scenario: [ 54.663014] [ 54.669037] CPU0 [ 54.671587] ---- [ 54.674134] lock(slock-AF_INET); [ 54.677639] [ 54.680362] lock(slock-AF_INET); [ 54.684045] [ 54.684045] *** DEADLOCK *** [ 54.684045] [ 54.690069] 1 lock held by syz-executor4/9538: [ 54.694621] #0: (slock-AF_INET){+.?...}, at: [] sk_clone_lock+0x438/0x11e0 [ 54.703351] [ 54.703351] stack backtrace: [ 54.707815] CPU: 1 PID: 9538 Comm: syz-executor4 Not tainted 4.13.0-rc1+ #1 [ 54.714879] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 54.724198] Call Trace: [ 54.726756] dump_stack+0x194/0x257 [ 54.730351] ? arch_local_irq_restore+0x53/0x53 [ 54.734989] print_usage_bug+0x421/0x480 [ 54.739023] ? save_stack_trace+0x16/0x20 [ 54.743136] ? print_irqtrace_events+0x270/0x270 [ 54.747858] ? hlock_class+0x140/0x140 [ 54.751712] mark_lock+0xe59/0x13d0 [ 54.755304] ? check_usage_forwards+0x430/0x430 [ 54.759938] ? print_usage_bug+0x480/0x480 [ 54.764138] ? find_held_lock+0x35/0x1d0 [ 54.768168] ? __might_fault+0x110/0x1d0 [ 54.772194] ? lock_downgrade+0x990/0x990 [ 54.776308] ? lock_release+0xa40/0xa40 [ 54.780250] ? check_same_owner+0x320/0x320 [ 54.784541] ? rcu_pm_notify+0xc0/0xc0 [ 54.788401] ? netif_receive_skb+0xae/0x390 [ 54.792688] mark_held_locks+0xaf/0x100 [ 54.796629] ? __local_bh_enable_ip+0x9d/0x160 [ 54.801179] trace_hardirqs_on_caller+0x421/0x5c0 [ 54.805990] ? tun_rx_batched.isra.42+0x5bd/0x860 [ 54.810799] trace_hardirqs_on+0xd/0x10 [ 54.814740] __local_bh_enable_ip+0x9d/0x160 [ 54.819114] tun_rx_batched.isra.42+0x5fd/0x860 [ 54.823751] ? skb_get_hash_perturb+0x9d0/0x9d0 [ 54.828386] ? tun_sock_write_space+0x370/0x370 [ 54.833021] ? tun_free_netdev+0x1b0/0x1b0 [ 54.837224] tun_get_user+0xde5/0x2910 [ 54.841079] ? tun_chr_ioctl+0x40/0x40 [ 54.844937] ? find_held_lock+0x35/0x1d0 [ 54.848973] ? __fget+0x333/0x570 [ 54.852395] ? find_held_lock+0x35/0x1d0 [ 54.856422] ? __tun_get+0x1ab/0x2e0 [ 54.860101] ? lock_downgrade+0x990/0x990 [ 54.864215] ? lock_release+0xa40/0xa40 [ 54.868155] ? __lock_is_held+0xb6/0x140 [ 54.872185] ? __tun_get+0x1d4/0x2e0 [ 54.875863] ? tun_chr_close+0x60/0x60 [ 54.879717] tun_chr_write_iter+0xd8/0x190 [ 54.883919] __vfs_write+0x684/0x970 [ 54.887602] ? default_llseek+0x290/0x290 [ 54.891722] ? avc_policy_seqno+0x9/0x20 [ 54.895750] ? selinux_file_permission+0x82/0x460 [ 54.900560] ? rw_verify_area+0xe5/0x2b0 [ 54.904590] ? __fdget_raw+0x20/0x20 [ 54.908274] vfs_write+0x189/0x510 [ 54.911781] SyS_write+0xef/0x220 [ 54.915200] ? SyS_read+0x220/0x220 [ 54.918792] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 54.923775] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 54.928500] entry_SYSCALL_64_fastpath+0x1f/0xbe [ 54.933223] RIP: 0033:0x40b731 [ 54.936381] RSP: 002b:00007f2081a84c10 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 [ 54.944057] RAX: ffffffffffffffda RBX: 0000000000718000 RCX: 000000000040b731 [ 54.951296] RDX: 0000000000000037 RSI: 0000000020004000 RDI: 0000000000000003 [ 54.958534] RBP: 0000000000000086 R08: 0000000000000000 R09: 0000000000000000 [ 54.965770] R10: 00000000000f4246 R11: 0000000000000293 R12: 00000000004b9e11 [ 54.973007] R13: 00000000ffffffff R14: 0000000000000004 R15: 0000000000000001 [ 54.980404] BUG: scheduling while atomic: syz-executor4/9538/0x00000002 [ 54.987188] INFO: lockdep is turned off. [ 54.991260] Modules linked in: [ 54.994481] Kernel panic - not syncing: scheduling while atomic [ 54.994481] [ 55.002006] CPU: 1 PID: 9538 Comm: syz-executor4 Not tainted 4.13.0-rc1+ #1 [ 55.009077] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 55.018400] Call Trace: [ 55.020961] dump_stack+0x194/0x257 [ 55.024558] ? arch_local_irq_restore+0x53/0x53 [ 55.029199] ? print_modules+0x18c/0x2f7 [ 55.033235] panic+0x1e4/0x417 [ 55.036395] ? __warn+0x1d9/0x1d9 [ 55.039818] ? console_unlock+0x662/0xd80 [ 55.043944] __schedule_bug+0x11f/0x130 [ 55.047888] __schedule+0x1315/0x2070 [ 55.051660] ? __sched_text_start+0x8/0x8 [ 55.055776] ? lock_downgrade+0x990/0x990 [ 55.059892] ? lock_release+0xa40/0xa40 [ 55.063835] ? __lock_is_held+0xb6/0x140 [ 55.067873] ? __tun_get+0x1d4/0x2e0 [ 55.071554] ? tun_chr_close+0x60/0x60 [ 55.075413] schedule+0x108/0x440 [ 55.078835] ? tun_chr_write_iter+0x133/0x190 [ 55.083297] ? __schedule+0x2070/0x2070 [ 55.087240] ? __vfs_write+0xf7/0x970 [ 55.091012] ? default_llseek+0x290/0x290 [ 55.095134] ? __fsnotify_parent+0xb4/0x3a0 [ 55.099427] exit_to_usermode_loop+0x250/0x2d0 [ 55.103979] ? trace_event_raw_event_sys_exit+0x260/0x260 [ 55.109485] ? rw_verify_area+0xe5/0x2b0 [ 55.113517] ? __fdget_raw+0x20/0x20 [ 55.117200] syscall_return_slowpath+0x3a7/0x450 [ 55.121923] ? prepare_exit_to_usermode+0x220/0x220 [ 55.126905] ? SyS_write+0x184/0x220 [ 55.130590] ? SyS_read+0x220/0x220 [ 55.134184] ? trace_hardirqs_on_thunk+0x1a/0x1c [ 55.138911] entry_SYSCALL_64_fastpath+0xbc/0xbe [ 55.143632] RIP: 0033:0x40b731 [ 55.146790] RSP: 002b:00007f2081a84c10 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 [ 55.154465] RAX: 0000000000000037 RBX: 0000000000718000 RCX: 000000000040b731 [ 55.161706] RDX: 0000000000000037 RSI: 0000000020004000 RDI: 0000000000000003 [ 55.168943] RBP: 0000000000005510 R08: 0000000000000000 R09: 0000000000000000 [ 55.176180] R10: 00000000000f4246 R11: 0000000000000293 R12: 00000000004ba802 [ 55.183420] R13: 00000000ffffffff R14: 0000000000000037 R15: 0000000020004000 [ 55.191118] Dumping ftrace buffer: [ 55.194637] (ftrace buffer empty) [ 55.198315] Kernel Offset: disabled [ 55.201916] Rebooting in 86400 seconds..