Automatch Connection Problem - Driving me Insane!

[auApex_01]

First of all - I've opened all my ports and run the network tester and everything is as it should be.

However, when I run automatch, I can't host games at all and I FREQUENTLY have difficulty connecting to other hosts. When connecting to another host in automatch the progress message will either not get past "you are joining another game" and when it gets past this stage it will often not start the game after getting to "2/2 players automatched". It will connect me to some games, but more often than not I can't connect at all. It's driving me NUTS!

My questions are:
-Why can't I ever host in automatch? When I set up my own game in the lobby, it always works!

-Why can I only connect to other people's automatch games about 5% of the time? If I couldn't connect to ANY games then obviously the problem would be at my end. However, the fact that I can connect to SOME games suggests the problem is not at my end, or not always anyway!

This is seriously impacting on my enjoyment of this great game. I don't like to play team QS games as anything other than a brief diversion. I just want to be able to play ladder games and be able to easily connect to others and host my own games! Why is it so hard to make this happen? Every other multiplayer game (including RTS games) works perfectly for me. After jumping through the hoops of opening up ports, I don't think it's reasonable for a game to have so much trouble with online play. Am I the only one suffering from this problem or is it common?

This is not a rant - I really would value any suggestions as to how I can get my auto connections working and would also value an explanation as to why it's so difficult to get it to work. Please help!

[TBS]

ok, lets see a warnings.log from after a session where you had this problem please.

Also, try shutting down all firewall and virus scanner applications that may be interfering with things.

As a workaround, have you tried quickly cancelling automatch and then resuming it (clicking on the skull)? That can sometimes get the game started.

[auApex_01]

^
Yeah I play the little juggling act everytime I attempt to automatch. As soon as I see the "players are joining your game" message I have to disconnect and quickly re-connect in the hope that I get the same player hosting.

I run windows firewall but it is set to make exceptions for DOW/WA. I don't run any virus scanner.

My warnings.log file is attached below. I hope this helps someone to diagnose the problem! If anyone wants to have a go at answering ANY of my questions or making suggestions I would greatly appreciate it!

Thanks


