• Please make sure you are familiar with the forum rules. You can find them here: https://forums.tripwireinteractive.com/index.php?threads/forum-rules.2334636/

(SOLVED) - Dedicated server timeout

ss1gohan13

Member
Mar 29, 2015
19
1
Morning everyone,
I've got a weird issue i'm hoping the community can at least help pointme in the right direction I got some new network equipment and am trying to get my KF2 dedicated server working properly From what I can gather, the only issue is when I am in the game: I will join my dedicated server and at game end (whether its a win or loss) the game will hang. Only until I disable my rules I created to separate my VLAN network does the game then recognize game end. Is there a specific port or a special way dedicated servers will communicate to a client at game end?

Equipment: TP Link Omada TL-SG2428P v5.0
ACL: DMZ Network denied access to my network
ACL: my network allowed access to the DMZ network
 
Found a recent log of the connection drop. I can find a bit more info:
[50689.91] ScriptWarning: Accessed None 'Inv'
KFPawn_Human KF-PRISON.TheWorld:persistentLevel.KFPawn_Human_47
Function Engine.Pawn:TossInventory:00D4
[50689.91] ScriptLog: Script call stack:
Function Engine.PlayerController:ServerSuicide
Function Engine.Pawn:Suicide
Function Engine.Pawn:KilledBy
Function KFGame.KFPawn_Human:Died
Function KFGame.KFPawn:Died
Function BaseAI.BaseAIPawn:Died
Function Engine.Pawn:Died
Function kfgamecontent.KFGameInfo_Survival:Killed
Function kfgamecontent.KFGameInfo_Survival:CheckWaveEnd
Function kfgamecontent.KFGameInfo_Survival:WaveEnded

[50689.92] ScriptLog: KFGameInfo_Survival - MatchEnded.BeginState - AARDisplayDelay: 15.0000
[50689.92] DevOnline: Sending out playfab request to url Server/GetTitleData with data: {}
[50739.97] Log: PlayCustomAnim Death_V1, CustomChildIndex: 1, Animation Not Found!!
[50739.97] Log: PlayBodyAnim Anim:Death_V1 Pawn:KFPawn_Human_47, Animation Not Found!!
[50739.97] DevNet: TcpNetDriver_25 Connection timed out after 30.000000 seconds (50.054932)
[50739.97] DevNet: TcpNetDriver_25 Connection state = 3
[50739.97] NetComeGo: Close TcpNetDriver_25 TcpipConnection_126 192.168.40.50:58975
[50739.97] ScriptLog: StaticOnClientConnectionClose
[50739.97] DevOnline: EndRemoteClientAuthSession: ClientAddr: 192.168.40.50:58975, ClientUID: 76561197972431326
[50739.97] DevOnline: EndLocalServerAuthSession: ClientAddr: 192.168.40.50, ClientUID: 76561197972431326
[50739.97] DevOnline: EndLocalServerAuthSession: SessionUID: 48
[50739.97] ScriptLog: KFGameInfo.SeatPlayer
[50740.00] ScriptLog: KFGameInfo.SeatPlayer
[50740.16] ScriptLog: ProcessServerTravel: KF-Sanitarium
[50740.16] ScriptLog: SESSIONS - Clearing ConsoleGameSessionGuid for a new map KF-Sanitarium
 
Upvote 0
There's no specific port communication dedicated to the game end in this game.

