Today i'm having a lot of trouble connection to some players while beeing able to play with others with only minor problems (2*temporary disconnect in 1 game). This didnt happen to me like that before.
this log should cover me trying to join a teamgame
and after trying to launch a ladder automatch
- Code: Select all
2013-09-26 16:42:27,318 DEBUG faf.fa.proxies Setting low delay on socket.
2013-09-26 16:42:27,318 INFO faf.fa.proxies Connected to proxy server direct.faforever.com:9123
2013-09-26 16:42:27,318 INFO faf.fa.relayer Command received from server : {"commands": [10090], "key": "DisconnectFromPeer"}
2013-09-26 16:42:27,318 INFO faf.fa.relayer Command received from server : {"commands": [4, "83.8.244.231", "silver_eye", 10090], "key": "ConnectToProxy"}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["85.23.172.95:6112", "/PLAYERID 32970 BC_Tiep"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["178.25.167.182:6112", "/PLAYERID 18383 DNF_Stephelord"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["178.25.167.182:6112", "/ASKREPLY DNF_Stephelord"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["91.158.49.210:6112", "/PLAYERID 39721 Hartza"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["109.182.36.42:28000", "/PLAYERID 37639 RK4000"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["109.182.36.42:28000", "/PLAYERID 37639 RK4000"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["109.182.36.42:28000", "/ASKREPLY RK4000"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["87.225.34.66:6112", "/PLAYERID 13497 RA_Shado"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["87.225.34.66:6112", "/ASKREPLY RA_Shado"]}
2013-09-26 16:42:30,921 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["87.225.34.66:6112", "/PLAYERID 13497 RA_Shado"]}
2013-09-26 16:42:30,923 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["87.225.34.66:6112", "/ASKREPLY RA_Shado"]}
2013-09-26 16:42:30,923 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["91.158.49.210:6112", "/PLAYERID 39721 Hartza"]}
2013-09-26 16:42:30,923 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["91.158.49.210:6112", "/ASKREPLY Hartza"]}
2013-09-26 16:42:30,923 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["85.23.172.95:6112", "/PLAYERID 32970 BC_Tiep"]}
2013-09-26 16:42:30,924 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["85.23.172.95:6112", "/ASKREPLY BC_Tiep"]}
2013-09-26 16:42:31,109 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["48295"]}
2013-09-26 16:42:35,825 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["32970"]}
2013-09-26 16:42:35,825 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["10090"]}
2013-09-26 16:42:35,825 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["43282"]}
2013-09-26 16:42:35,825 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["37639"]}
2013-09-26 16:42:35,826 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["18383"]}
2013-09-26 16:42:35,826 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Connected", "chuncks": ["39721"]}
2013-09-26 16:42:44,280 INFO faf.fa.relayer Command received from server : {"commands": [], "key": "ping"}
2013-09-26 16:42:56,423 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Chat", "chuncks": ["hacks"]}
2013-09-26 16:43:05,915 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "Chat", "chuncks": ["this was 5/8 one minute ago"]}
2013-09-26 16:43:15,282 INFO faf.fa.relayer Command received from server : {"commands": [], "key": "ping"}
2013-09-26 16:43:42,278 INFO faf.fa.relayer FA disconnected locally.
2013-09-26 16:43:42,278 INFO faf.fa.proxies disconnecting from proxy server
2013-09-26 16:43:42,279 INFO faf.fa.relayer remove relay
2013-09-26 16:43:42,279 DEBUG faf.fa.relayer destructor called
2013-09-26 16:43:42,431 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,431 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,431 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,433 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,701 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,704 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,799 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:42,809 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:43,288 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:43,289 WARNING faf.fa.proxies error writing to proxy server !
2013-09-26 16:43:43,607 INFO faf.client FA has finished with exit code: 0
2013-09-26 16:43:43,607 INFO faf.client Outgoing JSON Message: {"state": "off", "command": "fa_state"}
2013-09-26 16:43:43,608 INFO faf.chat autopostjoin: true
2013-09-26 16:44:19,447 INFO faf.fa Checking FA for: ladder1v1 and map None
2013-09-26 16:44:19,447 INFO faf.fa Updating FA for mod: ladder1v1, version None
2013-09-26 16:44:19,448 DEBUG faf.updater Update started at 2013-09-26 16:44:19
2013-09-26 16:44:19,448 DEBUG faf.updater Using game path: Z:\Program Files (x86)\THQ\Supreme Commander Forged Alliance
2013-09-26 16:44:19,448 DEBUG faf.updater Using appdata: C:\ProgramData\FAForever
2013-09-26 16:44:19,611 DEBUG faf.updater Connected to update server at 2013-09-26 16:44:19
2013-09-26 16:44:19,615 DEBUG faf.updater writeToServer(GET_FILES_TO_UPDATE, [FAF])
2013-09-26 16:44:19,644 DEBUG faf.updater handleAction(LIST_FILES_TO_UP) - 324 bytes
2013-09-26 16:44:19,645 DEBUG faf.updater Files to update: [ForgedAlliance.exe, init_faf.lua, init_ladder1v1.lua, GDFBinary.dll, SupComDataPath.lua, SupComDataPathFAF.lua, init_nonxt.lua]
2013-09-26 16:44:19,647 DEBUG faf.updater writeToServer(UPDATE, [bin, init_nonxt.lua, 46d9008f6b77d2c60f96272a816e4227])
2013-09-26 16:44:19,648 DEBUG faf.updater writeToServer(UPDATE, [bin, GDFBinary.dll, 3fc9e7684d150da5c51db71d2399f316])
2013-09-26 16:44:19,650 DEBUG faf.updater writeToServer(UPDATE, [bin, init_faf.lua, 7d5cebd90024c4c5dc44ae8f5c9fa35b])
2013-09-26 16:44:19,687 DEBUG faf.updater writeToServer(UPDATE, [bin, ForgedAlliance.exe, b779d298a8ac4412dfbcc7667cf5578f])
2013-09-26 16:44:19,687 DEBUG faf.updater writeToServer(UPDATE, [bin, SupComDataPath.lua, 55ac1ef97845f2291da64b830299331e])
2013-09-26 16:44:19,688 DEBUG faf.updater writeToServer(UPDATE, [bin, SupComDataPathFAF.lua, 962c9d1327657d77184adcf358c628f1])
2013-09-26 16:44:19,690 DEBUG faf.updater writeToServer(UPDATE, [bin, init_ladder1v1.lua, ea96ac9cec88af29c08556c96465acfe])
2013-09-26 16:44:19,726 DEBUG faf.updater handleAction(UP_TO_DATE) - 56 bytes
2013-09-26 16:44:19,726 DEBUG faf.updater file : init_nonxt.lua
2013-09-26 16:44:19,726 DEBUG faf.updater init_nonxt.lua is up to date.
2013-09-26 16:44:19,970 DEBUG faf.updater handleAction(UP_TO_DATE) - 54 bytes
2013-09-26 16:44:19,970 DEBUG faf.updater file : GDFBinary.dll
2013-09-26 16:44:19,970 DEBUG faf.updater GDFBinary.dll is up to date.
2013-09-26 16:44:19,970 DEBUG faf.updater handleAction(UP_TO_DATE) - 52 bytes
2013-09-26 16:44:19,970 DEBUG faf.updater file : init_faf.lua
2013-09-26 16:44:19,971 DEBUG faf.updater init_faf.lua is up to date.
2013-09-26 16:44:19,971 DEBUG faf.updater handleAction(UP_TO_DATE) - 64 bytes
2013-09-26 16:44:19,971 DEBUG faf.updater file : ForgedAlliance.exe
2013-09-26 16:44:19,971 DEBUG faf.updater ForgedAlliance.exe is up to date.
2013-09-26 16:44:19,973 DEBUG faf.updater handleAction(UP_TO_DATE) - 64 bytes
2013-09-26 16:44:19,973 DEBUG faf.updater file : SupComDataPath.lua
2013-09-26 16:44:19,973 DEBUG faf.updater SupComDataPath.lua is up to date.
2013-09-26 16:44:19,973 DEBUG faf.updater handleAction(UP_TO_DATE) - 70 bytes
2013-09-26 16:44:19,973 DEBUG faf.updater file : SupComDataPathFAF.lua
2013-09-26 16:44:19,973 DEBUG faf.updater SupComDataPathFAF.lua is up to date.
2013-09-26 16:44:19,974 DEBUG faf.updater handleAction(UP_TO_DATE) - 64 bytes
2013-09-26 16:44:19,974 DEBUG faf.updater file : init_ladder1v1.lua
2013-09-26 16:44:19,974 DEBUG faf.updater init_ladder1v1.lua is up to date.
2013-09-26 16:44:19,976 DEBUG faf.updater Updates applied successfully.
2013-09-26 16:44:19,976 DEBUG faf.updater writeToServer(GET_FILES_TO_UPDATE, [FAFGAMEDATA])
2013-09-26 16:44:20,012 DEBUG faf.updater handleAction(LIST_FILES_TO_UP) - 134 bytes
2013-09-26 16:44:20,013 DEBUG faf.updater Files to update: [faforever.faf, faforever.nxt, lobby.nxt]
2013-09-26 16:44:20,023 DEBUG faf.updater writeToServer(UPDATE, [gamedata, faforever.faf, 01918c842de543150cfc20cd511986ac])
2013-09-26 16:44:20,211 DEBUG faf.updater writeToServer(UPDATE, [gamedata, faforever.nxt, e046d2c5af04f78aca023941c1abd949])
2013-09-26 16:44:20,234 DEBUG faf.updater writeToServer(UPDATE, [gamedata, lobby.nxt, 9e6521423cc955e2cfb821839d482226])
2013-09-26 16:44:20,269 DEBUG faf.updater handleAction(UP_TO_DATE) - 54 bytes
2013-09-26 16:44:20,269 DEBUG faf.updater file : faforever.faf
2013-09-26 16:44:20,269 DEBUG faf.updater faforever.faf is up to date.
2013-09-26 16:44:20,569 DEBUG faf.updater handleAction(UP_TO_DATE) - 54 bytes
2013-09-26 16:44:20,569 DEBUG faf.updater file : faforever.nxt
2013-09-26 16:44:20,569 DEBUG faf.updater faforever.nxt is up to date.
2013-09-26 16:44:20,571 DEBUG faf.updater handleAction(UP_TO_DATE) - 46 bytes
2013-09-26 16:44:20,571 DEBUG faf.updater file : lobby.nxt
2013-09-26 16:44:20,571 DEBUG faf.updater lobby.nxt is up to date.
2013-09-26 16:44:20,571 DEBUG faf.updater Updates applied successfully.
2013-09-26 16:44:20,571 DEBUG faf.updater Disconnected from server at 2013-09-26 16:44:20
2013-09-26 16:44:20,615 DEBUG faf.updater Update finished at 2013-09-26 16:44:20
2013-09-26 16:44:20,615 INFO faf.fa Writing fa_path.lua config file.
2013-09-26 16:44:20,622 INFO faf.fa.upnp Creating UPnP port mappings...
2013-09-26 16:44:20,625 ERROR faf.fa.upnp Couldn't get StaticPortMappingCollection
2013-09-26 16:44:20,625 INFO faf.games Starting Ranked Search as /seraphim, port: 6112
2013-09-26 16:44:20,627 INFO faf.client Outgoing JSON Message: {"state": "start", "mod": "ladder1v1", "command": "game_matchmaking", "faction": "/seraphim", "gameport": 6112}
2013-09-26 16:44:30,631 DEBUG faf.games Expanding search to 0.05
2013-09-26 16:44:30,631 INFO faf.client Outgoing JSON Message: {"rate": 0.05, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:44:40,631 DEBUG faf.games Expanding search to 0.1
2013-09-26 16:44:40,631 INFO faf.client Outgoing JSON Message: {"rate": 0.1, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:44:45,460 INFO faf.client Handling game_launch via JSON {u'uid': 1336952, u'args': [u'/players 2', u'/team 2'], u'mapname': u'8 - badlands_v4.v0001', u'reason': u'ranked', u'command': u'game_launch', u'mod': u'ladder1v1'}
2013-09-26 16:44:45,460 INFO faf.fa Updating FA for map: 8 - badlands_v4.v0001
2013-09-26 16:44:45,460 INFO faf.fa Map is available.
2013-09-26 16:44:45,460 INFO faf.fa.upnp Creating UPnP port mappings...
2013-09-26 16:44:45,463 ERROR faf.fa.upnp Couldn't get StaticPortMappingCollection
2013-09-26 16:44:45,463 INFO faf.fa Running FA with info: {'recorder': u'Lame', 'game_time': 1380206685.463, 'uid': 1336952, 'version_info': {'lobby': '0.8.80'}, 'featured_mod': u'ladder1v1'}
2013-09-26 16:44:45,463 INFO faf.fa Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAlliance.exe" /players 2 /team 2 /seraphim /mean 1255.1671099 /deviation 47.1682978881 /init init_ladder1v1.lua /savereplay gpgnet://localhost/1336952/Lame.SCFAreplay /nobugreport /gpgnet 127.0.0.1:58647
2013-09-26 16:44:45,483 INFO faf.client FA has launched in an attached process.
2013-09-26 16:44:45,483 INFO faf.client Outgoing JSON Message: {"state": "on", "command": "fa_state"}
2013-09-26 16:44:45,483 DEBUG faf.games Stopping Ranked Search
2013-09-26 16:44:45,483 INFO faf.client Outgoing JSON Message: {"state": "stop", "command": "game_matchmaking", "mod": "ladder1v1"}
2013-09-26 16:44:53,832 DEBUG faf.fa.relayserver incoming connection to relay server...
2013-09-26 16:44:53,832 INFO faf.fa.relayer FA connected locally.
2013-09-26 16:44:53,861 DEBUG faf.fa.relayer faf server direct.faforever.com:8000
2013-09-26 16:44:53,861 DEBUG faf.fa.relayer Initializing ping timer
2013-09-26 16:44:53,861 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "GameState", "chuncks": ["Idle"]}
2013-09-26 16:44:53,894 INFO faf.fa.relayer Command received from server : {"commands": [1, 6112, "Lame", 63192, 1], "key": "CreateLobby"}
2013-09-26 16:44:53,894 INFO faf.fa.relayer Command received from server : {"commands": ["91.121.153.175:30351", "/PLAYERID 63192 Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:53,953 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "GameState", "chuncks": ["Lobby"]}
2013-09-26 16:44:54,095 INFO faf.fa.relayer Command received from server : {"commands": ["91.121.153.175:30351", "/PLAYERID 63192 Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:54,105 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["91.121.153.175:30351", "PACKET_RECEIVED 6117"]}
2013-09-26 16:44:54,138 INFO faf.client port used : 6117
2013-09-26 16:44:54,292 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:54,523 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:54,752 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:54,980 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:55,210 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:55,437 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:55,657 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:55,887 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:56,122 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:56,342 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:56,572 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:56,572 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:56,802 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:57,030 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:57,259 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:44:57,489 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37516", "Shadess", 69807], "key": "JoinGame"}
2013-09-26 16:45:07,388 INFO faf.fa.relayer Command received from server : {"commands": [0, "182.48.101.4", "Shadess", 69807], "key": "JoinProxy"}
2013-09-26 16:45:07,420 DEBUG faf.fa.proxies Setting low delay on socket.
2013-09-26 16:45:07,420 INFO faf.fa.proxies Connected to proxy server direct.faforever.com:9123
2013-09-26 16:45:23,821 INFO faf.fa.relayer FA disconnected locally.
2013-09-26 16:45:23,821 INFO faf.fa.proxies disconnecting from proxy server
2013-09-26 16:45:23,822 INFO faf.fa.relayer remove relay
2013-09-26 16:45:23,822 DEBUG faf.fa.relayer destructor called
2013-09-26 16:45:25,359 INFO faf.client FA has finished with exit code: 0
2013-09-26 16:45:25,359 INFO faf.client Outgoing JSON Message: {"state": "off", "command": "fa_state"}
2013-09-26 16:45:25,361 INFO faf.chat autopostjoin: true
2013-09-26 16:46:16,007 INFO faf.fa Checking FA for: ladder1v1 and map None
2013-09-26 16:46:16,007 INFO faf.fa Updating FA for mod: ladder1v1, version None
2013-09-26 16:46:16,009 DEBUG faf.updater Update started at 2013-09-26 16:46:16
2013-09-26 16:46:16,009 DEBUG faf.updater Using game path: Z:\Program Files (x86)\THQ\Supreme Commander Forged Alliance
2013-09-26 16:46:16,009 DEBUG faf.updater Using appdata: C:\ProgramData\FAForever
2013-09-26 16:46:16,190 DEBUG faf.updater Connected to update server at 2013-09-26 16:46:16
2013-09-26 16:46:16,196 DEBUG faf.updater writeToServer(GET_FILES_TO_UPDATE, [FAF])
2013-09-26 16:46:16,226 DEBUG faf.updater handleAction(LIST_FILES_TO_UP) - 324 bytes
2013-09-26 16:46:16,227 DEBUG faf.updater Files to update: [ForgedAlliance.exe, init_faf.lua, init_ladder1v1.lua, GDFBinary.dll, SupComDataPath.lua, SupComDataPathFAF.lua, init_nonxt.lua]
2013-09-26 16:46:16,229 DEBUG faf.updater writeToServer(UPDATE, [bin, init_nonxt.lua, 46d9008f6b77d2c60f96272a816e4227])
2013-09-26 16:46:16,230 DEBUG faf.updater writeToServer(UPDATE, [bin, GDFBinary.dll, 3fc9e7684d150da5c51db71d2399f316])
2013-09-26 16:46:16,232 DEBUG faf.updater writeToServer(UPDATE, [bin, init_faf.lua, 7d5cebd90024c4c5dc44ae8f5c9fa35b])
2013-09-26 16:46:16,266 DEBUG faf.updater writeToServer(UPDATE, [bin, ForgedAlliance.exe, b779d298a8ac4412dfbcc7667cf5578f])
2013-09-26 16:46:16,267 DEBUG faf.updater writeToServer(UPDATE, [bin, SupComDataPath.lua, 55ac1ef97845f2291da64b830299331e])
2013-09-26 16:46:16,269 DEBUG faf.updater writeToServer(UPDATE, [bin, SupComDataPathFAF.lua, 962c9d1327657d77184adcf358c628f1])
2013-09-26 16:46:16,269 DEBUG faf.updater writeToServer(UPDATE, [bin, init_ladder1v1.lua, ea96ac9cec88af29c08556c96465acfe])
2013-09-26 16:46:16,308 DEBUG faf.updater handleAction(UP_TO_DATE) - 56 bytes
2013-09-26 16:46:16,308 DEBUG faf.updater file : init_nonxt.lua
2013-09-26 16:46:16,308 DEBUG faf.updater init_nonxt.lua is up to date.
2013-09-26 16:46:16,532 DEBUG faf.updater handleAction(UP_TO_DATE) - 54 bytes
2013-09-26 16:46:16,532 DEBUG faf.updater file : GDFBinary.dll
2013-09-26 16:46:16,532 DEBUG faf.updater GDFBinary.dll is up to date.
2013-09-26 16:46:16,533 DEBUG faf.updater handleAction(UP_TO_DATE) - 52 bytes
2013-09-26 16:46:16,533 DEBUG faf.updater file : init_faf.lua
2013-09-26 16:46:16,533 DEBUG faf.updater init_faf.lua is up to date.
2013-09-26 16:46:16,535 DEBUG faf.updater handleAction(UP_TO_DATE) - 64 bytes
2013-09-26 16:46:16,535 DEBUG faf.updater file : ForgedAlliance.exe
2013-09-26 16:46:16,535 DEBUG faf.updater ForgedAlliance.exe is up to date.
2013-09-26 16:46:16,536 DEBUG faf.updater handleAction(UP_TO_DATE) - 64 bytes
2013-09-26 16:46:16,536 DEBUG faf.updater file : SupComDataPath.lua
2013-09-26 16:46:16,536 DEBUG faf.updater SupComDataPath.lua is up to date.
2013-09-26 16:46:16,536 DEBUG faf.updater handleAction(UP_TO_DATE) - 70 bytes
2013-09-26 16:46:16,536 DEBUG faf.updater file : SupComDataPathFAF.lua
2013-09-26 16:46:16,536 DEBUG faf.updater SupComDataPathFAF.lua is up to date.
2013-09-26 16:46:16,536 DEBUG faf.updater handleAction(UP_TO_DATE) - 64 bytes
2013-09-26 16:46:16,536 DEBUG faf.updater file : init_ladder1v1.lua
2013-09-26 16:46:16,536 DEBUG faf.updater init_ladder1v1.lua is up to date.
2013-09-26 16:46:16,538 DEBUG faf.updater Updates applied successfully.
2013-09-26 16:46:16,538 DEBUG faf.updater writeToServer(GET_FILES_TO_UPDATE, [FAFGAMEDATA])
2013-09-26 16:46:16,566 DEBUG faf.updater handleAction(LIST_FILES_TO_UP) - 134 bytes
2013-09-26 16:46:16,568 DEBUG faf.updater Files to update: [faforever.faf, faforever.nxt, lobby.nxt]
2013-09-26 16:46:16,578 DEBUG faf.updater writeToServer(UPDATE, [gamedata, faforever.faf, 01918c842de543150cfc20cd511986ac])
2013-09-26 16:46:16,757 DEBUG faf.updater writeToServer(UPDATE, [gamedata, faforever.nxt, e046d2c5af04f78aca023941c1abd949])
2013-09-26 16:46:16,780 DEBUG faf.updater writeToServer(UPDATE, [gamedata, lobby.nxt, 9e6521423cc955e2cfb821839d482226])
2013-09-26 16:46:16,815 DEBUG faf.updater handleAction(UP_TO_DATE) - 54 bytes
2013-09-26 16:46:16,815 DEBUG faf.updater file : faforever.faf
2013-09-26 16:46:16,815 DEBUG faf.updater faforever.faf is up to date.
2013-09-26 16:46:17,039 DEBUG faf.updater handleAction(UP_TO_DATE) - 54 bytes
2013-09-26 16:46:17,039 DEBUG faf.updater file : faforever.nxt
2013-09-26 16:46:17,039 DEBUG faf.updater faforever.nxt is up to date.
2013-09-26 16:46:17,040 DEBUG faf.updater handleAction(UP_TO_DATE) - 46 bytes
2013-09-26 16:46:17,040 DEBUG faf.updater file : lobby.nxt
2013-09-26 16:46:17,040 DEBUG faf.updater lobby.nxt is up to date.
2013-09-26 16:46:17,040 DEBUG faf.updater Updates applied successfully.
2013-09-26 16:46:17,042 DEBUG faf.updater Disconnected from server at 2013-09-26 16:46:17
2013-09-26 16:46:17,078 DEBUG faf.updater Update finished at 2013-09-26 16:46:17
2013-09-26 16:46:17,078 INFO faf.fa Writing fa_path.lua config file.
2013-09-26 16:46:17,089 INFO faf.fa.upnp Creating UPnP port mappings...
2013-09-26 16:46:17,094 ERROR faf.fa.upnp Couldn't get StaticPortMappingCollection
2013-09-26 16:46:17,094 INFO faf.games Starting Ranked Search as /seraphim, port: 6112
2013-09-26 16:46:17,094 INFO faf.client Outgoing JSON Message: {"state": "start", "mod": "ladder1v1", "command": "game_matchmaking", "faction": "/seraphim", "gameport": 6112}
2013-09-26 16:46:27,086 DEBUG faf.games Expanding search to 0.05
2013-09-26 16:46:27,086 INFO faf.client Outgoing JSON Message: {"rate": 0.05, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:46:37,101 DEBUG faf.games Expanding search to 0.1
2013-09-26 16:46:37,101 INFO faf.client Outgoing JSON Message: {"rate": 0.1, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:46:47,105 DEBUG faf.games Expanding search to 0.15
2013-09-26 16:46:47,105 INFO faf.client Outgoing JSON Message: {"rate": 0.15000000000000002, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:46:57,117 DEBUG faf.games Expanding search to 0.2
2013-09-26 16:46:57,117 INFO faf.client Outgoing JSON Message: {"rate": 0.2, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:47:07,121 DEBUG faf.games Search Cap reached at 0.25
2013-09-26 16:47:07,121 INFO faf.client Outgoing JSON Message: {"rate": 0.25, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2013-09-26 16:47:13,105 INFO faf.client Handling game_launch via JSON {u'uid': 1336959, u'args': [u'/players 2', u'/team 2'], u'mapname': u'scmp_020', u'reason': u'ranked', u'command': u'game_launch', u'mod': u'ladder1v1'}
2013-09-26 16:47:13,105 INFO faf.fa Updating FA for map: scmp_020
2013-09-26 16:47:13,107 INFO faf.fa Map is available.
2013-09-26 16:47:13,107 INFO faf.fa.upnp Creating UPnP port mappings...
2013-09-26 16:47:13,108 ERROR faf.fa.upnp Couldn't get StaticPortMappingCollection
2013-09-26 16:47:13,108 INFO faf.fa Running FA with info: {'recorder': u'Lame', 'game_time': 1380206833.109, 'uid': 1336959, 'version_info': {'lobby': '0.8.80'}, 'featured_mod': u'ladder1v1'}
2013-09-26 16:47:13,108 INFO faf.fa Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAlliance.exe" /players 2 /team 2 /seraphim /mean 1255.1671099 /deviation 47.1682978881 /init init_ladder1v1.lua /savereplay gpgnet://localhost/1336959/Lame.SCFAreplay /nobugreport /gpgnet 127.0.0.1:58647
2013-09-26 16:47:13,131 INFO faf.client FA has launched in an attached process.
2013-09-26 16:47:13,131 INFO faf.client Outgoing JSON Message: {"state": "on", "command": "fa_state"}
2013-09-26 16:47:13,131 DEBUG faf.games Stopping Ranked Search
2013-09-26 16:47:13,131 INFO faf.client Outgoing JSON Message: {"state": "stop", "command": "game_matchmaking", "mod": "ladder1v1"}
2013-09-26 16:47:20,687 DEBUG faf.fa.relayserver incoming connection to relay server...
2013-09-26 16:47:20,687 INFO faf.fa.relayer FA connected locally.
2013-09-26 16:47:23,710 DEBUG faf.fa.relayer faf server direct.faforever.com:8000
2013-09-26 16:47:23,710 DEBUG faf.fa.relayer Initializing ping timer
2013-09-26 16:47:23,711 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "GameState", "chuncks": ["Idle"]}
2013-09-26 16:47:24,357 INFO faf.fa.relayer Command received from server : {"commands": [1, 6112, "Lame", 63192, 1], "key": "CreateLobby"}
2013-09-26 16:47:24,357 INFO faf.fa.relayer Command received from server : {"commands": ["91.121.153.175:30351", "/PLAYERID 63192 Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:24,413 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "GameState", "chuncks": ["Lobby"]}
2013-09-26 16:47:24,539 INFO faf.fa.relayer Command received from server : {"commands": ["91.121.153.175:30351", "/PLAYERID 63192 Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:24,548 INFO faf.fa.relayer Command transmitted from FA to server : {"action": "ProcessNatPacket", "chuncks": ["91.121.153.175:30351", "PACKET_RECEIVED 6117"]}
2013-09-26 16:47:24,592 INFO faf.client port used : 6117
2013-09-26 16:47:24,740 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:24,993 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:25,296 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:25,595 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:25,595 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:25,895 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:26,194 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:26,194 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:26,426 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:26,654 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:26,884 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:27,114 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:27,345 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:27,345 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:27,573 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "/ASKREPLY Lame"], "key": "SendNatPacket"}
2013-09-26 16:47:27,802 INFO faf.fa.relayer Command received from server : {"commands": ["182.48.101.4:37518", "Shadess", 69807], "key": "JoinGame"}
2013-09-26 16:47:37,765 INFO faf.fa.relayer Command received from server : {"commands": [0, "182.48.101.4", "Shadess", 69807], "key": "JoinProxy"}
2013-09-26 16:47:37,792 DEBUG faf.fa.proxies Setting low delay on socket.
2013-09-26 16:47:37,792 INFO faf.fa.proxies Connected to proxy server direct.faforever.com:9123
2013-09-26 16:47:55,349 INFO faf.fa.relayer Command received from server : {"commands": [], "key": "ping"}
2013-09-26 16:48:26,355 INFO faf.fa.relayer Command received from server : {"commands": [], "key": "ping"}
2013-09-26 16:48:57,354 INFO faf.fa.relayer Command received from server : {"commands": [], "key": "ping"}
2013-09-26 16:49:28,357 INFO faf.fa.relayer Command received from server : {"commands": [], "key": "ping"}
2013-09-26 16:49:30,446 INFO faf.fa.relayer FA disconnected locally.
2013-09-26 16:49:30,446 INFO faf.fa.proxies disconnecting from proxy server
2013-09-26 16:49:30,446 INFO faf.fa.relayer remove relay
2013-09-26 16:49:30,447 DEBUG faf.fa.relayer destructor called
2013-09-26 16:49:31,424 INFO faf.client FA has finished with exit code: 0
2013-09-26 16:49:31,424 INFO faf.client Outgoing JSON Message: {"state": "off", "command": "fa_state"}
2013-09-26 16:49:31,424 INFO faf.chat autopostjoin: true