23:34:41.71 W40KWA started at 2006-07-08 23:34
23:34:41.71 OS NT 5.1, 1024MB Physical Memory
23:34:41.71 RUN-OPTIONS
23:34:41.71 WORKING-DIR F:\games\THQ\Dawn of War
23:34:41.71 USER Tom
23:34:41.71
23:34:44.03 MATHBOX -- Version=5, Cpu=AMD Athlon 64:f=15,m=15, Mode=SSE
23:34:44.03 FILESYSTEM -- filepath failure, path does not exist 'Engine\Locale\english\Data'
23:34:44.03 GAME -- Warning: Failed to map folder 'Engine\Locale\english\Data'.
23:34:44.03 GAME -- Warhammer, 1.50, Build 96341.83893
23:34:44.03 GAME -- Available memory: 1023MB RAM, 4874MB Pagefile
23:34:44.03 State::State - set socket send buffer to 131072
23:34:44.03 State::State - set socket receive buffer to 131072
23:34:44.03 HostState - 192.168.1.5:6112 / 192.168.1.5:6112
23:34:44.03 Session : Now Host
23:34:44.03 Session : We are now a Host
23:34:44.03 PeerState - 192.168.1.5:6112 / 192.168.1.5:6112
23:34:44.03 PeerState::ConnectCallbackHost - connecting to host at 255.255.255.255:6112
23:34:44.03 GSTransport::Connect - GT2AddressError
23:34:44.03 SPOOGE - Driver[Dx9 : Hardware TnL] on adapter[0], version[4,32]
23:34:44.03 SPDx9 -- Driver Name = nv4_disp.dll Device = \\.\DISPLAY1 Desc = NVIDIA GeForce 7800 GT
23:34:44.03 SPDx9 -- Driver Vendor = 0x10DE Device = 0x0092 SubSys = 0x030110DE Rev = 0x00A1
23:34:44.03 SPDx9 -- Driver Version Product = 0x0006 Version = 0x000E SubVersion = 0x000A Build = 0x20E5 (8421)
23:34:44.03 SPDx9 -- Driver GUID = {D7B71E3E-43D2-11CF-8B53-0B2300C2CB35}
23:34:44.03 SPOOGE -- 479.00MB available texture memory
23:34:44.03 SPOOGE -- 479.00MB available texture memory
23:34:44.03 GAME -- Beginning FE
23:34:44.07 MOVIE -- Opening movie 'movies:dxp_relic_intro.lua'
23:34:44.64 MOVIE -- Closing movie 'movies:dxp_relic_intro.lua'
23:34:44.64 MOVIE -- Opening movie 'movies:dow_intro.lua'
23:34:44.79 MOVIE -- Closing movie 'movies:dow_intro.lua'
23:34:46.85 MOD -- Initializing Mod wxp, 1.0
23:34:46.85 MOD -- Warning: Failed to load archive 'W40k\W40kDataGOTY.sga'
23:34:46.85 MOD -- Warning: Failed to open folder 'WXP\Locale\english\Data'.
23:34:46.85 MOD -- Warning: Failed to open folder 'WXP\Data_Shared_Textures\Full'.
23:34:46.85 MOD -- Warning: Failed to open folder 'WXP\Data_Sound\Med'.
23:34:46.85 MOD -- Warning: Failed to open folder 'WXP\Data_Music'.
23:34:46.85 MOD -- Warning: Failed to open folder 'WXP\Data_Whm\High'.
23:34:46.85 MOD -- Warning: Failed to open folder 'W40k\Locale\english\Data'.
23:34:46.85 MOD -- Warning: Failed to open folder 'W40k\Data_Shared_Textures\Full'.
23:34:46.85 MOD -- Warning: Failed to open folder 'W40k\Data_Sound\Med'.
23:34:46.85 MOD -- Warning: Failed to open folder 'W40k\Data_Whm\High'.
23:34:46.85 GAME -- Warning: Skipping MOD 'w40k' campaign 'DATA:SCENARIOS\SP\DAWNOFWAR.CAMP'.
23:34:48.25 GAME -- Using player profile Apecks
23:34:51.46 Lobby - LIE_ListPlayers received
23:34:51.46 Lobby - LOE_LobbySystemConnectionResult received
23:34:51.46 Lobby - in lobby handler restarting session on think
23:34:51.46 GAME -- Creating session
23:34:51.46 Lobby - LIE_Initialise received
23:34:51.46 Lobby - LIE_ListGames received
23:34:51.46 GAME -- Closing session
23:34:51.46 GAME -- Creating session
23:34:52.76 Lobby - LIE_StopListGames received
23:34:52.76 GAME -- Closing session
23:34:53.93 Lobby - LOE_LobbySystemConnectionResult received
23:34:56.73 Lobby - LIE_ValidateNickName received
23:34:56.73 Lobby - LOE_NickNameValidation received
23:34:57.67 Lobby - LIE_Initialise received
23:34:57.67 GSPeer::Logon - 7 OpiateBliss
23:34:57.67 GAME -- Creating session
23:35:00.67 GSPeer::Dispatch::OnChatConnect 1, 'OpiateBliss-thq'
23:35:00.93 GSPeer::Dispatch::OnCDAuthenticate - 1
23:35:00.93 GSPeer::Dispatch::OnCDAuthenticate - Authenticated
23:35:00.93 Lobby - LOE_LogInResult received
23:35:00.93 Lobby - LIE_CheckForPatch received
23:35:00.93 GSPatcher::CheckForPatch before
23:35:00.93 GSPatcher::CheckForPatch after
23:35:01.59 Lobby - received patch check result from gamespy 0, ,
23:35:01.59 Lobby - LOE_PatchQueryResult received
23:35:01.59 Lobby - LIE_ListPlayers received
23:35:01.59 Lobby - LIE_TA_Invitable received
23:35:01.59 Lobby - LIE_ListPlayers received
23:35:01.59 Lobby - LIE_PatchQueryResult received
23:35:01.59 Lobby - LIE_ListRooms received
23:35:01.59 Lobby - LIE_GetNews received
23:35:01.95 Lobby - LOE_Download_Complete received
23:35:01.95 Lobby - LIE_UpdateSpamConfig received
23:35:02.18 Lobby - LIE_JoinRoom received
23:35:02.48 Lobby - LOE_Download_Complete received
23:35:02.93 Lobby - LIE_StopAutoMatch received
23:35:02.93 Lobby - LIE_QuitGame received
23:35:02.93 GSPeer::SessionGetList()
23:35:02.93 GAME -- Closing session
23:35:02.93 GSPeer::SessionCancel
23:35:02.93 Lobby - LOE_News received
23:35:02.93 GSPeer::OnServerClear
23:35:02.93 Lobby - LOE_RoomJoinResult received
23:35:04.04 Lobby - LIE_ListPlayers received
23:35:04.04 Lobby - LIE_StartAutoMatch received
23:35:04.04 GAME -- Closing session
23:35:04.04 GAME -- Creating session
23:35:04.04 GSPeer::OnServerClear
23:35:04.04 GSPeer::AutoMatchStatus - Searching before
23:35:04.04 Lobby - LOE_PlayerCompleteStats received
23:35:05.96 Automatch report variant key= 1.0wxp
23:35:05.96 GSPeer::AutoMatchStatus - Waiting before
23:35:08.60 GSPeer::AutoMatchStatus - Staging before
23:35:08.60 GSPeer::AutoMatchStatus - Ready before
23:35:08.60 AutoMatch: Start Host
23:35:08.60 Lobby - LIE_HostGame received
23:35:08.60 GAME -- Closing session
23:35:08.60 Lobby -- hosting on port 6112
23:35:08.60 GAME -- Creating session
23:35:08.60 HostState - 220.253.27.177:6112 / 192.168.1.5:6112
23:35:08.60 Skirmish - loading badge 'space_marine_race/bloodangels' for player (0)
23:35:08.60 Skirmish - loading banner 'space_marine_race/BloodAngels' for player (0)
23:35:08.60 Lobby - LIE_PlayerUpdate received
23:35:08.64 Session : Now Host
23:35:08.64 Session : We are now a Host
23:35:08.65 Lobby - Host created 69f5246c
23:35:08.65 Lobby - LOE_JoinHostResult received
23:35:08.65 AutoMatch: Succeed hosting, Send Start Game
23:35:08.65 Lobby - Ignoring New Peer for local player
23:35:08.65 GSPeer::AutoMatchStatus - Complete before
23:35:09.32 Lobby - LIE_StopAutoMatch received
23:35:09.32 Lobby - LIE_QuitGame received
23:35:09.32 GAME -- Closing session
23:35:10.21 Lobby - LIE_ListPlayers received
23:35:10.21 Lobby - LIE_StartAutoMatch received
23:35:10.21 GAME -- Closing session
23:35:10.21 GAME -- Creating session
23:35:10.21 GSPeer::OnServerClear
23:35:10.21 GSPeer::AutoMatchStatus - Searching before
23:35:10.21 Lobby - LOE_PlayerCompleteStats received
23:35:10.79 AutoMatch compare options
23:35:10.79 Automatch variant key= 1.0wxp; 1.0wxp
23:35:11.51 Automatch report variant key= 1.0wxp
23:35:11.51 GSPeer::AutoMatchStatus - Ready before
23:35:18.70 Lobby - LIE_StopAutoMatch received
23:35:18.70 Lobby - LIE_QuitGame received
23:35:18.70 GAME -- Closing session
23:35:24.75 Lobby - LIE_ListPlayers received
23:35:24.75 Lobby - LIE_StartAutoMatch received
23:35:24.75 GAME -- Closing session
23:35:24.75 GAME -- Creating session
23:35:24.75 GSPeer::OnServerClear
23:35:24.75 GSPeer::AutoMatchStatus - Searching before
23:35:24.75 Lobby - LOE_PlayerCompleteStats received
23:35:25.29 AutoMatch compare options
23:35:25.29 Automatch variant key= 1.0wxp; 1.0wxp
23:35:25.98 Automatch report variant key= 1.0wxp
23:35:25.98 GSPeer::AutoMatchStatus - Waiting before
23:35:28.67 GSPeer::AutoMatchStatus - Staging before
23:35:28.67 GSPeer::AutoMatchStatus - Ready before
23:35:28.67 AutoMatch: Start Host
23:35:28.67 Lobby - LIE_HostGame received
23:35:28.67 GAME -- Closing session
23:35:28.67 Lobby -- hosting on port 6112
23:35:28.67 GAME -- Creating session
23:35:28.67 HostState - 220.253.27.177:6112 / 192.168.1.5:6112
23:35:28.67 Skirmish - loading badge 'space_marine_race/bloodangels' for player (0)
23:35:28.67 Skirmish - loading banner 'space_marine_race/BloodAngels' for player (0)
23:35:28.67 Lobby - LIE_PlayerUpdate received
23:35:28.70 Session : Now Host
23:35:28.70 Session : We are now a Host
23:35:28.70 Lobby - Host created 976dc8b4
23:35:28.70 Lobby - LOE_JoinHostResult received
23:35:28.70 AutoMatch: Succeed hosting, Send Start Game
23:35:28.70 Lobby - Ignoring New Peer for local player
23:35:28.70 GSPeer::AutoMatchStatus - Complete before
23:35:29.18 Lobby - LIE_StopAutoMatch received
23:35:29.18 Lobby - LIE_QuitGame received
23:35:29.18 GAME -- Closing session
23:35:29.96 Lobby - LIE_ListPlayers received
23:35:29.96 Lobby - LIE_StartAutoMatch received
23:35:29.96 GAME -- Closing session
23:35:29.96 GAME -- Creating session
23:35:29.96 GSPeer::OnServerClear
23:35:29.96 GSPeer::AutoMatchStatus - Searching before
23:35:29.96 Lobby - LOE_PlayerCompleteStats received
23:35:30.53 AutoMatch compare options
23:35:30.53 Automatch variant key= 1.0wxp; 1.0wxp
23:35:30.53 AutoMatch matched successfully
23:35:30.53 AutoMatch compare options
23:35:30.53 Automatch variant key= 1.0wxp; 1.0wxp
23:35:31.23 GSPeer::AutoMatchStatus - Staging before
23:35:31.23 GSPeer::AutoMatchStatus - Ready before
23:35:33.43 GSPeer::AutoMatchStatus - Complete before
23:35:33.43 AutoMatch: Start Join
23:35:33.43 Lobby - LIE_JoinGame received
23:35:33.43 Lobby - Joining game
23:35:33.43 PeerState - 220.253.27.177:6112 / 192.168.1.5:6112
23:35:33.43 PeerState::ConnectCallbackHost - connecting to host at 84.149.123.244:6112
23:35:34.42 PeerState::ProcessConnected - result 0, message length 63
23:35:34.45 PeerState::ProcessConnected - connected to the host
23:35:34.45 PeerState::HandleSessionDetailsEvent - received details for the session, 1 peers to connect to (including host)
23:35:34.45 PeerState - connection complete, connected to 1 peers including the host
23:35:34.95 PeerState::HandlePeerInSessionEvent - we have successfully joined the session
23:35:34.95 Session : We are now a Peer
23:35:34.96 Lobby - Join success 957e6d52
23:35:34.96 Lobby - LOE_JoinHostResult received
23:35:35.18 Lobby -- Net WELCOME received
23:35:35.18 Lobby -- Setting up game with scenario 4P_TIBORAXX (valid)
23:35:35.18 Lobby - LOE_GameUpdate received
23:35:35.18 Lobby - LIE_OnGameUpdate received
23:35:35.18 Skirmish - loading badge 'space_marine_race/bloodangels' for player (1)
23:35:35.18 Skirmish - loading banner 'space_marine_race/BloodAngels' for player (1)
23:35:35.18 Lobby - LIE_PlayerUpdate received
23:35:35.18 Lobby - Sending a request to the host for a player update message (1 - 957e6d52 - OpiateBliss
23:35:35.18 Lobby - LIE_OnPlayerUpdate received
23:35:35.18 Lobby - LOE_MapStatus received
23:35:35.95 Lobby -- Net UPDATE PLAYER information for player (1 - 957e6d52 - OpiateBliss) received
23:35:35.95 Lobby - LIE_OnPlayerUpdate received
23:35:35.95 Lobby -- Net GAMESTARTING received
23:35:35.95 Lobby - LIE_GameStarting received
23:35:35.95 Lobby - LOE_GameStarting received
23:35:35.95 Lobby - loading badge 'space_marine_race/bloodangels' for player (1)
23:35:35.95 Lobby - loading banner 'space_marine_race/BloodAngels' for player (1)
23:35:35.95 Lobby - Sending badge for player (1)
23:35:37.95 Lobby - Badge received from net player 28f1f2ef for 0, 0 left
23:35:37.95 Lobby -- Local player sending ready to start
23:35:38.67 Lobby -- Net GAME START received
23:35:38.67 Lobby - LOE_GameStart received
23:35:38.67 AutoMatch: Starting game
23:35:38.67 Lobby - LIE_StopAutoMatch received
23:35:38.67 Lobby - LIE_TA_Invitable received
23:35:39.42 APP -- Game Start
23:35:39.42 GAME -- Ending FE
23:35:39.42 GAME -- Beginning multi-player mission '4P_TIBORAXX' (2 Humans, 0 Computers, 0 Observers)
23:35:39.42 GAME -- Recording game
23:35:39.42 GAME -- Initializing sync checking...
23:35:39.45 GAME -- Initializing FX Manager...
23:35:39.46 GAME -- Initializing Rendering Systems...
23:35:39.48 GAME -- Initializing NISLets...
23:35:39.59 GAME -- Initializing World Blueprints...
23:35:39.59 GAME -- Initializing Scene Graph...
23:35:39.60 GAME -- Initializing MOD systems...
23:35:40.81 GAME -- Initializing Session...
23:35:40.81 GAME -- Initializing Players...
23:35:40.92 GAME -- Initializing SCAR (Pre-Sim) systems...
23:35:41.59 MOD -- Loading Win Condition(DATA:Scar/WinConditions/ANNIHILATE.SCAR)
23:35:41.59 MOD -- Loading Win Condition(DATA:Scar/WinConditions/CONTROLAREA.SCAR)
23:35:41.59 MOD -- Loading Win Condition(DATA:Scar/WinConditions/STRATEGICOBJECTIVE.SCAR)
23:35:41.59 GAME -- Initializing Team Colour Systems...
23:35:45.98 GAME -- Preloading all models...
23:35:52.15 GAME -- Applying Team Colours...
23:35:53.06 GAME -- Initializing UI...
23:35:53.06 GAME -- Initializing LUA...
23:35:53.09 GAME -- Initializing SCAR (Post SIM)...
23:36:04.57 GAME -- Precaching Events...
23:36:04.57 GAME -- Remote peer (xXJunkyXx, 28f1f2ef) finished loading (94e4d302)
23:36:04.57 GAME -- Loading, still waiting on 957e6d52
23:36:04.57 GAME -- Initializing Sim-Vis Handlers...
23:36:04.84 GAME -- Initializing music...
23:36:04.84 GAME -- Initializing AI...
23:36:04.85 GAME -- Initializing SCAR...
23:36:05.00 GAME -- Preparing Sim-Vis...
23:36:05.28 SIM -- Setting SyncErrorChecking level to Low
23:36:05.28 GAME -- Local player (OpiateBliss, 957e6d52) finished loading (94e4d302) (26 seconds)
23:36:05.28 GAME -- Starting mission...
23:36:05.56 P2PNET -- no sync command for some time
23:37:14.54 P2PNET -- no sync command for some time
23:42:40.93 P2PNET -- no sync command for some time
23:53:00.09 P2PNET -- no sync command for some time
23:57:16.84 PeerState::ProcessConnectionClosed - reason 1
23:57:16.84 PeerState::ProcessConnectionClosed - InSession - lost connection to the host
23:57:16.84 PeerState - posting DestroyPeerEvent for (xXJunkyXx) (28f1f2ef) - reason Connection was lost
23:57:16.84 Closing connection 604c998
23:57:16.84 PeerState::DoHostMigration - we're the new host, switching to HostState
23:57:16.84 Session : We are now a Host
23:57:16.90 GAME -- Remote peer 28F1F2EF disconnected at frame 6566 (disconnected)
23:57:16.90 P2PNET -- Lost connection to peer 28f1f2ef
23:57:16.90 P2PNET -- Starting consolidation for peer 28f1f2ef
23:57:16.90 P2PNET -- Voting for consolidation of 28f1f2ef (frame 6566)
23:57:16.90 P2PNET -- Consolidation for 28f1f2ef is completed
23:57:16.90 GAME -- peer 28f1f2ef scheduled for destruction on frame 6568 (current 6566)
23:57:16.90 GAME -- sim player 1003 scheduled for destruction on frame 6568
23:57:17.04 GAME -- Frame 6568 - SimKillPlayer - Destroying SimPlayer 1003
23:57:17.04 MOD -- Player xXJunkyXx has been killed (4)(frame 6568)
23:57:17.96 MOD -- Game Over at frame 6575
23:57:23.40 APP -- Game Stop
23:57:23.40 GAME -- Closing session
23:57:23.40 GAME -- Ending mission (Game over)
23:57:23.40 GAME -- Resetting SIM...
23:57:23.40 Lobby - LIE_StopGame received
23:57:23.40 GSPersist::Report
23:57:23.40 GAME -- Beginning FE
23:57:23.40 Lobby - LIE_Back2FE received
23:57:26.96 Lobby - LIE_ListPlayers received
23:57:26.96 Lobby - LIE_TA_Invitable received
23:57:26.96 Lobby - LIE_ListPlayers received
23:57:26.96 Lobby - LIE_StopAutoMatch received
23:57:26.96 Lobby - LIE_QuitGame received
23:57:26.96 GAME -- Closing session
23:57:26.96 Lobby - LIE_ListRooms received
23:57:26.96 Lobby - LIE_JoinRoom received
23:57:26.96 Lobby - LIE_GetNews received
23:57:26.96 Lobby - LOE_News received
23:57:26.98 Lobby - LOE_PlayerCompleteStats received
23:57:27.59 Lobby - LOE_RoomJoinResult received
23:57:27.81 Lobby - LOE_PlayerCompleteStats received
23:57:29.76 Lobby - LIE_GetPlayerCompleteStats received
23:57:29.98 Lobby - LOE_PlayerCompleteStats received
23:57:30.75 Lobby - LIE_ListPlayers received
23:57:30.92 Lobby - LOE_PlayerCompleteStats received
23:57:34.28 Lobby - LIE_StopAutoMatch received
23:57:34.28 Lobby - LIE_QuitGame received
23:57:34.28 GSPeer::SessionGetList()
23:57:34.28 GAME -- Closing session
23:57:34.28 GSPeer::SessionCancel
23:57:34.28 Lobby - LIE_TA_Invitable received
23:57:36.65 GAME -- Ending FE
23:57:36.65 MOD -- Shutting down Mod 'wxp'...
23:57:37.26 SPOOGE -- 478.00MB available texture memory
23:57:37.26 SPOOGE -- 479.00MB available texture memory
23:57:37.26
Application closed without errors