We need more info. Full logs both on the client (KF2 game) and server (KF2 server) at the same time, and a video (because it's not clear what do you mean by "the game will hang"). Network config on the router showing which ports are you exposing to the internet and which ports do you map them on the machine on which KF2 server runs.
 
Upvote 0
There's no specific port communication dedicated to the game end in this game.

We need more info. Full logs both on the client (KF2 game) and server (KF2 server) at the same time, and a video (because it's not clear what do you mean by "the game will hang"). Network config on the router showing which ports are you exposing to the internet and which ports do you map them on the machine on which KF2 server runs.
Thank you for replying.

Please see this youtube link for a couple of instances:

Here is the time related log info regarding my session during my timeout on the client side:

[0512.25] Log: REFRESHING GAME CONTENT
[0512.25] ScriptLog: NotifyAllowSeasonalSkins: AllowSeasonalSkins: True
[0512.25] ScriptLog: Gameplay events will not be recorded.
[0512.25] Log: Bringing up level for play took: 0.151256
[0512.25] ScriptLog: KFGameInfo.SeatPlayer
[0512.25] ScriptLog: Warning - PATHS NOT DEFINED or NO PLAYERSTART with positive rating
[0512.25] ScriptLog: GetSeasonalStateName: 0 AllowSeasonalSkins: True
[0512.25] ScriptLog: GetSeasonalStateName: No Event
[0512.25] Log: Failed to load LightFX.dll.
[0512.38] Log: FVoiceInterfaceVivox::SetVolume(1.500000)
[0512.38] Log: FVoiceInterfaceVivox::SetMicVolume(50.000000) rescaled to 47
[0512.38] ScriptLog: Failed to check CanShareUserCreatedContent
[0512.38] ScriptLog: Failed to check CanCommunicateVoice
[0512.38] Log: FVoiceInterfaceVivox::StopLocalVoiceProcessing
[0512.38] Log: FVoiceInterfaceVivox::VivoxMuteLocalMic MUTE
[0512.39] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: root1
[0512.39] ScriptLog: (KFGFxMoviePlayer_Manager_2) KFGfxMoviePlayer_Manager::None:LaunchMenus 11 bShowIIS:'True'
[0512.39] ScriptLog: open menu: 0
[0512.39] ScriptLog: Controller Disconnected
[0512.58] ScriptWarning: Accessed None 'myGfxHUD'
KFPawn_Customization kfmainmenu.TheWorld:persistentLevel.KFPawn_Customization_2
Function KFGame.KFPawn_Human:UpdateActiveSkillsPath:0246
[0512.58] ScriptWarning: Accessed None
KFPawn_Customization kfmainmenu.TheWorld:persistentLevel.KFPawn_Customization_2
Function KFGame.KFPawn_Human:UpdateActiveSkillsPath:025A
[0512.58] ScriptWarning: Accessed None 'myGfxHUD'
KFPawn_Customization kfmainmenu.TheWorld:persistentLevel.KFPawn_Customization_2
Function KFGame.KFPawn_Human:UpdateActiveSkillsPath:0246
[0512.58] ScriptWarning: Accessed None
KFPawn_Customization kfmainmenu.TheWorld:persistentLevel.KFPawn_Customization_2
Function KFGame.KFPawn_Human:UpdateActiveSkillsPath:025A
[0512.58] ScriptWarning: Accessed None 'myGfxHUD'
KFPawn_Customization kfmainmenu.TheWorld:persistentLevel.KFPawn_Customization_2
Function KFGame.KFPawn_Human:UpdateActiveSkillsPath:0246
[0512.58] ScriptWarning: Accessed None
KFPawn_Customization kfmainmenu.TheWorld:persistentLevel.KFPawn_Customization_2
Function KFGame.KFPawn_Human:UpdateActiveSkillsPath:025A
[0512.58] Log: ########### Finished loading level: 1.568478 seconds
[0512.70] Log: OnReadComplete INT StatId=1 Value=72896158
[0512.70] Log: OnReadComplete INT StatId=2 Value=105
[0512.70] Log: OnReadComplete INT StatId=10 Value=67108864
[0512.70] Log: OnReadComplete INT StatId=11 Value=666
[0512.70] Log: OnReadComplete INT StatId=20 Value=72095813
[0512.70] Log: OnReadComplete INT StatId=21 Value=26
[0512.70] Log: OnReadComplete INT StatId=30 Value=72352677
[0512.70] Log: OnReadComplete INT StatId=31 Value=105
[0512.70] Log: OnReadComplete INT StatId=40 Value=71048940
[0512.70] Log: OnReadComplete INT StatId=41 Value=21
[0512.70] Log: OnReadComplete INT StatId=50 Value=67108872
[0512.70] Log: OnReadComplete INT StatId=51 Value=341
[0512.70] Log: OnReadComplete INT StatId=60 Value=69471104
[0512.70] Log: OnReadComplete INT StatId=61 Value=1
[0512.70] Log: OnReadComplete INT StatId=70 Value=5251563
[0512.70] Log: OnReadComplete INT StatId=71 Value=22
[0512.70] Log: OnReadComplete INT StatId=80 Value=67109614
[0512.70] Log: OnReadComplete INT StatId=81 Value=357
[0512.70] Log: OnReadComplete INT StatId=90 Value=69993262
[0512.70] Log: OnReadComplete INT StatId=91 Value=9
[0512.70] Log: OnReadComplete INT StatId=200 Value=163412
[0512.70] Log: OnReadComplete INT StatId=201 Value=2142
[0512.70] Log: OnReadComplete INT StatId=22 Value=504
[0512.70] Log: OnReadComplete INT StatId=42 Value=19
[0512.70] Log: OnReadComplete INT StatId=202 Value=5689
[0512.70] Log: OnReadComplete INT StatId=203 Value=193
[0512.70] Log: OnReadComplete INT StatId=300 Value=-941292540
[0512.70] Log: OnReadComplete INT StatId=301 Value=0
[0512.70] Log: OnReadComplete INT StatId=302 Value=9510
[0512.70] Log: OnReadComplete INT StatId=303 Value=130123798
[0512.70] Log: OnReadComplete INT StatId=304 Value=0
[0512.70] Log: OnReadComplete INT StatId=305 Value=0
[0512.70] Log: OnReadComplete INT StatId=306 Value=1097
[0512.70] Log: OnReadComplete INT StatId=307 Value=404
[0512.70] Log: OnReadComplete INT StatId=308 Value=0
[0512.70] Log: OnReadComplete INT StatId=309 Value=115207
[0512.70] Log: OnReadComplete INT StatId=310 Value=5
[0512.70] Log: OnReadComplete INT StatId=311 Value=0
[0512.70] Log: OnReadComplete INT StatId=2000 Value=11
[0512.70] Log: OnReadComplete INT StatId=2001 Value=199
[0512.70] Log: OnReadComplete INT StatId=2002 Value=538
[0512.70] Log: OnReadComplete INT StatId=2003 Value=3318
[0512.70] Log: OnReadComplete INT StatId=2004 Value=482
[0512.70] Log: OnReadComplete INT StatId=2005 Value=267
[0512.70] Log: OnReadComplete INT StatId=2006 Value=18
[0512.70] Log: OnReadComplete INT StatId=2007 Value=16428
[0512.70] Log: OnReadComplete INT StatId=3000 Value=105
[0512.70] Log: OnReadComplete INT StatId=400 Value=26382134
[0512.70] Log: OnReadComplete INT StatId=401 Value=25908406
[0512.70] Log: OnReadComplete INT StatId=402 Value=1
[0512.70] Log: OnReadComplete INT StatId=403 Value=0
[0512.70] Log: [FSocketWin::Bind] Binding to 216.239.35.12:123
[0512.73] Log: [UKFOnlineStatsWrite::VerifyDoshVaultCrates] Requesting 51 number of Vault Crates.
[0512.73] Log: [TWAnalytics::requestInventoryItem] Requesting Item Id: 5313 TotalNumberExpected: 51
[0512.73] Log: InAKFPerk::LoadPerkData() NewLevel = 20, NewBuild = 105, NewPrestigeLevel = 1
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: MenuBarWidget
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: StartMenu
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: ButtonPromptWidgetContainer
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: PartyWidget
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: BackendStatusIndicatorWidget
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: Notification
[0512.82] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: ChatBoxWidget
[0512.84] ScriptLog: label LatestUpdate
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_Event
[0512.84] ScriptLog: redirectURL http://www.tripwireinteractive.com/redirect/KF2LatestUpdate/
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2022_UltimateEdition_Upgrade
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/app/1914560/KF2__Ultimate_Edition_Upgrade_DLC/
[0512.84] ScriptLog: label ArmorySeasonPass
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2022_ArmorySeasonPassII
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/app/1914490/KF2__Season_Pass_2022
[0512.84] ScriptLog: label ArmorySeasonPass
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Spring_Armory_Season_Pass
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/app/1524820/Killing_Floor_2__Armory_Season_Pass
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_CosmeticsSeasonPass
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/app/2363410/Killing_Floor_2__Cosmetics_Season_Pass
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_S12Shockgun
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/9655
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_HorzineDiver_Uniforms
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/9653
[0512.84] ScriptLog: label FeaturedEventItem
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_SS_PremiumTicket
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/4928
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_Predator_Weapon_Skin
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/9651
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_JunkyardMKII_Weapon_Skin
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/9646
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_JaegerMKIV_Weapon_Skin
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/9645
[0512.84] ScriptLog: label FeaturedItemBundle
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Summer2023_Stingray_Weapon_Skin
[0512.84] ScriptLog: redirectURL https://store.steampowered.com/buyitem/232090/9649
[0512.84] ScriptLog: label Jaegorhorn
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_CommunityHub
[0512.84] ScriptLog: redirectURL https://steamcommunity.com/app/232090
[0512.84] ScriptLog: label Forums
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_CommunityForums
[0512.84] ScriptLog: redirectURL http://forums.tripwireinteractive.com/
[0512.84] ScriptLog: label Survey
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Survey
[0512.84] ScriptLog: redirectURL http://www.tripwireinteractive.com/redirect/KF2Survey/
[0512.84] ScriptLog: label CommunityTracker
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_CommunityIssue
[0512.84] ScriptLog: redirectURL http://www.killingfloor2.com/roadmap
[0512.84] ScriptLog: label Merch
[0512.84] ScriptLog: imageURL img://UI_WhatsNew.UI_WhatsNew_Merch
[0512.84] ScriptLog: redirectURL http://www.tripwireinteractive.com/redirect/shop/
[0512.84] ScriptLog: label Uncovered
[0512.84] ScriptLog: imageURL img://UI_WhatsNew_PS4.UI_WhatsNew_KFUncovered
[0512.84] ScriptLog: redirectURL
[0512.85] ScriptLog: WI: WorldInfo_3
[0512.85] ScriptLog: WI.NetMode: NM_Standalone
[0512.88] Warning: Warning, Failed to load 'Font None.Times New Roman': Failed to find object 'Font None.Times New Roman'
[0512.93] Log:
* evt_media_stream_updated: sip:[email protected] session_media_disconnecting 'Success' (0)

[0512.93] Log: [Vivox Debug] vivoxMediaState = session_media_disconnecting
[0512.93] Log: cropped = 110000100B9A1DE.
[0512.94] Log:
* evt_media_stream_updated: sip:[email protected] session_media_disconnected 'Success' (0)

[0512.94] Log: [Vivox Debug] vivoxMediaState = session_media_disconnected
[0512.96] Log:
* evt_session_removed session_handle=sip:[email protected]
[0512.96] Log: [Vivox Debug] vivoxSessionHandle.clear();
[0512.97] Log:
* evt_sessiongroup_removed session_handle=sg_sip:[email protected]
[0512.97] Log: [Vivox Debug] vivoxSessionGroupHandle.clear();
[0513.52] Log: GameThreadStopMovie - StreamAllResources time: 0.007757
[0513.53] Log: --- LOADING MOVIE TIME: 2.51 sec ---
[0513.56] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: ConnectionErrorPopup
[0516.34] ScriptLog: KFGfxObject_Popup::Callback_ClosedPopup invoke ClosePopUp()
[0517.20] Log: [FSocketWin::Bind] Binding to 216.239.35.12:123
[0517.23] Log: *** Updating timed game events
[0517.23] Log: **** Current Time: 2023 9 9 17 42 0
[0517.23] Log: **** World start in seconds: 1492491600
[0517.23] Log: **** Current time in seconds: 1694299320
[0517.23] Log: **** Intended weekly index: 333
[0517.23] Log: **** USED Weekly index: 333
[0521.09] Log: Assembled 961 auto-complete commands, manual: 92, exec: 732, kismet: 109
[0521.15] Log: [FSocketWin::Bind] Binding to 0.0.0.0:0
[0531.13] Log: FVoiceInterfaceVivox::SetLobbyUid 90176241406461977
[0535.16] NetComeGo: Close TcpNetDriver_1 TcpipConnection_1 10.0.200.2:7777 09/10/23 12:42:18
[0535.16] ScriptLog: KFGameInfo.SeatPlayer
[0535.17] Log: Pending connect to '10.0.200.2/KFMainMenu?Name=Player?Team=255' failed; Your connection to the host has been lost.
[0535.17] Error: Can't destroy a null online session
[0535.29] ScriptLog: KFGfxMoviePlayer_Manager - WidgetInitialized - WidgetName: ConnectionErrorPopup
[0537.00] ScriptLog: KFGfxObject_Popup::Callback_ClosedPopup invoke ClosePopUp()
[0539.24] Log: [FSocketWin::Bind] Binding to 0.0.0.0:0
[0539.97] Log: FVoiceInterfaceVivox::SetLobbyUid 90176241406461977
[0540.37] ScriptLog: Kicked off game server auth
[0541.00] Log: Welcomed by server (Level: KF-TheDescent, Game: kfgamecontent.KFGameInfo_Survival)
[0541.03] Log: LoadMap: 10.0.200.2/KF-TheDescent?Name=Player?Team=255?game=kfgamecontent.KFGameInfo_Survival
 
Upvote 0
Thanks for the video, this clarifies the situation. You have a disconnect problem, aka "zeds running into walls". For whatever reason you get disconnected from your server ungracefully and your client game (KF2 game) isn't informed about it as it should. Then your game waits, by default, AFAIR 180 seconds for the connection to timeout because it has no idea if it's terminated or not as there was no graceful termination, at which point you get sent to the main menu with the "disconnected" message.

Each log line in KF2 logs is preceded by the amount of [seconds.centiseconds]. You get sent to the main menu with the disconnect message at [0535.17] since the game start, therefore, if the timeout was 180 seconds, the event that caused the disconnect should have been logged at around 355 seconds mark. You didn't share that portion of your log.

I suggest again that you supply both server log and client log and in full. You can upload them somewhere zipped (e.g. pastebin) and just refer by URL.

But overall, if the disconnect is linked to a specific game event, it hints that it's application related, not network related. If it was network related, you would expect disconnects happening at times not tied to the in-game events. I would speculate that there's something wrong with your server. Maybe the KF2 server crashes at the end of the game? Have you checked your server uptime? Have you tried wiping it completely and reinstalling? Verifying its integrity using the steam client?
 
Upvote 0
Thanks for the video, this clarifies the situation. You have a disconnect problem, aka "zeds running into walls". For whatever reason you get disconnected from your server ungracefully and your client game (KF2 game) isn't informed about it as it should. Then your game waits, by default, AFAIR 180 seconds for the connection to timeout because it has no idea if it's terminated or not as there was no graceful termination, at which point you get sent to the main menu with the "disconnected" message.

Each log line in KF2 logs is preceded by the amount of [seconds.centiseconds]. You get sent to the main menu with the disconnect message at [0535.17] since the game start, therefore, if the timeout was 180 seconds, the event that caused the disconnect should have been logged at around 355 seconds mark. You didn't share that portion of your log.

I suggest again that you supply both server log and client log and in full. You can upload them somewhere zipped (e.g. pastebin) and just refer by URL.

But overall, if the disconnect is linked to a specific game event, it hints that it's application related, not network related. If it was network related, you would expect disconnects happening at times not tied to the in-game events. I would speculate that there's something wrong with your server. Maybe the KF2 server crashes at the end of the game? Have you checked your server uptime? Have you tried wiping it completely and reinstalling? Verifying its integrity using the steam client?
Thank you for the input and reply back. To cut to the bottom of your comment, there is indeed a network culprit involved. The issue only came to be when I swapped network equipment and began setting up ACLs. This issue wasn't present previously when I was running PFSense and a cisco switch.

I can provide another video here in a little bit showing what happens when I disable my ACL rules.

EDIT: Here is an example of what happens when I turn off ACLs in the middle of my match:
 
Last edited:
Upvote 0
Can't exactly make sense of what's going on on the video. The screen of your router configuration page doesn't show the details, such as which ports were forwarded, etc. Could you please share it?

Also, IF it's a network issue (which I'm not convinced it is) it may be related to how NAT translation works for devices inside and outside of your internal network. Your PC, I assume, sits inside of your home network. Does the issue manifest itself when someone else connects to your server from the internet and wins/loses a match? Care to provide IP so I can test from the outside?
 
Upvote 0
Can't exactly make sense of what's going on on the video. The screen of your router configuration page doesn't show the details, such as which ports were forwarded, etc. Could you please share it?

Also, IF it's a network issue (which I'm not convinced it is) it may be related to how NAT translation works for devices inside and outside of your internal network. Your PC, I assume, sits inside of your home network. Does the issue manifest itself when someone else connects to your server from the internet and wins/loses a match? Care to provide IP so I can test from the outside?
Evening,

I wanted to do a follow up regarding this issue. And yes, it was regarding a DNS query the server does at the end of game. What I believe to be a query to tripwire, to indicate XP gains, requires DNS access to query back to the tripwire servers. Being that ALL of my VLAN traffic was being blocked from accessing any other local infrastructure, the server would basically time out and give an loss of communication/connection error. This was not only happening to me, but to other outside players as well.

TL;DR:
I enabled DNS query on my DMZ network, and now my game server is working properly.
 
Upvote 0