Hello,
I am trying to setup a very basic HOST and JOIN ui using AdvancedSessions (although I don’t think that is relevant).
What works:
Computer A (steam user A) can host a LAN (listen) game, and the desired map is opened on that computer //Aka OpenLevel node WITH the listen option
Computer B can find that LAN Session
Computer B can call Join Session on that game (and the success execution wire is fired)
Computer B claims it has joined the session
What doesn’t work:
Computer B does not travel to the correct map (it reloads the main menu map)
Computer B “times out” as if it had never really connected to the server
Here is some more info on how I do my server creation:
(seems like most people who have this issue forget to have “listen” in their OpenLevel options)
This is how I join sessions:
So to reiterate the “Joined Server” print string does occur, but my Computer B will sit on the main menu, meanwhile Computer A shows nothing in the logs about a possible connecting user.
[2018.07.13-19.36.14:784] 0]LogOnline: Verbose: STEAM: FOnlineAsyncEventSteamStatsReceived bWasSuccessful: 1 User: esherrthantest [0x110000134DE7453] Result: ‘1’ k_EResultOK (success)
[2018.07.13-19.36.14:784] 0]LogMetal: Display: No .metalmap file found for SF_METAL_SM5!
[2018.07.13-19.36.14:809] 0]LogMaterial: Verifying Global Shaders for SF_METAL_SM5
[2018.07.13-19.36.14:809] 0]LogSlate: Using Freetype 2.4.12
[2018.07.13-19.36.14:810] 0]LogSlate: SlateFontCache - WITH_FREETYPE: 1, WITH_HARFBUZZ: 1
[2018.07.13-19.36.14:810] 0]LogSlate: SlateFontCache - WITH_FREETYPE: 1, WITH_HARFBUZZ: 1
[2018.07.13-19.36.14:906] 0]LogOnline: Verbose: STEAM: FOnlineAsyncEventSteamRichPresenceUpdate got new information about user 76561198847259731
[2018.07.13-19.36.14:906] 0]LogAssetRegistry: FAssetRegistry took 0.0268 seconds to start up
[2018.07.13-19.36.15:033] 0]LogPackageLocalizationCache: Processed 9 localized package path(s) for 1 prioritized culture(s) in 0.004426 seconds
[2018.07.13-19.36.15:033] 0]LogPakFile: New pak file …/…/…/AlienBetrayal/Content/Paks/AlienBetrayal-MacNoEditor.pak added to pak precacher.
[2018.07.13-19.36.15:035] 0]LogInit: Selected Device Profile: [MacNoEditor]
[2018.07.13-19.36.15:110] 0]LogNetVersion: AlienBetrayal , NetCL: 3944462, EngineNetVer: 2, GameNetVer: 0 (Checksum: 172357803)
[2018.07.13-19.36.15:203] 0]LogMoviePlayer: FAVMoviePlayer ctor…
[2018.07.13-19.36.15:203] 0]LogMoviePlayer: Initializing movie player
[2018.07.13-19.36.15:818] 0]LogAndroidPermission: UAndroidPermissionCallbackProxy::GetInstance
[2018.07.13-19.36.15:825] 0]LogUObjectArray: 35423 objects as part of root set at end of initial load.
[2018.07.13-19.36.15:825] 0]LogUObjectAllocator: 6772608 out of 0 bytes used by permanent object pool.
[2018.07.13-19.36.15:825] 0]LogUObjectArray: CloseDisregardForGC: 0/0 objects in disregard for GC pool
[2018.07.13-19.36.15:825] 0]LogEngine: Initializing Engine…
[2018.07.13-19.36.15:864] 0]LogHMD: Failed to initialize OpenVR with code 126
[2018.07.13-19.36.15:869] 0]LogStats: UGameplayTagsManager::ConstructGameplayTagTree: Construct from data asset - 0.000 s
[2018.07.13-19.36.15:870] 0]LogStats: UGameplayTagsManager::ConstructGameplayTagTree: GameplayTagTreeChangedEvent.Broadcast - 0.000 s
[2018.07.13-19.36.16:019] 0]LogInit: FAudioDevice initialized.
[2018.07.13-19.36.16:021] 0]LogInit: Texture streaming: Enabled
[2018.07.13-19.36.16:035] 0]LogInit: Display: Game Engine Initialized.
[2018.07.13-19.36.16:035] 0]LogGameplayTags: Display: UGameplayTagsManager::DoneAddingNativeTags. DelegateIsBound: 0
[2018.07.13-19.36.16:036] 0]LogStats: UGameplayTagsManager::ConstructGameplayTagTree: Construct from data asset - 0.000 s
[2018.07.13-19.36.16:036] 0]LogStats: UGameplayTagsManager::ConstructGameplayTagTree: GameplayTagTreeChangedEvent.Broadcast - 0.000 s
[2018.07.13-19.36.16:059] 0]LogInit: Display: Starting Game.
[2018.07.13-19.36.16:059] 0]LogNet: Browse: /Game/Maps/MainMenu?Name=Player
[2018.07.13-19.36.16:060] 0]LogLoad: LoadMap: /Game/Maps/MainMenu?Name=Player
[2018.07.13-19.36.16:094] 0]LogAIModule: Creating AISystem for world MainMenu
[2018.07.13-19.36.16:094] 0]LogLoad: Game class is ‘GameMode’
[2018.07.13-19.36.16:095] 0]LogWorld: Bringing World /Game/Maps/MainMenu.MainMenu up for play (max tick rate 0) at 2018.07.13-14.36.16
[2018.07.13-19.36.16:095] 0]LogWorld: Bringing up level for play took: 0.000422
[2018.07.13-19.36.16:096] 0]LogGameMode: FindPlayerStart: PATHS NOT DEFINED or NO PLAYERSTART with positive rating
[2018.07.13-19.36.16:096] 0]LogVoice: Stopping networked voice for user: 0
[2018.07.13-19.36.16:096] 0]LogGameMode: Display: Match State Changed from EnteringMap to WaitingToStart
[2018.07.13-19.36.16:096] 0]LogGameState: Match State Changed from EnteringMap to WaitingToStart
[2018.07.13-19.36.16:097] 0]LogGameMode: Display: Match State Changed from WaitingToStart to InProgress
[2018.07.13-19.36.16:097] 0]LogUMG: Display: Widget Class MainMenu_BP_C - Loaded Fast Template.
[2018.07.13-19.36.16:100] 0]LogGameState: Match State Changed from WaitingToStart to InProgress
[2018.07.13-19.36.16:100] 0]LogLoad: Took 0.039795 seconds to LoadMap(/Game/Maps/MainMenu)
[2018.07.13-19.36.16:100] 0]LogSlate: Took 0.000150 seconds to synchronously load lazily loaded font ‘…/…/…/Engine/Content/EngineFonts/Faces/RobotoBold.ufont’ (160K)
[2018.07.13-19.36.16:101] 0]LogSlate: Took 0.000126 seconds to synchronously load lazily loaded font ‘…/…/…/Engine/Content/Slate/Fonts/Roboto-Regular.ttf’ (155K)
[2018.07.13-19.36.16:114] 0]LogLoad: (Engine Initialization) Total time: 1.83 seconds
[2018.07.13-19.36.16:150] 0]LogRenderer: Reallocating scene render targets to support 1440x900 Format 10 NumSamples 1 (Frame:1).
[2018.07.13-19.36.16:193] 0]LogInit: Adding external input plugin.
[2018.07.13-19.36.16:196] 0]LogContentStreaming: Texture pool size now 1000 MB
[2018.07.13-19.36.20:923][281]LogSlate: FSceneViewport::OnFocusLost() reason 0
[2018.07.13-19.36.22:841][396]LogSlate: FSceneViewport::OnFocusLost() reason 0
[2018.07.13-19.36.42:659][585]LogSlate: FSceneViewport::OnFocusLost() reason 0
[2018.07.13-19.36.43:570][630]LogInit: BSD: I am Ethans-MacBook-Pro-3.local (10.0.0.161:0)
[2018.07.13-19.36.43:570][630]LogOnline: Verbose: Sending 16 bytes to 255.255.255.255:14001
[2018.07.13-19.36.43:570][630]LogOnline: Verbose: Sent query packet…
[2018.07.13-19.36.43:571][631]LogOnline: Verbose: Received 16 bytes from 10.0.0.161:14001
[2018.07.13-19.36.43:636][636]LogOnline: Verbose: Received 118 bytes from 10.0.0.184:14001
[2018.07.13-19.36.48:422][929]LogScriptCore: Script Msg: Found a session. Ping is 9999
[2018.07.13-19.36.48:438][930]LogOnline: Verbose: STEAM: “gamedir” “unrealtest”
[2018.07.13-19.36.50:455] 55]LogOnline: Verbose: STEAM: Server query complete ‘2’ eNoServersListedOnMasterServer (No servers match)
[2018.07.13-19.36.50:455] 55]LogOnline: Verbose: Async task ‘FOnlineAsyncTaskSteamFindServers bWasSuccessful: 1 Results: 0’ succeeded in 2.024439 seconds
[2018.07.13-19.36.50:455] 55]LogBlueprintUserMessages: [MainMenu_BP_C_0] Servers Found: 1
[2018.07.13-19.36.50:455] 55]LogUMG: Display: Widget Class ServerRow_C - Loaded Fast Template.
[2018.07.13-19.36.50:471] 55]LogSlate: Took 0.000213 seconds to synchronously load lazily loaded font ‘…/…/…/Engine/Content/EngineFonts/Faces/RobotoRegular.ufont’ (155K)
[2018.07.13-19.37.00:428][670]LogVoice: Starting networked voice for user: 0
[2018.07.13-19.37.00:428][670]LogVoice: RegisterLocalTalker(0) returned 0x00000000
[2018.07.13-19.37.00:433][670]LogVoice: StartLocalProcessing(0) returned 0x00000000
[2018.07.13-19.37.00:434][670]LogOnline: Join session: traveling to 10.0.0.184:7777
[2018.07.13-19.37.00:434][670]LogBlueprintUserMessages: [ServerRow_C_0] Joined server
[2018.07.13-19.37.00:443][671]LogGameMode: Display: Match State Changed from InProgress to LeavingMap
[2018.07.13-19.37.00:443][671]LogGameState: Match State Changed from InProgress to LeavingMap
[2018.07.13-19.37.00:443][671]LogNet: Browse: 10.0.0.184//Game/Maps/MainMenu
[2018.07.13-19.37.00:444][671]LogTemp: Display: ParseSettings for GameNetDriver
[2018.07.13-19.37.00:444][671]LogTemp: Display: ParseSettings for SteamNetDriver_0
[2018.07.13-19.37.00:444][671]LogTemp: Display: ParseSettings for PendingNetDriver
[2018.07.13-19.37.00:444][671]LogInit: BSD: Socket queue 32768 / 32768
[2018.07.13-19.37.00:445][671]PacketHandlerLog: Loaded PacketHandler component: Engine.EngineHandlerComponentFactory (StatelessConnectHandlerComponent)
[2018.07.13-19.37.00:445][671]LogNet: Game client on port 7777, rate 10000
[2018.07.13-19.37.00:484][673]LogVoice: Trigger Local 0 TALKING
[2018.07.13-19.37.01:394][728]LogVoice: Trigger Local 0 NOTTALKING
[2018.07.13-19.37.10:644][282]LogVoice: Trigger Local 0 TALKING
[2018.07.13-19.37.11:944][360]LogVoiceEngine: Warning: Dropping voice data due to network layer not processing fast enough
[2018.07.13-19.37.14:212][493]LogVoice: Trigger Local 0 NOTTALKING
[2018.07.13-19.37.16:462][628]LogVoice: Trigger Local 0 TALKING
[2018.07.13-19.37.16:512][631]LogVoiceEngine: Warning: Dropping voice data due to network layer not processing fast enough
[2018.07.13-19.37.17:362][682]LogVoice: Trigger Local 0 NOTTALKING
[2018.07.13-19.37.47:715][496]LogVoice: Trigger Local 0 TALKING
[2018.07.13-19.37.48:532][544]LogVoice: Trigger Local 0 NOTTALKING
[2018.07.13-19.37.52:165][762]LogVoice: Trigger Local 0 TALKING
[2018.07.13-19.37.52:981][811]LogVoice: Trigger Local 0 NOTTALKING
[2018.07.13-19.38.00:467][262]LogNet: Warning: UNetConnection::Tick: Connection TIMED OUT. Closing connection… Elapsed: 60.03, Real: 60.00, Good: 60.00, DriverTime: 60.03, Threshold: 60.00, [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: SteamNetConnection_0, Driver: PendingNetDriver SteamNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID
[2018.07.13-19.38.00:467][262]LogNet: Warning: Network Failure: PendingNetDriver[ConnectionTimeout]: UNetConnection::Tick: Connection TIMED OUT. Closing connection… Elapsed: 60.03, Real: 60.00, Good: 60.00, DriverTime: 60.03, Threshold: 60.00, [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: SteamNetConnection_0, Driver: PendingNetDriver SteamNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID
[2018.07.13-19.38.00:467][262]LogNet: NetworkFailure: ConnectionTimeout, Error: ‘UNetConnection::Tick: Connection TIMED OUT. Closing connection… Elapsed: 60.03, Real: 60.00, Good: 60.00, DriverTime: 60.03, Threshold: 60.00, [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: SteamNetConnection_0, Driver: PendingNetDriver SteamNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID’
[2018.07.13-19.38.00:467][262]LogGameMode: Display: Match State Changed from LeavingMap to Aborted
[2018.07.13-19.38.00:467][262]LogGameState: Match State Changed from LeavingMap to Aborted
[2018.07.13-19.38.00:467][262]LogNet: UNetConnection::Close: [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: SteamNetConnection_0, Driver: PendingNetDriver SteamNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID, Channels: 2, Time: 2018.07.13-19.38.00
[2018.07.13-19.38.00:467][262]LogNet: UChannel::Close: Sending CloseBunch. ChIndex == 0. Name: [UChannel] ChIndex: 0, Closing: 0 [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: SteamNetConnection_0, Driver: PendingNetDriver SteamNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID
[2018.07.13-19.38.00:467][262]LogNet: DestroyNamedNetDriver SteamNetDriver_0 [PendingNetDriver]
[2018.07.13-19.38.00:467][262]LogExit: PendingNetDriver SteamNetDriver_0 shut down
[2018.07.13-19.38.00:483][263]LogGameMode: Display: Match State Changed from Aborted to LeavingMap
[2018.07.13-19.38.00:483][263]LogGameState: Match State Changed from Aborted to LeavingMap
[2018.07.13-19.38.00:483][263]LogNet: Browse: /Game/Maps/MainMenu?closed
[2018.07.13-19.38.00:483][263]LogNet: Failed; returning to Entry
[2018.07.13-19.38.00:483][263]LogLoad: LoadMap: /Game/Maps/MainMenu?closed
[2018.07.13-19.38.00:491][263]LogNet: UChannel::CleanUp: ChIndex == 0. Closing connection. [UChannel] ChIndex: 0, Closing: 0 [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: None, Driver: PendingNetDriver None, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID
[2018.07.13-19.38.00:492][263]LogNet: UNetConnection::PendingConnectionLost. [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: None, Driver: PendingNetDriver None, IsServer: YES, PC: NULL, Owner: NULL, UniqueId: INVALID bPendingDestroy=0
[2018.07.13-19.38.00:492][263]LogNet: Verbose: UNetConnection::SetClientLoginState: State changing from Invalid to CleanedUp
[2018.07.13-19.38.00:492][263]LogNet: Verbose: UNetConnection::SetClientLoginState: State same: CleanedUp
[2018.07.13-19.38.00:572][263]LogAIModule: Creating AISystem for world MainMenu
[2018.07.13-19.38.00:572][263]LogLoad: Game class is ‘GameMode’
[2018.07.13-19.38.00:573][263]LogWorld: Bringing World /Game/Maps/MainMenu.MainMenu up for play (max tick rate 0) at 2018.07.13-14.38.00
[2018.07.13-19.38.00:573][263]LogWorld: Bringing up level for play took: 0.000421
[2018.07.13-19.38.00:574][263]LogGameMode: FindPlayerStart: PATHS NOT DEFINED or NO PLAYERSTART with positive rating
[2018.07.13-19.38.00:574][263]LogVoice: Stopping networked voice for user: 0
[2018.07.13-19.38.00:575][263]LogGameMode: Display: Match State Changed from EnteringMap to WaitingToStart
[2018.07.13-19.38.00:575][263]LogGameState: Match State Changed from EnteringMap to WaitingToStart
[2018.07.13-19.38.00:575][263]LogGameMode: Display: Match State Changed from WaitingToStart to InProgress
[2018.07.13-19.38.00:576][263]LogGameState: Match State Changed from WaitingToStart to InProgress
[2018.07.13-19.38.00:577][263]LogLoad: Took 0.093475 seconds to LoadMap(/Game/Maps/MainMenu)
[2018.07.13-19.38.59:247][476]LogOnline: Verbose: STEAM: FOnlineAsyncEventSteamRichPresenceUpdate got new information about user 76561198075981070
TLD I found this interesting:
Thing1:
2018.07.13-19.37.00:434][670]LogOnline: Join session: traveling to 10.0.0.184:7777
[2018.07.13-19.37.00:434][670]LogBlueprintUserMessages: [ServerRow_C_0] Joined server
[2018.07.13-19.37.00:443][671]LogGameMode: Display: Match State Changed from InProgress to LeavingMap
[2018.07.13-19.37.00:443][671]LogGameState: Match State Changed from InProgress to LeavingMap
[2018.07.13-19.37.00:443][671]LogNet: Browse: 10.0.0.184//Game/Maps/MainMenu
I see computer B is trying to join Computer A (10.0.0.184). Great! I also see my “Joined server” print. Great! However, Computer B is joining the wrong map - MainMenu. It’s supposed to be joining the BasicMap which is what Computer A is currently sitting in. Commonly this happens when someone forgets to add “listen” to the Open Map node.
Thing2:
[2018.07.13-19.38.00:467][262]LogNet: Warning: UNetConnection::Tick: Connection TIMED OUT. Closing connection… Elapsed: 60.03, Real: 60.00, Good: 60.00, DriverTime: 60.03, Threshold: 60.00, [UNetConnection] RemoteAddr: 10.0.0.184:7777, Name: SteamNetConnection_0, Driver: PendingNetDriver SteamNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, UniqueId: INVALID
After about a minute of sitting around and swearing at my desk ( see the TALKING / NOTTALKING logs? ), I see this connection timed out log. That’s also common when someone forgets to add “listen” to the Open Map node.
Other Relevant Information:
Computer A is running a Debug packaged Win10 (x64) build (using steam account A) from a source-built 4.19.2 engine
Computer B is running a Debug packaged macosx build (using steam account B) from a pre-built 4.19.2 engine
Thanks for reading this & giving me your time. I am almost out of ideas. I also have some logs in my PreLogin function of BasicMap’s custom GameMode class, and I never see them being called (or any other logs suggesting that someone is trying connecting).