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 '10.128.10.1' (ECDSA) to the list of known hosts. syzkaller login: [ 40.497933] IPVS: ftp: loaded support on port[0] = 21 [ 40.499293] IPVS: ftp: loaded support on port[0] = 21 [ 40.506401] IPVS: ftp: loaded support on port[0] = 21 [ 40.511857] IPVS: ftp: loaded support on port[0] = 21 [ 40.517357] IPVS: ftp: loaded support on port[0] = 21 [ 40.519490] IPVS: ftp: loaded support on port[0] = 21 [ 40.526788] IPVS: ftp: loaded support on port[0] = 21 [ 40.532283] IPVS: ftp: loaded support on port[0] = 21 executing program executing program executing program executing program executing program executing program executing program executing program [ 40.642652] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.642779] IPVS: stopping backup sync thread 4524 ... [ 40.661055] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.676811] IPVS: stopping backup sync thread 4532 ... executing program executing program executing program [ 40.693634] IPVS: stopping backup sync thread 4540 ... [ 40.693745] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.702898] IPVS: stopping backup sync thread 4543 ... [ 40.707980] IPVS: stopping backup sync thread 4528 ... [ 40.715417] IPVS: stopping backup sync thread 4546 ... [ 40.722074] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.726559] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 executing program executing program executing program executing program executing program executing program [ 40.732366] IPVS: stopping backup sync thread 4549 ... [ 40.747442] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.747756] IPVS: stopping backup sync thread 4550 ... [ 40.762528] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.767396] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.771006] IPVS: stopping backup sync thread 4556 ... [ 40.781343] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 executing program executing program [ 40.795254] IPVS: stopping backup sync thread 4562 ... [ 40.795753] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.800828] IPVS: stopping backup sync thread 4564 ... [ 40.809705] IPVS: stopping backup sync thread 4571 ... [ 40.814607] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.822907] IPVS: stopping backup sync thread 4576 ... [ 40.828861] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 40.837463] [ 40.843800] ============================================ [ 40.849223] WARNING: possible recursive locking detected [ 40.854651] 4.17.0-rc1+ #10 Not tainted [ 40.858620] -------------------------------------------- [ 40.864052] syzkaller616458/4568 is trying to acquire lock: [ 40.869740] 00000000eefca8ba (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 [ 40.876573] [ 40.876573] but task is already holding lock: [ 40.882522] 00000000eefca8ba (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 [ 40.889352] [ 40.889352] other info that might help us debug this: [ 40.895996] Possible unsafe locking scenario: [ 40.895996] [ 40.902029] CPU0 [ 40.904584] ---- [ 40.907141] lock(rtnl_mutex); [ 40.910395] lock(rtnl_mutex); [ 40.913650] [ 40.913650] *** DEADLOCK *** [ 40.913650] [ 40.919683] May be due to missing lock nesting notation [ 40.919683] [ 40.926589] 2 locks held by syzkaller616458/4568: [ 40.931404] #0: 00000000eefca8ba (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 [ 40.938665] #1: 000000009c632cd2 (ipvs->sync_mutex){+.+.}, at: do_ip_vs_set_ctl+0x562/0x1d30 [ 40.948463] [ 40.948463] stack backtrace: [ 40.952944] CPU: 0 PID: 4568 Comm: syzkaller616458 Not tainted 4.17.0-rc1+ #10 [ 40.960280] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 40.969610] Call Trace: [ 40.972179] dump_stack+0x1b9/0x294 [ 40.975785] ? dump_stack_print_info.cold.2+0x52/0x52 [ 40.980956] ? print_lock+0xd1/0xd6 [ 40.984560] ? vprintk_func+0x81/0xe7 [ 40.988340] __lock_acquire.cold.62+0x18c/0x55b [ 40.993002] ? debug_check_no_locks_freed+0x310/0x310 [ 40.998173] ? _raw_spin_unlock_irq+0x27/0x70 [ 41.002646] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 41.007637] ? trace_hardirqs_on+0xd/0x10 [ 41.011762] ? __lock_acquire+0x7f5/0x5140 [ 41.015975] ? preempt_notifier_register+0x1e0/0x1e0 [ 41.021058] ? debug_check_no_locks_freed+0x310/0x310 [ 41.026226] ? __schedule+0x809/0x1e30 [ 41.030091] ? graph_lock+0x170/0x170 [ 41.033871] ? kasan_check_read+0x11/0x20 [ 41.037997] ? graph_lock+0x170/0x170 [ 41.041772] ? graph_lock+0x170/0x170 [ 41.045552] lock_acquire+0x1dc/0x520 [ 41.049331] ? rtnl_lock+0x17/0x20 [ 41.052851] ? lock_release+0xa10/0xa10 [ 41.056811] ? check_same_owner+0x320/0x320 [ 41.061119] ? schedule+0xef/0x430 [ 41.064638] ? rcu_note_context_switch+0x710/0x710 [ 41.069555] ? __schedule+0x1e30/0x1e30 [ 41.073509] ? __might_sleep+0x95/0x190 [ 41.077463] ? rtnl_lock+0x17/0x20 [ 41.080984] __mutex_lock+0x16d/0x17f0 [ 41.084852] ? rtnl_lock+0x17/0x20 [ 41.088373] ? rtnl_lock+0x17/0x20 [ 41.091911] ? mutex_trylock+0x2a0/0x2a0 [ 41.095963] ? mark_held_locks+0xc9/0x160 [ 41.100093] ? do_raw_spin_trylock+0x1b0/0x1b0 [ 41.104656] ? _raw_spin_unlock_irq+0x27/0x70 [ 41.109133] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 41.114129] ? trace_hardirqs_on+0xd/0x10 [ 41.118255] ? _raw_spin_unlock_irq+0x27/0x70 [ 41.122728] ? wait_for_completion_killable+0x56c/0x960 [ 41.128071] ? wait_for_completion_interruptible_timeout+0x960/0x960 [ 41.134540] ? lock_downgrade+0x8e0/0x8e0 [ 41.138666] ? kasan_check_read+0x11/0x20 [ 41.142790] ? do_raw_spin_unlock+0x9e/0x2e0 [ 41.147177] ? do_raw_spin_trylock+0x1b0/0x1b0 [ 41.151736] ? wake_up_q+0x100/0x100 [ 41.155429] ? kasan_check_write+0x14/0x20 [ 41.159644] ? do_raw_spin_lock+0xc1/0x200 [ 41.163865] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 41.169399] ? __kthread_create_on_node+0x34f/0x4c0 [ 41.174400] ? __kthread_parkme+0x280/0x280 [ 41.178704] ? kasan_check_read+0x11/0x20 [ 41.182836] ? do_raw_spin_unlock+0x9e/0x2e0 [ 41.187225] ? __lock_is_held+0xb5/0x140 [ 41.191267] mutex_lock_nested+0x16/0x20 [ 41.195315] ? mutex_lock_nested+0x16/0x20 [ 41.199533] rtnl_lock+0x17/0x20 [ 41.202894] ip_mc_drop_socket+0x8f/0x270 [ 41.207037] inet_release+0x4e/0x1f0 [ 41.210731] sock_release+0x96/0x1b0 [ 41.214424] start_sync_thread+0xdc3/0x2d40 [ 41.218722] ? ip_vs_process_message+0xa0a/0xa0a [ 41.223456] ? ip_vs_sync_conn+0x33a0/0x33a0 [ 41.227846] ? graph_lock+0x170/0x170 [ 41.231623] ? lock_downgrade+0x8e0/0x8e0 [ 41.235749] ? lock_downgrade+0x8e0/0x8e0 [ 41.239892] ? kasan_check_read+0x11/0x20 [ 41.244023] ? do_raw_spin_unlock+0x9e/0x2e0 [ 41.248410] ? __local_bh_enable_ip+0x161/0x230 [ 41.253057] ? find_held_lock+0x36/0x1c0 [ 41.257095] ? lock_downgrade+0x8e0/0x8e0 [ 41.261235] ? lock_release+0xa10/0xa10 [ 41.265189] ? check_same_owner+0x320/0x320 [ 41.269495] ? module_unload_free+0x5b0/0x5b0 [ 41.273976] do_ip_vs_set_ctl+0x59c/0x1d30 [ 41.278192] ? find_held_lock+0x36/0x1c0 [ 41.282233] ? ip_vs_genl_set_cmd+0xe80/0xe80 [ 41.286706] ? lock_downgrade+0x8e0/0x8e0 [ 41.290837] ? kasan_check_read+0x11/0x20 [ 41.294979] ? rcu_is_watching+0x85/0x140 [ 41.299116] ? rcu_bh_force_quiescent_state+0x20/0x20 [ 41.304288] ? kasan_check_write+0x14/0x20 [ 41.308503] ? __mutex_unlock_slowpath+0x180/0x8a0 [ 41.313422] ? wait_for_completion+0x870/0x870 [ 41.318000] ? module_unload_free+0x5b0/0x5b0 [ 41.322480] ? mutex_unlock+0xd/0x10 [ 41.326176] nf_setsockopt+0x7d/0xd0 [ 41.329867] ? nf_setsockopt+0x7d/0xd0 [ 41.333731] ip_setsockopt+0xd8/0xf0 [ 41.337425] tcp_setsockopt+0x93/0xe0 [ 41.341208] sock_common_setsockopt+0x9a/0xe0 [ 41.345684] __sys_setsockopt+0x1bd/0x390 [ 41.349815] ? kernel_accept+0x310/0x310 [ 41.353876] ? mm_fault_error+0x380/0x380 [ 41.358009] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 41.363526] ? syscall_slow_exit_work+0x4f0/0x4f0 [ 41.368360] __x64_sys_setsockopt+0xbe/0x150 [ 41.372755] ? trace_hardirqs_on_caller+0x421/0x5c0 [ 41.377758] do_syscall_64+0x1b1/0x800 [ 41.381630] ? finish_task_switch+0x1ca/0x810 [ 41.386109] ? syscall_return_slowpath+0x5c0/0x5c0 [ 41.391020] ? syscall_return_slowpath+0x30f/0x5c0 [ 41.395932] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20 [ 41.401445] ? retint_user+0x18/0x18 [ 41.405151] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 41.409977] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 41.415147] RIP: 0033:0x446a29 [ 41.418313] RSP: 002b:00007f8243a68db8 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 41.426001] RAX: ffffffffffffffda RBX: 00000000006e29e4 RCX: 0000000000446a29 [ 41.433248] RDX: 000000000000048b RSI: 0000000000000000 RDI: 0000000000000003 [ 41.440496] RBP: 00000000006e29e0 R08: 0000000000000018 R09: 0000000000000000 executing program [ 41.447745] R10: 0000000020000040 R11: 0000000000000246 R12: 0000000000000000 [ 41.455004] R13: 00007ffe7398449f R14: 00007f8243a699c0 R15: 00000000006e2b60 [ 41.465258] IPVS: stopping backup sync thread 4559 ...