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-mmots-kasan-gce-5,10.128.0.28' (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 syzkaller login: [ 67.079137] BUG: sleeping function called from invalid context at net/core/sock.c:2758 [ 67.080341] in_atomic(): 1, irqs_disabled(): 0, pid: 0, name: swapper/1 [ 67.081482] 1 lock held by swapper/1/0: [ 67.082041] #0: (((&strp->msg_timer))){+.-.}, at: [] call_timer_fn+0x1cd/0x830 [ 67.083284] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.14.0-rc5-mm1+ #18 [ 67.084197] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 67.084467] device lo entered promiscuous mode [ 67.086076] Call Trace: [ 67.086432] [ 67.086764] dump_stack+0x194/0x257 [ 67.087255] ? arch_local_irq_restore+0x53/0x53 [ 67.087882] ? print_lock+0x9f/0xa2 [ 67.088371] ? lockdep_print_held_locks+0xc4/0x130 [ 67.089049] ___might_sleep+0x2b2/0x470 [ 67.089584] ? trace_event_raw_event_sched_switch+0x8a0/0x8a0 [ 67.090363] ? __lock_acquire+0x6aa/0x3d50 [ 67.090938] __might_sleep+0x95/0x190 [ 67.091448] ? __lock_is_held+0xb6/0x140 [ 67.091999] lock_sock_nested+0x37/0x110 [ 67.092579] strp_sock_lock+0x2e/0x40 [ 67.093092] strp_msg_timeout+0x6c/0xf0 [ 67.093642] call_timer_fn+0x233/0x830 [ 67.094166] ? default_read_sock_done+0x20/0x20 [ 67.094790] ? __next_timer_interrupt+0x150/0x150 [ 67.095439] ? __run_timers+0x7ef/0xb90 [ 67.095972] ? lock_downgrade+0x990/0x990 [ 67.096535] ? do_raw_spin_trylock+0x190/0x190 [ 67.097171] ? __lock_is_held+0xb6/0x140 [ 67.097723] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 67.098392] ? default_read_sock_done+0x20/0x20 [ 67.099037] __run_timers+0x7fd/0xb90 [ 67.101556] ? del_timer_sync+0x240/0x240 [ 67.105672] ? check_noncircular+0x20/0x20 [ 67.109880] ? timerqueue_add+0x1e9/0x280 [ 67.114003] ? enqueue_hrtimer+0x162/0x490 [ 67.118209] ? __remove_hrtimer+0x190/0x190 [ 67.122508] ? find_held_lock+0x35/0x1d0 [ 67.126552] ? clockevents_program_event+0x163/0x2e0 [ 67.131622] ? lock_downgrade+0x990/0x990 [ 67.135748] ? rcu_pm_notify+0xc0/0xc0 [ 67.139615] run_timer_softirq+0x4c/0xb0 [ 67.143645] __do_softirq+0x2d7/0xb85 [ 67.147413] ? lock_downgrade+0x990/0x990 [ 67.151533] ? __irqentry_text_end+0x4/0x4 [ 67.155736] ? do_raw_spin_trylock+0x190/0x190 [ 67.160286] ? do_timer+0x50/0x50 [ 67.163712] ? native_apic_msr_write+0x5c/0x80 [ 67.168259] ? kvm_clock_get_cycles+0x25/0x30 [ 67.172720] ? lapic_next_event+0x54/0x80 [ 67.176837] ? clockevents_program_event+0x108/0x2e0 [ 67.181913] ? tick_program_event+0x7f/0xd0 [ 67.186203] ? hrtimer_interrupt+0x46e/0x5e0 [ 67.190581] ? rcu_pm_notify+0xc0/0xc0 [ 67.194445] irq_exit+0x1cc/0x200 [ 67.197869] smp_apic_timer_interrupt+0x16b/0x700 [ 67.202677] ? smp_reschedule_interrupt+0xe6/0x670 [ 67.207573] ? smp_call_function_single_interrupt+0x640/0x640 [ 67.213424] ? _raw_spin_lock+0x32/0x40 [ 67.217371] ? handle_edge_irq+0x2b4/0x7c0 [ 67.221585] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 67.226396] ? tick_nohz_idle_enter+0xd9/0x160 [ 67.230949] apic_timer_interrupt+0x9d/0xb0 [ 67.235235] [ 67.237442] RIP: 0010:native_safe_halt+0x6/0x10 [ 67.242076] RSP: 0018:ffff8801d9f67da8 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff11 [ 67.249750] RAX: dffffc0000000000 RBX: 1ffff1003b3ecfb8 RCX: 0000000000000000 [ 67.256988] RDX: 1ffffffff0b58f3c RSI: 0000000000000001 RDI: ffffffff85ac79e0 [ 67.264225] RBP: ffff8801d9f67da8 R08: ffffffff81628ac9 R09: 0000000000000000 [ 67.271460] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff85ac79d0 [ 67.278697] R13: ffff8801d9f67e60 R14: 0000000000000000 R15: ffffffff85ac79c0 [ 67.285944] ? tick_nohz_idle_enter+0xd9/0x160 [ 67.290500] ? trace_hardirqs_on+0xd/0x10 [ 67.294617] default_idle+0xbf/0x430 [ 67.298298] ? __sched_text_end+0x4/0x4 [ 67.302240] ? rcu_eqs_special_set+0x1e0/0x1e0 [ 67.306793] ? set_cpu_sd_state_idle+0x1dc/0x2d0 [ 67.311519] ? paravirt_write_msr.constprop.1+0x60/0x60 [ 67.316857] arch_cpu_idle+0xa/0x10 [ 67.320449] default_idle_call+0x36/0x90 [ 67.324476] do_idle+0x249/0x3a0 [ 67.327806] ? complete+0x62/0x80 [ 67.331229] cpu_startup_entry+0x18/0x20 [ 67.335258] start_secondary+0x2dd/0x3e0 [ 67.339288] secondary_startup_64+0xa5/0xb0 [ 67.343662] [ 67.345256] ================================ [ 67.349624] WARNING: inconsistent lock state [ 67.353997] 4.14.0-rc5-mm1+ #18 Tainted: G W [ 67.359586] -------------------------------- [ 67.363955] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 67.370063] swapper/1/0 [HC0[0]:SC1[3]:HE1:SE0] takes: [ 67.375300] (sk_lock-AF_INET){+.?.}, at: [] strp_sock_lock+0x2e/0x40 [ 67.383410] {SOFTIRQ-ON-W} state was registered at: [ 67.388389] trace_hardirqs_on_caller+0x421/0x5c0 [ 67.393282] trace_hardirqs_on+0xd/0x10 [ 67.397307] __local_bh_enable_ip+0x9d/0x160 [ 67.401772] lock_sock_nested+0xd9/0x110 [ 67.405883] sock_setsockopt+0x163/0x1ad0 [ 67.410078] SyS_setsockopt+0x2ff/0x360 [ 67.414101] entry_SYSCALL_64_fastpath+0x1f/0xbe [ 67.418903] irq event stamp: 159100 [ 67.422494] hardirqs last enabled at (159100): [] restore_regs_and_iret+0x0/0x1d [ 67.431640] hardirqs last disabled at (159099): [] apic_timer_interrupt+0x98/0xb0 [ 67.440789] softirqs last enabled at (159022): [] _local_bh_enable+0x1c/0x30 [ 67.449588] softirqs last disabled at (159023): [] irq_exit+0x1cc/0x200 [ 67.457861] [ 67.457861] other info that might help us debug this: [ 67.464486] Possible unsafe locking scenario: [ 67.464486] [ 67.470503] CPU0 [ 67.473049] ---- [ 67.475596] lock(sk_lock-AF_INET); [ 67.479272] [ 67.481988] lock(sk_lock-AF_INET); [ 67.485836] [ 67.485836] *** DEADLOCK *** [ 67.485836] [ 67.491855] 1 lock held by swapper/1/0: [ 67.495788] #0: (((&strp->msg_timer))){+.-.}, at: [] call_timer_fn+0x1cd/0x830 [ 67.504852] [ 67.504852] stack backtrace: [ 67.509311] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 4.14.0-rc5-mm1+ #18 [ 67.517499] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 67.526818] Call Trace: [ 67.529368] [ 67.531498] dump_stack+0x194/0x257 [ 67.535095] ? arch_local_irq_restore+0x53/0x53 [ 67.539730] print_usage_bug+0x40e/0x43e [ 67.543755] ? save_stack_trace+0x1a/0x20 [ 67.547869] ? print_irq_inversion_bug.part.43+0x2da/0x2da [ 67.553455] ? hlock_class+0x140/0x140 [ 67.557306] ? unwind_next_frame.part.6+0x1ae/0xc70 [ 67.562283] ? unwind_dump+0x4d0/0x4d0 [ 67.566133] mark_lock+0xe59/0x13d0 [ 67.569725] ? check_usage_backwards+0x420/0x420 [ 67.574443] ? print_irqtrace_events+0x270/0x270 [ 67.579165] ? check_usage_forwards+0x430/0x430 [ 67.583797] ? __unwind_start+0x169/0x330 [ 67.587908] ? unwind_next_frame+0x3e/0x50 [ 67.592107] ? __save_stack_trace+0x6e/0xd0 [ 67.596390] ? add_lock_to_list.isra.32+0x292/0x39b [ 67.601380] ? print_lockdep_cache.isra.35+0xe6/0xe6 [ 67.606446] ? save_stack_trace+0x1a/0x20 [ 67.610557] ? save_trace+0x11f/0x350 [ 67.614320] ? graph_lock+0x170/0x170 [ 67.618082] ? hlock_class+0x140/0x140 [ 67.621935] __lock_acquire+0x1770/0x3d50 [ 67.626049] ? debug_check_no_locks_freed+0x3c0/0x3c0 [ 67.631201] ? check_noncircular+0x20/0x20 [ 67.635398] ? do_raw_spin_trylock+0x190/0x190 [ 67.639941] ? do_timer+0x50/0x50 [ 67.643358] ? print_irqtrace_events+0x270/0x270 [ 67.648077] ? rcu_idle_enter+0x380/0x380 [ 67.652727] ? tick_program_event+0x7f/0xd0 [ 67.657015] ? __lock_is_held+0xb6/0x140 [ 67.661042] ? rcu_irq_exit+0x2a/0x90 [ 67.664805] ? irq_exit+0xbb/0x200 [ 67.668307] ? smp_apic_timer_interrupt+0x16b/0x700 [ 67.673284] ? smp_call_function_single_interrupt+0x124/0x640 [ 67.679131] ? smp_call_function_single_interrupt+0x640/0x640 [ 67.684975] ? lock_release+0xa40/0xa40 [ 67.688911] ? __free_insn_slot+0x5c0/0x5c0 [ 67.693196] ? mark_held_locks+0xaf/0x100 [ 67.697308] ? retint_kernel+0x10/0x10 [ 67.701156] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 67.706143] ? check_noncircular+0x20/0x20 [ 67.710340] ? retint_kernel+0x10/0x10 [ 67.714191] ? find_held_lock+0x35/0x1d0 [ 67.718216] lock_acquire+0x1d5/0x580 [ 67.721977] ? lock_acquire+0x1d5/0x580 [ 67.725913] ? strp_sock_lock+0x2e/0x40 [ 67.729852] ? lock_release+0xa40/0xa40 [ 67.733790] ? trace_event_raw_event_sched_switch+0x8a0/0x8a0 [ 67.739634] ? __lock_acquire+0x6aa/0x3d50 [ 67.743839] lock_sock_nested+0xc2/0x110 [ 67.747862] ? strp_sock_lock+0x2e/0x40 [ 67.751800] strp_sock_lock+0x2e/0x40 [ 67.755563] strp_msg_timeout+0x6c/0xf0 [ 67.759505] call_timer_fn+0x233/0x830 [ 67.763355] ? default_read_sock_done+0x20/0x20 [ 67.767986] ? __next_timer_interrupt+0x150/0x150 [ 67.772790] ? __run_timers+0x7ef/0xb90 [ 67.776726] ? lock_downgrade+0x990/0x990 [ 67.780841] ? do_raw_spin_trylock+0x190/0x190 [ 67.785387] ? __lock_is_held+0xb6/0x140 [ 67.789409] ? trace_hardirqs_on_caller+0x19e/0x5c0 [ 67.794389] ? default_read_sock_done+0x20/0x20 [ 67.799019] __run_timers+0x7fd/0xb90 [ 67.802786] ? del_timer_sync+0x240/0x240 [ 67.806895] ? check_noncircular+0x20/0x20 [ 67.811093] ? timerqueue_add+0x1e9/0x280 [ 67.815204] ? enqueue_hrtimer+0x162/0x490 [ 67.819402] ? __remove_hrtimer+0x190/0x190 [ 67.823689] ? find_held_lock+0x35/0x1d0 [ 67.827715] ? clockevents_program_event+0x163/0x2e0 [ 67.832778] ? lock_downgrade+0x990/0x990 [ 67.836889] ? rcu_pm_notify+0xc0/0xc0 [ 67.840740] run_timer_softirq+0x4c/0xb0 [ 67.844764] __do_softirq+0x2d7/0xb85 [ 67.848535] ? lock_downgrade+0x990/0x990 [ 67.852649] ? __irqentry_text_end+0x4/0x4 [ 67.856846] ? do_raw_spin_trylock+0x190/0x190 [ 67.861390] ? do_timer+0x50/0x50 [ 67.864809] ? native_apic_msr_write+0x5c/0x80 [ 67.869363] ? kvm_clock_get_cycles+0x25/0x30 [ 67.873821] ? lapic_next_event+0x54/0x80 [ 67.877931] ? clockevents_program_event+0x108/0x2e0 [ 67.882999] ? tick_program_event+0x7f/0xd0 [ 67.887288] ? hrtimer_interrupt+0x46e/0x5e0 [ 67.891664] ? rcu_pm_notify+0xc0/0xc0 [ 67.895519] irq_exit+0x1cc/0x200 [ 67.898936] smp_apic_timer_interrupt+0x16b/0x700 [ 67.903743] ? smp_reschedule_interrupt+0xe6/0x670 [ 67.908635] ? smp_call_function_single_interrupt+0x640/0x640 [ 67.914483] ? _raw_spin_lock+0x32/0x40 [ 67.918426] ? handle_edge_irq+0x2b4/0x7c0 [ 67.922628] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 67.927435] ? tick_nohz_idle_enter+0xd9/0x160 [ 67.931981] apic_timer_interrupt+0x9d/0xb0 [ 67.936262] [ 67.938464] RIP: 0010:native_safe_halt+0x6/0x10 [ 67.943092] RSP: 0018:ffff8801d9f67da8 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff11 [ 67.950762] RAX: dffffc0000000000 RBX: 1ffff1003b3ecfb8 RCX: 0000000000000000 [ 67.957998] RDX: 1ffffffff0b58f3c RSI: 0000000000000001 RDI: ffffffff85ac79e0 [ 67.965234] RBP: ffff8801d9f67da8 R08: ffffffff81628ac9 R09: 0000000000000000 [ 67.972466] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff85ac79d0 [ 67.979701] R13: ffff8801d9f67e60 R14: 0000000000000000 R15: ffffffff85ac79c0 [ 67.986938] ? tick_nohz_idle_enter+0xd9/0x160 [ 67.991495] ? trace_hardirqs_on+0xd/0x10 [ 67.995604] default_idle+0xbf/0x430 [ 67.999280] ? __sched_text_end+0x4/0x4 [ 68.003217] ? rcu_eqs_special_set+0x1e0/0x1e0 [ 68.007762] ? set_cpu_sd_state_idle+0x1dc/0x2d0 [ 68.012484] ? paravirt_write_msr.constprop.1+0x60/0x60 [ 68.017817] arch_cpu_idle+0xa/0x10 [ 68.021407] default_idle_call+0x36/0x90 [ 68.025432] do_idle+0x249/0x3a0 [ 68.028761] ? complete+0x62/0x80 [ 68.032176] cpu_startup_entry+0x18/0x20 [ 68.036201] start_secondary+0x2dd/0x3e0 [ 68.040227] secondary_startup_64+0xa5/0xb0