Download

Unable to join listen server with Join Session node using Advanced Sessions

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).

Answering my Q:

Seems like the game doesn’t let you join another session if it thinks the engine versions are different! Of course this is a good thing. I figured it out by switching subsystem to null, doing my rebuild
mac: 4.19 release
pc: 4.19.2 source

and I could see a clear error message telling me it didn’t want to join, mismatching versions.

Once I had rebuilt my mac on the source 4.19.2, it all worked.

Thanks for sharing, i got the same problem, and did what you said and it worked, thanks again for answering you own question

Hey all, I got the exact same problem but my versions are the same (4.26.1). Does someone have an idea why I am still loading the main menu when joining? :frowning:
EDIT: If I join on the same PC it works totally fine. The bug only happens if i try to join with my second device via network.

EDIT2: I recreated the map with my current Unreal version and it works now for some reason.

Hey Maffinius, I’m having the same issue. This is really weird, would you mind explaining what you mean why recreating the map with the unreal version?