INIT: Id "3" respawning too fast: disabled for 5 minutes INIT: Id "4" respawning too fast: disabled for 5 minutes INIT: Id "1" respawning too fast: disabled for 5 minutes INIT: Id "5" respawning too fast: disabled for 5 minutes INIT: Id "2" respawning too fast: disabled for 5 minutes [ 605.621142] random: crng init done Warning: Permanently added '10.128.10.6' (ECDSA) to the list of known hosts. 2018/04/18 13:05:49 parsed 1 programs 2018/04/18 13:05:49 executed programs: 0 [ 771.997334] IPVS: Creating netns size=2536 id=1 [ 772.027855] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.027948] IPVS: stopping backup sync thread 3914 ... [ 772.036685] IPVS: Creating netns size=2536 id=2 [ 772.048632] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.050816] IPVS: stopping backup sync thread 3923 ... [ 772.051292] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.065882] IPVS: stopping backup sync thread 3925 ... [ 772.067189] IPVS: stopping backup sync thread 3920 ... [ 772.067540] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.076297] IPVS: stopping backup sync thread 3934 ... [ 772.076737] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.080404] IPVS: Creating netns size=2536 id=3 [ 772.090628] IPVS: stopping backup sync thread 3939 ... [ 772.093809] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.095311] IPVS: stopping backup sync thread 3949 ... [ 772.095857] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.106083] IPVS: stopping backup sync thread 3947 ... [ 772.110931] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.112912] IPVS: stopping backup sync thread 3951 ... [ 772.113616] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.116136] IPVS: Creating netns size=2536 id=4 [ 772.132097] IPVS: stopping backup sync thread 3959 ... [ 772.133133] IPVS: stopping backup sync thread 3962 ... [ 772.133679] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.133725] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.141451] IPVS: Creating netns size=2536 id=5 [ 772.167877] IPVS: stopping backup sync thread 3974 ... [ 772.173021] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.173365] IPVS: stopping backup sync thread 3994 ... [ 772.173716] IPVS: stopping backup sync thread 3995 ... [ 772.180916] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.180938] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.188892] IPVS: Creating netns size=2536 id=6 [ 772.209050] IPVS: stopping backup sync thread 4000 ... [ 772.214331] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.219654] IPVS: stopping backup sync thread 3993 ... [ 772.220572] IPVS: stopping backup sync thread 3975 ... [ 772.225593] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.228645] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.228778] IPVS: stopping backup sync thread 4023 ... [ 772.229197] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.233989] IPVS: Creating netns size=2536 id=7 [ 772.256197] IPVS: stopping backup sync thread 4025 ... [ 772.256648] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.257376] IPVS: stopping backup sync thread 3997 ... [ 772.259991] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.286707] IPVS: Creating netns size=2536 id=8 [ 772.294374] IPVS: stopping backup sync thread 4047 ... [ 772.294842] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.310651] IPVS: stopping backup sync thread 4057 ... [ 772.311643] IPVS: stopping backup sync thread 4049 ... [ 772.312113] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.312281] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.326292] IPVS: stopping backup sync thread 4013 ... [ 772.327310] IPVS: stopping backup sync thread 4062 ... [ 772.327798] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.327969] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.487729] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.498878] IPVS: stopping backup sync thread 4070 ... [ 772.505050] IPVS: stopping backup sync thread 4021 ... [ 772.512561] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.512817] IPVS: stopping backup sync thread 4040 ... [ 772.512933] IPVS: stopping backup sync thread 4038 ... [ 772.512965] IPVS: stopping backup sync thread 4061 ... [ 772.513196] IPVS: stopping backup sync thread 4075 ... [ 772.513440] IPVS: stopping backup sync thread 4071 ... [ 772.514804] IPVS: stopping backup sync thread 4020 ... [ 772.520140] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.520299] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.529376] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.535360] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 772.544730] [ 772.544732] ============================================= [ 772.544733] [ INFO: possible recursive locking detected ] [ 772.544741] 4.9.94-g8683408 #3 Not tainted [ 772.544742] --------------------------------------------- [ 772.544744] syz-executor4/4099 is trying to acquire lock: [ 772.544861] (rtnl_mutex){+.+.+.}, at: [] rtnl_lock+0x17/0x20 [ 772.544862] but task is already holding lock: [ 772.544869] (rtnl_mutex){+.+.+.}, at: [] rtnl_lock+0x17/0x20 [ 772.544870] other info that might help us debug this: [ 772.544871] Possible unsafe locking scenario: [ 772.544871] [ 772.544871] CPU0 [ 772.544872] ---- [ 772.544875] lock(rtnl_mutex); [ 772.544877] lock(rtnl_mutex); [ 772.544878] [ 772.544878] *** DEADLOCK *** [ 772.544878] [ 772.544879] May be due to missing lock nesting notation [ 772.544879] [ 772.544881] 2 locks held by syz-executor4/4099: [ 772.544888] #0: (rtnl_mutex){+.+.+.}, at: [] rtnl_lock+0x17/0x20 [ 772.544926] #1: (ipvs->sync_mutex){+.+.+.}, at: [] do_ip_vs_set_ctl+0x90d/0xbd0 [ 772.544927] [ 772.544927] stack backtrace: [ 772.544931] CPU: 1 PID: 4099 Comm: syz-executor4 Not tainted 4.9.94-g8683408 #3 [ 772.544934] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 772.544953] ffff8801d6d4f470 ffffffff81d9b509 ffffffff85373290 ffffffff85373290 [ 772.544958] ffffffff85373290 ffff8801d4a120e8 b07e995446be6d18 ffff8801d6d4f630 [ 772.544963] ffffffff814258ef ffffffff84c6eca0 ffffffff84fc7af8 ffffffff84c6eca4 [ 772.544964] Call Trace: [ 772.545003] [] dump_stack+0xc1/0x128 [ 772.545024] [] __lock_acquire.cold.59+0x166/0x5f8 [ 772.545061] [] ? debug_check_no_locks_freed+0x210/0x210 [ 772.545066] [] ? __lock_is_held+0xa2/0xf0 [ 772.545070] [] lock_acquire+0x130/0x3e0 [ 772.545074] [] ? rtnl_lock+0x17/0x20 [ 772.545078] [] ? rtnl_lock+0x17/0x20 [ 772.545110] [] mutex_lock_nested+0xc0/0x870 [ 772.545114] [] ? rtnl_lock+0x17/0x20 [ 772.545118] [] ? trace_hardirqs_on_caller+0x38b/0x590 [ 772.545122] [] ? qtaguid_untag+0x41a/0x620 [ 772.545127] [] ? mutex_trylock+0x3e0/0x3e0 [ 772.545131] [] ? _raw_spin_unlock_bh+0x30/0x40 [ 772.545135] [] ? qtaguid_untag+0x31e/0x620 [ 772.545138] [] ? __lock_is_held+0xa2/0xf0 [ 772.545142] [] rtnl_lock+0x17/0x20 [ 772.545175] [] ip_mc_drop_socket+0x8c/0x230 [ 772.545180] [] inet_release+0x5b/0x1d0 [ 772.545208] [] sock_release+0x96/0x1c0 [ 772.545212] [] start_sync_thread+0xa0e/0x1ea0 [ 772.545226] [] ? ip_vs_proc_sync_conn+0x952/0x952 [ 772.545231] [] ? ip_vs_sync_conn+0x2a90/0x2a90 [ 772.545235] [] ? mark_held_locks+0xc7/0x130 [ 772.545239] [] ? mutex_lock_nested+0x572/0x870 [ 772.545243] [] ? trace_hardirqs_on_caller+0x38b/0x590 [ 772.545248] [] ? mutex_lock_nested+0x596/0x870 [ 772.545251] [] ? do_ip_vs_set_ctl+0x90d/0xbd0 [ 772.545255] [] ? mutex_trylock+0x3e0/0x3e0 [ 772.545282] [] ? memcpy+0x45/0x50 [ 772.545286] [] do_ip_vs_set_ctl+0x91f/0xbd0 [ 772.545290] [] ? ip_vs_genl_set_cmd+0x9c0/0x9c0 [ 772.545295] [] ? mutex_lock_nested+0x572/0x870 [ 772.545299] [] ? __mutex_unlock_slowpath+0x221/0x3c0 [ 772.545304] [] ? __ww_mutex_lock+0x14c0/0x14c0 [ 772.545326] [] ? sock_has_perm+0x1c2/0x3e0 [ 772.545330] [] ? mutex_unlock+0x9/0x10 [ 772.545349] [] ? nf_sockopt_find.constprop.0+0x1b1/0x230 [ 772.545354] [] compat_nf_setsockopt+0x10d/0x130 [ 772.545362] [] compat_ip_setsockopt+0xa7/0xe0 [ 772.545367] [] inet_csk_compat_setsockopt+0x97/0x120 [ 772.545370] [] ? ip_setsockopt+0xb0/0xb0 [ 772.545375] [] compat_tcp_setsockopt+0x3d/0x70 [ 772.545382] [] compat_sock_common_setsockopt+0xb4/0x150 [ 772.545386] [] ? tcp_setsockopt+0xe0/0xe0 [ 772.545403] [] compat_SyS_setsockopt+0x14c/0x2a0 [ 772.545407] [] ? sock_common_setsockopt+0xe0/0xe0 [ 772.545411] [] ? scm_detach_fds_compat+0x3b0/0x3b0 [ 772.545425] [] ? do_fast_syscall_32+0xcf/0x870 [ 772.545430] [] ? scm_detach_fds_compat+0x3b0/0x3b0 [ 772.545433] [] do_fast_syscall_32+0x2f7/0x870 [ 772.545437] [] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 772.545442] [] entry_SYSENTER_compat+0x90/0xa2 [ 772.547179] IPVS: sync thread started: state = BACKUP, mcast_ifn = lo, syncid = 0, id = 0 [ 773.321683] IPVS: stopping backup sync thread 4094 ... [ 773.330552] IPVS: stopping backup sync thread 4105 ... [ 773.331426] IPVS: stopping backup sync thread 4103 ... [ 773.343312] IPVS: stopping backup sync thread 4106 ... [ 773.348897] IPVS: stopping backup sync thread 4115 ... [ 773.349773] IPVS: stopping backup sync thread 4112 ...