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-9,10.128.15.220' (ECDSA) to the list of known hosts. executing program executing program executing program executing program executing program executing program executing program executing program syzkaller login: [ 107.091057] BUG: sleeping function called from invalid context at net/core/sock.c:2758 [ 107.092316] in_atomic(): 1, irqs_disabled(): 0, pid: 21, name: kworker/u4:1 [ 107.093363] 2 locks held by kworker/u4:1/21: [ 107.093951] #0: ("%s""kkcmd"){+.+.}, at: [] process_one_work+0xadf/0x1bc0 [ 107.095152] #1: (((&strp->msg_timer))){+.-.}, at: [] call_timer_fn+0x1cd/0x830 [ 107.096381] CPU: 0 PID: 21 Comm: kworker/u4:1 Not tainted 4.14.0-rc5-next-20171017+ #35 [ 107.097463] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 107.098689] Workqueue: kkcmd kcm_tx_work [ 107.099233] Call Trace: [ 107.099586] [ 107.099890] dump_stack+0x194/0x257 [ 107.100383] ? arch_local_irq_restore+0x53/0x53 [ 107.101006] ? print_lock+0x9f/0xa2 [ 107.101498] ? lockdep_print_held_locks+0xc4/0x130 [ 107.102161] ___might_sleep+0x2b2/0x470 [ 107.102696] ? trace_event_raw_event_sched_switch+0x8a0/0x8a0 [ 107.102931] device lo entered promiscuous mode [ 107.104096] ? lock_downgrade+0x990/0x990 [ 107.104660] __might_sleep+0x95/0x190 [ 107.105169] ? __lock_is_held+0xb6/0x140 [ 107.105732] lock_sock_nested+0x37/0x110 [ 107.106296] strp_sock_lock+0x2e/0x40 [ 107.106809] strp_msg_timeout+0x6c/0xf0 [ 107.107346] call_timer_fn+0x233/0x830 [ 107.107871] ? default_read_sock_done+0x20/0x20 [ 107.108505] ? __next_timer_interrupt+0x150/0x150 [ 107.109158] ? __run_timers+0x7ef/0xb90 [ 107.109692] ? lock_downgrade+0x990/0x990 [ 107.110254] ? do_raw_spin_trylock+0x190/0x190 [ 107.110872] ? mark_held_locks+0xaf/0x100 [ 107.112767] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 107.117757] ? default_read_sock_done+0x20/0x20 [ 107.122395] __run_timers+0x7fd/0xb90 [ 107.126177] ? del_timer_sync+0x240/0x240 [ 107.130294] ? print_irqtrace_events+0x270/0x270 [ 107.135020] ? timerqueue_add+0x1e9/0x280 [ 107.139145] ? enqueue_hrtimer+0x162/0x490 [ 107.143350] ? __remove_hrtimer+0x190/0x190 [ 107.147646] ? check_noncircular+0x20/0x20 [ 107.151849] ? find_held_lock+0x35/0x1d0 [ 107.155900] ? clockevents_program_event+0x163/0x2e0 [ 107.160981] ? lock_downgrade+0x990/0x990 [ 107.165110] ? __lock_is_held+0xb6/0x140 [ 107.169149] run_timer_softirq+0x4c/0xb0 [ 107.173192] __do_softirq+0x2d7/0xb85 [ 107.176967] ? lock_downgrade+0x990/0x990 [ 107.181085] ? __irqentry_text_end+0x4/0x4 [ 107.185287] ? do_raw_spin_trylock+0x190/0x190 executing program [ 107.189837] ? do_timer+0x50/0x50 [ 107.193263] ? native_apic_msr_write+0x5c/0x80 [ 107.197814] ? lapic_next_event+0x54/0x80 [ 107.201936] ? clockevents_program_event+0x108/0x2e0 [ 107.207011] ? tick_program_event+0x7f/0xd0 [ 107.211307] ? __lock_is_held+0xb6/0x140 [ 107.215357] irq_exit+0x1cc/0x200 [ 107.218789] smp_apic_timer_interrupt+0x16b/0x700 [ 107.223597] ? smp_reschedule_interrupt+0xe6/0x670 [ 107.228495] ? smp_call_function_single_interrupt+0x640/0x640 [ 107.234348] ? _raw_spin_lock+0x32/0x40 [ 107.238299] ? handle_edge_irq+0x2b4/0x7c0 [ 107.242514] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 107.247337] apic_timer_interrupt+0x9d/0xb0 [ 107.251624] [ 107.253831] RIP: 0010:lock_acquire+0x256/0x580 [ 107.258377] RSP: 0018:ffff8801d986f458 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff11 [ 107.266053] RAX: dffffc0000000000 RBX: ffff8801d985c580 RCX: 0000000000000000 [ 107.273291] RDX: 1ffffffff0b58f41 RSI: 0000000000000000 RDI: 0000000000000282 [ 107.280617] RBP: ffff8801d986f550 R08: 0000000000000000 R09: ffff8801d986ec10 [ 107.287857] R10: ffff8801d986f438 R11: 1ffff1003b30bb0b R12: 1ffff1003b30de91 [ 107.295096] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000001 [ 107.302364] ? lock_release+0xa40/0xa40 [ 107.306315] ? finish_task_switch+0x1d3/0x740 [ 107.310784] ? lock_downgrade+0x990/0x990 [ 107.314903] ? load_balance+0x33b0/0x33b0 [ 107.319024] ? _raw_spin_unlock_irq+0x27/0x70 [ 107.323492] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 107.328481] process_one_work+0xb64/0x1bc0 [ 107.332683] ? process_one_work+0xadf/0x1bc0 [ 107.337058] ? _raw_spin_unlock_irq+0x27/0x70 [ 107.341534] ? pwq_dec_nr_in_flight+0x450/0x450 [ 107.346177] ? preempt_notifier_dec+0x20/0x20 [ 107.350651] ? __schedule+0x8f3/0x2060 [ 107.354528] ? check_noncircular+0x20/0x20 [ 107.358748] ? wake_q_add+0x90/0x90 [ 107.362351] ? lock_acquire+0x1d5/0x580 [ 107.366294] ? lock_acquire+0x1d5/0x580 [ 107.370238] ? worker_thread+0x4a3/0x1990 [ 107.374369] ? lock_downgrade+0x990/0x990 [ 107.378487] ? lock_release+0xa40/0xa40 [ 107.382434] ? do_raw_spin_trylock+0x190/0x190 [ 107.386985] ? retint_kernel+0x10/0x10 [ 107.390857] worker_thread+0x223/0x1990 [ 107.394818] ? process_one_work+0x1bc0/0x1bc0 [ 107.399284] ? _raw_spin_unlock_irq+0x27/0x70 [ 107.403751] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 107.408738] ? trace_hardirqs_on+0xd/0x10 [ 107.412858] ? mmdrop+0x18/0x30 [ 107.416107] ? finish_task_switch+0x1f6/0x740 [ 107.420575] ? preempt_notifier_dec+0x20/0x20 [ 107.425048] ? __schedule+0x8f3/0x2060 [ 107.428926] ? find_held_lock+0x35/0x1d0 [ 107.432966] ? find_held_lock+0x35/0x1d0 [ 107.437003] ? complete+0x62/0x80 [ 107.440432] ? __schedule+0x2060/0x2060 [ 107.444371] ? do_wait_intr+0x350/0x3e0 [ 107.448317] ? __lockdep_init_map+0xe4/0x650 [ 107.452699] ? lockdep_init_map+0x9/0x10 [ 107.456729] ? _raw_spin_unlock_irqrestore+0x31/0xba [ 107.461801] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 107.466785] ? trace_hardirqs_on+0xd/0x10 [ 107.470904] ? __kthread_parkme+0x175/0x240 [ 107.475200] kthread+0x38b/0x470 [ 107.478535] ? process_one_work+0x1bc0/0x1bc0 [ 107.483003] ? kthread_create_on_node+0x100/0x100 [ 107.487818] ret_from_fork+0x2a/0x40 [ 107.491620] [ 107.493214] ================================ [ 107.497584] WARNING: inconsistent lock state [ 107.501959] 4.14.0-rc5-next-20171017+ #35 Tainted: G W [ 107.508429] -------------------------------- [ 107.512803] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 107.518911] kworker/u4:1/21 [HC0[0]:SC1[3]:HE1:SE0] takes: [ 107.524495] (sk_lock-AF_INET){+.?.}, at: [] strp_sock_lock+0x2e/0x40 [ 107.532619] {SOFTIRQ-ON-W} state was registered at: [ 107.537603] trace_hardirqs_on_caller+0x421/0x5c0 [ 107.542500] trace_hardirqs_on+0xd/0x10 [ 107.546528] __local_bh_enable_ip+0x9d/0x160 [ 107.550989] lock_sock_nested+0xd9/0x110 [ 107.555102] sock_setsockopt+0x163/0x1ad0 [ 107.559304] SyS_setsockopt+0x2ff/0x360 [ 107.563331] entry_SYSCALL_64_fastpath+0x1f/0xbe [ 107.568137] irq event stamp: 27702 [ 107.571641] hardirqs last enabled at (27702): [] restore_regs_and_iret+0x0/0x1d [ 107.580703] hardirqs last disabled at (27701): [] apic_timer_interrupt+0x98/0xb0 [ 107.589764] softirqs last enabled at (27586): [] release_sock+0x1d4/0x2a0 [ 107.598304] softirqs last disabled at (27595): [] irq_exit+0x1cc/0x200 [ 107.606492] [ 107.606492] other info that might help us debug this: [ 107.613121] Possible unsafe locking scenario: [ 107.613121] [ 107.619140] CPU0 [ 107.621687] ---- [ 107.624235] lock(sk_lock-AF_INET); [ 107.627912] [ 107.630631] lock(sk_lock-AF_INET); [ 107.634484] [ 107.634484] *** DEADLOCK *** [ 107.634484] [ 107.640509] 2 locks held by kworker/u4:1/21: [ 107.644878] #0: ("%s""kkcmd"){+.+.}, at: [] process_one_work+0xadf/0x1bc0 [ 107.653511] #1: (((&strp->msg_timer))){+.-.}, at: [] call_timer_fn+0x1cd/0x830 [ 107.662581] [ 107.662581] stack backtrace: [ 107.667043] CPU: 0 PID: 21 Comm: kworker/u4:1 Tainted: G W 4.14.0-rc5-next-20171017+ #35 [ 107.676448] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 107.685779] Workqueue: kkcmd kcm_tx_work [ 107.689803] Call Trace: [ 107.692352] [ 107.694483] dump_stack+0x194/0x257 [ 107.698077] ? arch_local_irq_restore+0x53/0x53 [ 107.702716] print_usage_bug+0x40e/0x43e [ 107.706746] ? save_stack_trace+0x1a/0x20 [ 107.710858] ? print_irq_inversion_bug.part.43+0x2da/0x2da [ 107.716447] ? hlock_class+0x140/0x140 [ 107.720299] ? unwind_next_frame.part.6+0x1ae/0xc70 [ 107.725287] ? unwind_dump+0x4d0/0x4d0 [ 107.729143] mark_lock+0xe59/0x13d0 [ 107.732736] ? check_usage_backwards+0x420/0x420 [ 107.737458] ? print_irqtrace_events+0x270/0x270 [ 107.742179] ? check_usage_forwards+0x430/0x430 [ 107.746813] ? __unwind_start+0x169/0x330 [ 107.750925] ? unwind_next_frame+0x3e/0x50 [ 107.755126] ? __save_stack_trace+0x6e/0xd0 [ 107.759412] ? add_lock_to_list.isra.32+0x292/0x39b [ 107.764393] ? print_lockdep_cache.isra.35+0xe6/0xe6 [ 107.769462] ? save_stack_trace+0x1a/0x20 [ 107.773574] ? save_trace+0x11f/0x350 [ 107.777338] ? graph_lock+0x170/0x170 [ 107.781108] ? hlock_class+0x140/0x140 [ 107.784960] __lock_acquire+0x1770/0x3d50 [ 107.789075] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 107.794231] ? check_noncircular+0x20/0x20 [ 107.798428] ? do_raw_spin_trylock+0x190/0x190 [ 107.802974] ? do_timer+0x50/0x50 [ 107.806391] ? print_irqtrace_events+0x270/0x270 [ 107.811118] ? rcu_idle_enter+0x380/0x380 [ 107.815235] ? tick_program_event+0x7f/0xd0 [ 107.819522] ? __lock_is_held+0xb6/0x140 [ 107.823548] ? rcu_irq_exit+0x2a/0x90 [ 107.827317] ? irq_exit+0xbb/0x200 [ 107.830823] ? smp_apic_timer_interrupt+0x16b/0x700 [ 107.835803] ? smp_call_function_single_interrupt+0x124/0x640 [ 107.841652] ? smp_call_function_single_interrupt+0x640/0x640 [ 107.847501] ? lock_release+0xa40/0xa40 [ 107.851442] ? __free_insn_slot+0x5c0/0x5c0 [ 107.855729] ? mark_held_locks+0xaf/0x100 [ 107.859843] ? retint_kernel+0x10/0x10 [ 107.863697] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 107.868681] ? check_noncircular+0x20/0x20 [ 107.872884] ? retint_kernel+0x10/0x10 [ 107.876739] ? find_held_lock+0x35/0x1d0 [ 107.880768] lock_acquire+0x1d5/0x580 [ 107.884534] ? lock_acquire+0x1d5/0x580 [ 107.888472] ? strp_sock_lock+0x2e/0x40 [ 107.892411] ? lock_release+0xa40/0xa40 [ 107.896352] ? trace_event_raw_event_sched_switch+0x8a0/0x8a0 [ 107.902205] lock_sock_nested+0xc2/0x110 [ 107.906229] ? strp_sock_lock+0x2e/0x40 [ 107.910167] strp_sock_lock+0x2e/0x40 [ 107.913932] strp_msg_timeout+0x6c/0xf0 [ 107.917874] call_timer_fn+0x233/0x830 [ 107.921728] ? default_read_sock_done+0x20/0x20 [ 107.926362] ? __next_timer_interrupt+0x150/0x150 [ 107.931170] ? __run_timers+0x7ef/0xb90 [ 107.935110] ? lock_downgrade+0x990/0x990 [ 107.939225] ? do_raw_spin_trylock+0x190/0x190 [ 107.943773] ? mark_held_locks+0xaf/0x100 [ 107.947888] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 107.952869] ? default_read_sock_done+0x20/0x20 [ 107.957500] __run_timers+0x7fd/0xb90 [ 107.961275] ? del_timer_sync+0x240/0x240 [ 107.965390] ? print_irqtrace_events+0x270/0x270 [ 107.970114] ? timerqueue_add+0x1e9/0x280 [ 107.974228] ? enqueue_hrtimer+0x162/0x490 [ 107.978426] ? __remove_hrtimer+0x190/0x190 [ 107.982714] ? check_noncircular+0x20/0x20 [ 107.986912] ? find_held_lock+0x35/0x1d0 [ 107.990952] ? clockevents_program_event+0x163/0x2e0 [ 107.996021] ? lock_downgrade+0x990/0x990 [ 108.000136] ? __lock_is_held+0xb6/0x140 [ 108.004165] run_timer_softirq+0x4c/0xb0 [ 108.008192] __do_softirq+0x2d7/0xb85 [ 108.011967] ? lock_downgrade+0x990/0x990 [ 108.016082] ? __irqentry_text_end+0x4/0x4 [ 108.020280] ? do_raw_spin_trylock+0x190/0x190 [ 108.024827] ? do_timer+0x50/0x50 [ 108.028247] ? native_apic_msr_write+0x5c/0x80 [ 108.032796] ? lapic_next_event+0x54/0x80 [ 108.036910] ? clockevents_program_event+0x108/0x2e0 [ 108.041977] ? tick_program_event+0x7f/0xd0 [ 108.046266] ? __lock_is_held+0xb6/0x140 [ 108.050295] irq_exit+0x1cc/0x200 [ 108.053711] smp_apic_timer_interrupt+0x16b/0x700 [ 108.058517] ? smp_reschedule_interrupt+0xe6/0x670 [ 108.063410] ? smp_call_function_single_interrupt+0x640/0x640 [ 108.069260] ? _raw_spin_lock+0x32/0x40 [ 108.073203] ? handle_edge_irq+0x2b4/0x7c0 [ 108.077413] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 108.082221] apic_timer_interrupt+0x9d/0xb0 [ 108.086506] [ 108.088710] RIP: 0010:lock_acquire+0x256/0x580