Disconnect from over 50% of team games

This is for troubleshooting of problems with the FAF client and Forged Alliance game.

Moderator: PhilipJFry

Forum rules


BEFORE POSTING read


Our wiki contains a lot of helpful material about technical issues, try looking there first!

Use the search function before opening a thread! (https://www.faforever.com/forums/search.php)

Read this before posting here: https://wiki.faforever.com/index.php?ti ... cal_Issues
Post a reply

Disconnect from over 50% of team games

Postby BRNKoINSANITY » 22 Mar 2016, 02:33

When I play, there is a roughly 50/50 chance that I will disconnect with either one or two people, and the connection is never re-established. No one else loses connection, it is just between me and that person. Game log is posted.

Spoiler: show
info: GAMERESULT : \000defeat -1
debug: Loading module '\000/lua/ui/dialogs/score.lua\000'
debug: Loading module '\000/lua/ui/dialogs/hotstats.lua\000'
debug: Loading module '\000/lua/ui/dialogs/myeffecthelpers.lua\000'
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GAMERESULT : \000score 0
info: GPGNET: setting nat handler to 0x00000000
info: CNetTCPBuf::Read(): recv() failed: WSAEINTR
info: Run time: 0h20m25s


Spoiler: show
2016-03-21 19:07:40,737 INFO root FAF version: 0.11.53+430 Environment: production
2016-03-21 19:07:40,839 INFO __main__ >>> --------------------------- Application Launch
2016-03-21 19:07:41,256 INFO faf.secondaryServer.Statistic Instantiating secondary server.
2016-03-21 19:07:41,267 INFO util Read common stylesheet: client/client.css
2016-03-21 19:07:41,378 INFO util Read common stylesheet: stats/formatters/style.css
2016-03-21 19:07:41,391 INFO faf.secondaryServer.Tournament Instantiating secondary server.
2016-03-21 19:07:41,392 INFO util Read common stylesheet: tournaments/formatters/style.css
2016-03-21 19:07:42,503 INFO replays._replayswidget Replays Widget instantiated.
2016-03-21 19:07:42,506 INFO tutorials._tutorialswidget Tutorials instantiated.
2016-03-21 19:07:42,513 INFO util Read common stylesheet: coop/formatters/style.css
2016-03-21 19:07:42,532 INFO fa.replayserver listening on address 127.0.0.1:15000
2016-03-21 19:07:42,690 INFO client._clientwindow Outgoing JSON Message: {"version": "0.11.53+430", "command": "ask_session", "user_agent": "faf-client"}
2016-03-21 19:07:44,963 INFO client._clientwindow Logging in with {'login': u'BRNKoINSANITY', 'session': '988059236', 'command': 'hello', 'unique_id': 'DGZWMXlYdUc1UXlVaE9UN2dwR2pscGc9PThCUlhVNWZLTTF4YmxYcUxvVlJVbWJl\nUEpUL3owOU9oK0lJZEN5czZYQk9mMXFKNHVGOUt0VXlpeWpYZTJnODcKcWw0Q3V5\neUJkZW1jTmhNZjgrNnRZREIyNUlaZE1XV0VoT3lzUnUzMEkrbWp5eHB3WXo1SXk4\nNVlhWFh5SCtTTwp2R2YrL2dyZHU4a2l3enVZWWk3OXZHK0NWTDJ4QWpWUVFUSGNj\nY2pXOHorTEg2WnJPQXJySnVZdFJPUUQyMElJCmwzbmpkbUl3emtQemhSMGZhSzhD\namhiV0pGbEVHL1NCUThKWXhBQ1ZSSnBxZWJYOW5GRlg4R3NPUVdkdkYyVHoKQkpn\nUnJGMDE0emZiZjluMjdCUlZmN0RFdDhYbTgvSlNENzlYaUtvS2ZXVHlRMHRVUzEw\nTUpndUtwYWJuS2lPSQo0blBQTUxacGFtNmg5RFQvdFIwVkpJSjRGb0swYVpNcmpn\nUmV0TE5LcVpIZUJXTk1LUGJ5MFlKN1UzNjBwZ3liCnVMSEtjL0dBSWlYVi9UWTdE\nM3lNZFV5d3BGV0loTzJMRTllaFJqSnhIa3ZUL1FJRGlLaTVyRm5BZzBLZzBGeHkK\nZUNkMFdUazJWL0JnOGV6T0FZMkdGOVlKV3pZYm41ZGtIMmxCK2N6czFqTnNLSkdO\nK2JuTlJrOElvR2NReWptVQp1ZTd2RzVUSHFZTHNReEJrR001QmQ0L3p1bm9ENFJW\nSnozaVQrRVpsVFdWTjZobGNYRjQydnJhOGxIOEQvRmlRCmJpcUY4ZGgzN29lR3k0\nTGl5bHdhY05EWWVsTWZsYmxuNS9Ta2s0WGY1UHc9QXFKNUFiRjZKVm9CQ3NDQXpO\nSDJndm1ndXVPTCtiemhMQ1FTeGc9PQ=='}
2016-03-21 19:07:45,184 INFO fa.upnp UPnP mapping 192.168.44.11:6112
2016-03-21 19:07:45,269 ERROR fa.upnp Couldn't get StaticPortMappingCollection
2016-03-21 19:07:46,704 INFO connectivity.qturnsocket.QTurnSocket Turn socket initialized: faforever.com
2016-03-21 19:07:46,704 INFO client._clientwindow Outgoing JSON Message: {"args": [6112], "command": "InitiateTest", "target": "connectivity"}
2016-03-21 19:07:47,217 WARNING util Expensive lookup by player login, FIXME.
2016-03-21 19:07:51,312 INFO connectivity.helper.ConnectivityHelper Connectivity state is STUN, mapped address: 66.190.145.54:52274
2016-03-21 19:25:59,859 ERROR fa.maps Web preview download failed for bloodrock canyon
2016-03-21 19:25:59,861 ERROR fa.maps Web Preview not found for: bloodrock canyon
2016-03-21 20:02:54,019 INFO fa.check Checking FA for: faf and map scmp_009
2016-03-21 20:02:54,019 INFO fa.check Version unknown, assuming latest
2016-03-21 20:02:54,019 INFO fa.check Updating FA for mod: faf, version None
2016-03-21 20:02:54,019 INFO fa.check Writing fa_path.lua config file.
2016-03-21 20:02:55,298 INFO fa.check Updating FA for map: scmp_009
2016-03-21 20:02:55,298 INFO fa.check Map is available.
2016-03-21 20:02:55,299 INFO connectivity.turn Requesting relay allocation
2016-03-21 20:02:55,423 INFO connectivity.turn Relay allocated: ('37.58.123.3', 53684L)
2016-03-21 20:02:55,423 INFO connectivity.qturnsocket.QTurnSocket TURN state changed: TURNState.BOUND
2016-03-21 20:02:55,423 INFO client._clientwindow Outgoing JSON Message: {"relay_address": ["37.58.123.3", 53684], "command": "game_join", "uid": 4566837, "gameport": 6112}
2016-03-21 20:02:55,424 INFO connectivity.turn Permitting sends from ('66.190.145.54', 52274L)
2016-03-21 20:02:55,424 INFO connectivity.turn Permitting sends from ('37.58.123.2', 6112)
2016-03-21 20:02:55,424 INFO connectivity.turn Permitting sends from ('37.58.123.3', 6112)
2016-03-21 20:02:55,532 INFO client._clientwindow Handling game_launch via JSON {u'args': [u'/numgames 2422'], u'command': u'game_launch', u'uid': 4566837, u'mod': u'faf'}
2016-03-21 20:02:55,532 INFO fa.upnp UPnP mapping 192.168.44.11:6112
2016-03-21 20:02:55,532 ERROR fa.upnp Couldn't get StaticPortMappingCollection
2016-03-21 20:02:55,533 INFO fa.play Play received arguments: ['/mean', '1596.84', '/deviation', '61.2657', '/country ', u'US', '/clan', u'VoR']
2016-03-21 20:02:55,533 INFO fa.game_process Running FA with info: {'recorder': u'BRNKoINSANITY', 'featured_mod': u'faf', 'uid': 4566837, 'launched_at': 1458604975.534}
2016-03-21 20:02:55,533 INFO fa.game_process Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAlliance.exe" /mean 1596.84 /deviation 61.2657 /country US /clan VoR /init init_faf.lua /numgames 2422 /log "C:\ProgramData\FAForever\logs\game.log" /nobugreport /savereplay "gpgnet://localhost/4566837/BRNKoINSANITY.SCFAreplay" /gpgnet 127.0.0.1:53112
2016-03-21 20:02:55,533 INFO fa.game_process Running FA via executable: C:\ProgramData\FAForever\bin\ForgedAlliance.exe
2016-03-21 20:02:55,559 INFO client._clientwindow FA has launched in an attached process.
2016-03-21 20:02:55,559 INFO fa.game_session.GameSession Game has started
2016-03-21 20:03:01,128 INFO fa.game_session.GameSession Game connected through GPGNet
2016-03-21 20:03:01,131 INFO fa.game_connection.GPGNetConnection GC >> : GameState : [u'Idle']
2016-03-21 20:03:01,131 INFO fa.game_session.GameSession Incoming GPGNet: GameState [u'Idle']
2016-03-21 20:03:01,131 INFO fa.game_connection.GPGNetConnection GC<<: CreateLobby:(0, 6113, u'BRNKoINSANITY', 30361, 1)
2016-03-21 20:03:01,131 INFO fa.game_session.GameSession Outgoing relay message GameState [u'Idle']
2016-03-21 20:03:01,131 INFO client._clientwindow Outgoing JSON Message: {"args": ["Idle"], "command": "GameState", "target": "game"}
2016-03-21 20:03:01,302 INFO fa.game_connection.GPGNetConnection GC >> : GameState : [u'Lobby']
2016-03-21 20:03:01,302 INFO fa.game_session.GameSession Incoming GPGNet: GameState [u'Lobby']
2016-03-21 20:03:01,302 INFO fa.game_session.GameSession Outgoing relay message GameState [u'Lobby']
2016-03-21 20:03:01,302 INFO client._clientwindow Outgoing JSON Message: {"args": ["Lobby"], "command": "GameState", "target": "game"}
2016-03-21 20:03:01,446 INFO client._clientwindow Outgoing JSON Message: {"args": ["78.51.153.26:54690", "Hello from 27763"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,446 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,638 INFO connectivity.relay.Relay Allocating local relay for Calamity_Jones, 27763
2016-03-21 20:03:01,638 INFO fa.game_session.GameSession Bound peer Calamity_Jones/27763 to 54240
2016-03-21 20:03:01,638 INFO fa.game_connection.GPGNetConnection GC<<: JoinGame:('127.0.0.1:54240', u'Calamity_Jones', 27763)
2016-03-21 20:03:01,654 INFO client._clientwindow Outgoing JSON Message: {"args": ["5.198.124.188:6112", "Hello from 48642"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,654 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,664 INFO client._clientwindow Outgoing JSON Message: {"args": ["78.51.153.26:54690", "Hello from 27763"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,664 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,665 INFO client._clientwindow Outgoing JSON Message: {"args": ["78.51.153.26:54690", "Hello from 27763"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,665 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,684 INFO client._clientwindow Outgoing JSON Message: {"args": ["84.58.5.141:59578", "Hello from 60776"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,684 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,723 INFO client._clientwindow Outgoing JSON Message: {"args": ["5.198.124.188:6112", "Hello from 48642"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,723 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,723 INFO client._clientwindow Outgoing JSON Message: {"args": ["5.198.124.188:6112", "Hello from 48642"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,723 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,753 INFO client._clientwindow Outgoing JSON Message: {"args": ["84.58.5.141:59578", "Hello from 60776"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,753 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,753 INFO client._clientwindow Outgoing JSON Message: {"args": ["84.58.5.141:59578", "Hello from 60776"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,753 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,773 INFO client._clientwindow Outgoing JSON Message: {"args": ["5.198.124.188:6112", "Hello from 48642"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,773 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,812 INFO client._clientwindow Outgoing JSON Message: {"args": ["84.58.5.141:59578", "Hello from 60776"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,812 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,815 INFO connectivity.relay.Relay Allocating local relay for REVJT, 48642
2016-03-21 20:03:01,815 INFO fa.game_session.GameSession Bound peer REVJT/48642 to 54241
2016-03-21 20:03:01,815 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:54241', u'REVJT', 48642)
2016-03-21 20:03:01,844 INFO client._clientwindow Outgoing JSON Message: {"args": ["5.198.124.188:6112", "Hello from 48642"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,844 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,845 INFO client._clientwindow Outgoing JSON Message: {"args": ["5.198.124.188:6112", "Hello from 48642"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,845 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,854 INFO client._clientwindow Outgoing JSON Message: {"args": ["79.65.14.163:28299", "Hello from 115228"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,854 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,884 INFO client._clientwindow Outgoing JSON Message: {"args": ["79.65.14.163:28299", "Hello from 115228"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,884 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,884 INFO client._clientwindow Outgoing JSON Message: {"args": ["79.65.14.163:28299", "Hello from 115228"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,884 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,884 INFO client._clientwindow Outgoing JSON Message: {"args": ["84.58.5.141:59578", "Hello from 60776"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,884 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,885 INFO client._clientwindow Outgoing JSON Message: {"args": ["84.58.5.141:59578", "Hello from 60776"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,885 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,900 INFO connectivity.relay.Relay Allocating local relay for Ruzkillz, 60776
2016-03-21 20:03:01,900 INFO fa.game_session.GameSession Bound peer Ruzkillz/60776 to 54242
2016-03-21 20:03:01,900 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:54242', u'Ruzkillz', 60776)
2016-03-21 20:03:01,966 INFO client._clientwindow Outgoing JSON Message: {"args": ["86.121.129.186:6112", "Hello from 114210"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,967 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,967 INFO client._clientwindow Outgoing JSON Message: {"args": ["86.121.129.186:6112", "Hello from 114210"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,967 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:01,967 INFO client._clientwindow Outgoing JSON Message: {"args": ["86.121.129.186:6112", "Hello from 114210"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:01,967 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:03:02,081 INFO connectivity.relay.Relay Allocating local relay for Heiach, 115228
2016-03-21 20:03:02,082 INFO fa.game_session.GameSession Bound peer Heiach/115228 to 54243
2016-03-21 20:03:02,082 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:54243', u'Heiach', 115228)
2016-03-21 20:03:02,237 INFO connectivity.relay.Relay Allocating local relay for Karyci, 114210
2016-03-21 20:03:02,237 INFO fa.game_session.GameSession Bound peer Karyci/114210 to 54244
2016-03-21 20:03:02,237 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:54244', u'Karyci', 114210)
2016-03-21 20:03:05,638 INFO connectivity.turn Permitting sends from [u'70.254.224.181', 4182]
2016-03-21 20:03:05,793 INFO client._clientwindow Outgoing JSON Message: {"args": ["70.254.224.181:12113", "Bind 173993"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:05,795 INFO connectivity.helper.ConnectivityHelper Binding ('70.254.224.181', 12113L) to 173993
2016-03-21 20:03:05,795 INFO connectivity.turn Permitting sends from ('70.254.224.181', 12113L)
2016-03-21 20:03:05,795 INFO connectivity.turn Requesting channel bind for 16384:('70.254.224.181', 12113L)
2016-03-21 20:03:05,795 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:05,901 INFO connectivity.turn Successfully bound 70.254.224.181:12113 to 16384
2016-03-21 20:03:05,901 INFO connectivity.qturnsocket.QTurnSocket Bound channel 16384 to ('70.254.224.181', 12113L)
2016-03-21 20:03:05,908 INFO connectivity.relay.Relay Allocating local relay for MomsSpaghetti, 173993
2016-03-21 20:03:05,908 INFO fa.game_session.GameSession Bound peer MomsSpaghetti/173993 to 59016
2016-03-21 20:03:05,908 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:59016', u'MomsSpaghetti', 173993)
2016-03-21 20:03:06,098 INFO client._clientwindow Outgoing JSON Message: {"args": ["70.254.224.181:12113", "Bind 173993"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:06,098 INFO connectivity.helper.ConnectivityHelper Binding ('70.254.224.181', 12113) to 173993
2016-03-21 20:03:06,098 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:06,098 INFO client._clientwindow Outgoing JSON Message: {"args": ["70.254.224.181:12113", "Bind 173993"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:06,098 INFO connectivity.helper.ConnectivityHelper Binding ('70.254.224.181', 12113) to 173993
2016-03-21 20:03:06,098 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:06,098 INFO client._clientwindow Outgoing JSON Message: {"args": ["70.254.224.181:12113", "Bind 173993"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:06,098 INFO connectivity.helper.ConnectivityHelper Binding ('70.254.224.181', 12113) to 173993
2016-03-21 20:03:06,098 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:44,507 INFO fa.game_connection.GPGNetConnection GC >> : Chat : [u'yes']
2016-03-21 20:03:44,507 INFO fa.game_session.GameSession Incoming GPGNet: Chat [u'yes']
2016-03-21 20:03:44,507 INFO fa.game_session.GameSession Outgoing relay message Chat [u'yes']
2016-03-21 20:03:44,507 INFO client._clientwindow Outgoing JSON Message: {"args": ["yes"], "command": "Chat", "target": "game"}
2016-03-21 20:03:45,674 INFO fa.game_connection.GPGNetConnection GC >> : Chat : [u'I will']
2016-03-21 20:03:45,674 INFO fa.game_session.GameSession Incoming GPGNet: Chat [u'I will']
2016-03-21 20:03:45,674 INFO fa.game_session.GameSession Outgoing relay message Chat [u'I will']
2016-03-21 20:03:45,674 INFO client._clientwindow Outgoing JSON Message: {"args": ["I will"], "command": "Chat", "target": "game"}
2016-03-21 20:03:46,622 INFO fa.game_connection.GPGNetConnection GC >> : Chat : [u'sorry']
2016-03-21 20:03:46,622 INFO fa.game_session.GameSession Incoming GPGNet: Chat [u'sorry']
2016-03-21 20:03:46,622 INFO fa.game_session.GameSession Outgoing relay message Chat [u'sorry']
2016-03-21 20:03:46,622 INFO client._clientwindow Outgoing JSON Message: {"args": ["sorry"], "command": "Chat", "target": "game"}
2016-03-21 20:03:48,526 INFO connectivity.turn Permitting sends from [u'90.176.33.217', 58079]
2016-03-21 20:03:48,654 INFO client._clientwindow Outgoing JSON Message: {"args": ["90.176.33.217:58079", "Bind 49422"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:48,654 INFO connectivity.helper.ConnectivityHelper Binding ('90.176.33.217', 58079L) to 49422
2016-03-21 20:03:48,654 INFO connectivity.turn Permitting sends from ('90.176.33.217', 58079L)
2016-03-21 20:03:48,654 INFO connectivity.turn Requesting channel bind for 16385:('90.176.33.217', 58079L)
2016-03-21 20:03:48,654 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:48,654 INFO client._clientwindow Outgoing JSON Message: {"args": ["90.176.33.217:58079", "Bind 49422"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:48,654 INFO connectivity.helper.ConnectivityHelper Binding ('90.176.33.217', 58079L) to 49422
2016-03-21 20:03:48,654 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:48,654 INFO client._clientwindow Outgoing JSON Message: {"args": ["90.176.33.217:58079", "Bind 49422"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:03:48,654 INFO connectivity.helper.ConnectivityHelper Binding ('90.176.33.217', 58079L) to 49422
2016-03-21 20:03:48,654 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:03:48,765 INFO connectivity.turn Successfully bound 90.176.33.217:58079 to 16385
2016-03-21 20:03:48,765 INFO connectivity.qturnsocket.QTurnSocket Bound channel 16385 to ('90.176.33.217', 58079L)
2016-03-21 20:03:48,845 INFO connectivity.relay.Relay Allocating local relay for Raghar, 49422
2016-03-21 20:03:48,845 INFO fa.game_session.GameSession Bound peer Raghar/49422 to 51771
2016-03-21 20:03:48,845 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:51771', u'Raghar', 49422)
2016-03-21 20:03:49,450 INFO fa.game_connection.GPGNetConnection GC<<: DisconnectFromPeer:(173993,)
2016-03-21 20:03:49,459 INFO fa.game_connection.GPGNetConnection GC >> : Disconnected : [u'173993']
2016-03-21 20:03:49,459 INFO fa.game_session.GameSession Incoming GPGNet: Disconnected [u'173993']
2016-03-21 20:03:49,459 INFO fa.game_session.GameSession Outgoing relay message Disconnected [u'173993']
2016-03-21 20:03:49,459 INFO client._clientwindow Outgoing JSON Message: {"args": ["173993"], "command": "Disconnected", "target": "game"}
2016-03-21 20:04:51,270 INFO connectivity.turn Permitting sends from [u'24.23.93.38', 51634]
2016-03-21 20:04:51,433 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:04:51,433 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634L) to 170969
2016-03-21 20:04:51,433 INFO connectivity.turn Permitting sends from ('24.23.93.38', 51634L)
2016-03-21 20:04:51,433 INFO connectivity.turn Requesting channel bind for 16386:('24.23.93.38', 51634L)
2016-03-21 20:04:51,434 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:04:51,546 INFO connectivity.turn Successfully bound 24.23.93.38:51634 to 16386
2016-03-21 20:04:51,546 INFO connectivity.qturnsocket.QTurnSocket Bound channel 16386 to ('24.23.93.38', 51634L)
2016-03-21 20:04:51,546 INFO connectivity.relay.Relay Allocating local relay for MonkeyD-Luffy, 170969
2016-03-21 20:04:51,546 INFO fa.game_session.GameSession Bound peer MonkeyD-Luffy/170969 to 52594
2016-03-21 20:04:51,546 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:52594', u'MonkeyD-Luffy', 170969)
2016-03-21 20:04:51,641 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:04:51,641 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:04:51,641 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:04:51,645 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:04:51,645 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:04:51,645 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:04:51,647 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:04:51,647 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:04:51,647 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:04,555 INFO fa.game_connection.GPGNetConnection GC<<: DisconnectFromPeer:(170969,)
2016-03-21 20:05:04,563 INFO fa.game_connection.GPGNetConnection GC >> : Disconnected : [u'170969']
2016-03-21 20:05:04,565 INFO fa.game_session.GameSession Incoming GPGNet: Disconnected [u'170969']
2016-03-21 20:05:04,565 INFO fa.game_session.GameSession Outgoing relay message Disconnected [u'170969']
2016-03-21 20:05:04,565 INFO client._clientwindow Outgoing JSON Message: {"args": ["170969"], "command": "Disconnected", "target": "game"}
2016-03-21 20:05:19,352 INFO connectivity.turn Permitting sends from [u'24.23.93.38', 51634]
2016-03-21 20:05:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:19,503 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:05:19,503 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:19,622 INFO connectivity.relay.Relay Allocating local relay for MonkeyD-Luffy, 170969
2016-03-21 20:05:19,622 INFO fa.game_session.GameSession Bound peer MonkeyD-Luffy/170969 to 52597
2016-03-21 20:05:19,622 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:52597', u'MonkeyD-Luffy', 170969)
2016-03-21 20:05:19,703 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:19,703 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:05:19,703 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:19,703 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:19,703 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:05:19,703 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:19,703 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:19,703 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:05:19,703 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:54,411 INFO connectivity.turn Permitting sends from [u'108.46.248.217', 51732]
2016-03-21 20:05:54,766 INFO client._clientwindow Outgoing JSON Message: {"args": ["108.46.248.217:51732", "Bind 127374"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:54,766 INFO connectivity.helper.ConnectivityHelper Binding ('108.46.248.217', 51732L) to 127374
2016-03-21 20:05:54,766 INFO connectivity.turn Permitting sends from ('108.46.248.217', 51732L)
2016-03-21 20:05:54,766 INFO connectivity.turn Requesting channel bind for 16387:('108.46.248.217', 51732L)
2016-03-21 20:05:54,766 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:54,766 INFO client._clientwindow Outgoing JSON Message: {"args": ["108.46.248.217:51732", "Bind 127374"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:54,766 INFO connectivity.helper.ConnectivityHelper Binding ('108.46.248.217', 51732L) to 127374
2016-03-21 20:05:54,766 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:54,766 INFO client._clientwindow Outgoing JSON Message: {"args": ["108.46.248.217:51732", "Bind 127374"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:05:54,767 INFO connectivity.helper.ConnectivityHelper Binding ('108.46.248.217', 51732L) to 127374
2016-03-21 20:05:54,767 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:05:54,874 INFO connectivity.turn Successfully bound 108.46.248.217:51732 to 16387
2016-03-21 20:05:54,874 INFO connectivity.qturnsocket.QTurnSocket Bound channel 16387 to ('108.46.248.217', 51732L)
2016-03-21 20:05:54,880 INFO connectivity.relay.Relay Allocating local relay for TrinityReformed, 127374
2016-03-21 20:05:54,880 INFO fa.game_session.GameSession Bound peer TrinityReformed/127374 to 54719
2016-03-21 20:05:54,880 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:54719', u'TrinityReformed', 127374)
2016-03-21 20:06:02,829 INFO fa.game_connection.GPGNetConnection GC<<: DisconnectFromPeer:(170969,)
2016-03-21 20:06:02,839 INFO fa.game_connection.GPGNetConnection GC >> : Disconnected : [u'170969']
2016-03-21 20:06:02,839 INFO fa.game_session.GameSession Incoming GPGNet: Disconnected [u'170969']
2016-03-21 20:06:02,839 INFO fa.game_session.GameSession Outgoing relay message Disconnected [u'170969']
2016-03-21 20:06:02,841 INFO client._clientwindow Outgoing JSON Message: {"args": ["170969"], "command": "Disconnected", "target": "game"}
2016-03-21 20:06:18,063 INFO connectivity.turn Permitting sends from [u'24.23.93.38', 51634]
2016-03-21 20:06:18,217 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:06:18,217 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:06:18,217 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:06:18,322 INFO connectivity.relay.Relay Allocating local relay for MonkeyD-Luffy, 170969
2016-03-21 20:06:18,322 INFO fa.game_session.GameSession Bound peer MonkeyD-Luffy/170969 to 60244
2016-03-21 20:06:18,322 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:60244', u'MonkeyD-Luffy', 170969)
2016-03-21 20:06:18,421 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:06:18,421 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:06:18,421 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:06:18,424 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:06:18,424 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:06:18,426 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:06:18,426 INFO client._clientwindow Outgoing JSON Message: {"args": ["24.23.93.38:51634", "Bind 170969"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:06:18,426 INFO connectivity.helper.ConnectivityHelper Binding ('24.23.93.38', 51634) to 170969
2016-03-21 20:06:18,426 INFO connectivity.helper.ConnectivityHelper Processed bind request
2016-03-21 20:06:42,983 INFO fa.game_connection.GPGNetConnection GC<<: DisconnectFromPeer:(127374,)
2016-03-21 20:06:42,990 INFO fa.game_connection.GPGNetConnection GC >> : Disconnected : [u'127374']
2016-03-21 20:06:42,990 INFO fa.game_session.GameSession Incoming GPGNet: Disconnected [u'127374']
2016-03-21 20:06:42,990 INFO fa.game_session.GameSession Outgoing relay message Disconnected [u'127374']
2016-03-21 20:06:42,992 INFO client._clientwindow Outgoing JSON Message: {"args": ["127374"], "command": "Disconnected", "target": "game"}
2016-03-21 20:06:49,635 INFO fa.game_connection.GPGNetConnection GC<<: DisconnectFromPeer:(114210,)
2016-03-21 20:06:49,641 INFO fa.game_connection.GPGNetConnection GC >> : Disconnected : [u'114210']
2016-03-21 20:06:49,641 INFO fa.game_session.GameSession Incoming GPGNet: Disconnected [u'114210']
2016-03-21 20:06:49,641 INFO fa.game_session.GameSession Outgoing relay message Disconnected [u'114210']
2016-03-21 20:06:49,641 INFO client._clientwindow Outgoing JSON Message: {"args": ["114210"], "command": "Disconnected", "target": "game"}
2016-03-21 20:07:18,951 INFO client._clientwindow Outgoing JSON Message: {"args": ["83.251.124.237:60942", "Hello from 107641"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:07:18,951 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:07:18,953 INFO client._clientwindow Outgoing JSON Message: {"args": ["83.251.124.237:60942", "Hello from 107641"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:07:18,953 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:07:18,954 INFO client._clientwindow Outgoing JSON Message: {"args": ["83.251.124.237:60942", "Hello from 107641"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:07:18,954 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:07:19,059 INFO connectivity.relay.Relay Allocating local relay for ossi, 107641
2016-03-21 20:07:19,059 INFO fa.game_session.GameSession Bound peer ossi/107641 to 62082
2016-03-21 20:07:19,059 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:62082', u'ossi', 107641)
2016-03-21 20:07:25,982 INFO fa.game_connection.GPGNetConnection GC<<: DisconnectFromPeer:(60776,)
2016-03-21 20:07:25,992 INFO fa.game_connection.GPGNetConnection GC >> : Disconnected : [u'60776']
2016-03-21 20:07:25,992 INFO fa.game_session.GameSession Incoming GPGNet: Disconnected [u'60776']
2016-03-21 20:07:25,992 INFO fa.game_session.GameSession Outgoing relay message Disconnected [u'60776']
2016-03-21 20:07:25,992 INFO client._clientwindow Outgoing JSON Message: {"args": ["60776"], "command": "Disconnected", "target": "game"}
2016-03-21 20:07:43,165 INFO client._clientwindow Outgoing JSON Message: {"args": ["41.107.241.59:6001", "Hello from 171977"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:07:43,165 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:07:43,273 INFO connectivity.relay.Relay Allocating local relay for Huawei, 171977
2016-03-21 20:07:43,273 INFO fa.game_session.GameSession Bound peer Huawei/171977 to 53258
2016-03-21 20:07:43,273 INFO fa.game_connection.GPGNetConnection GC<<: ConnectToPeer:('127.0.0.1:53258', u'Huawei', 171977)
2016-03-21 20:07:43,361 INFO client._clientwindow Outgoing JSON Message: {"args": ["41.107.241.59:6001", "Hello from 171977"], "command": "ProcessNatPacket", "target": "connectivity"}
2016-03-21 20:07:43,361 INFO connectivity.helper.ConnectivityHelper Unknown natpacket
2016-03-21 20:08:27,174 INFO fa.game_connection.GPGNetConnection GC >> : GameState : [u'Launching']
2016-03-21 20:08:27,174 INFO fa.game_session.GameSession Incoming GPGNet: GameState [u'Launching']
2016-03-21 20:08:27,174 INFO fa.game_session.GameSession Outgoing relay message GameState [u'Launching']
2016-03-21 20:08:27,174 INFO client._clientwindow Outgoing JSON Message: {"args": ["Launching"], "command": "GameState", "target": "game"}
2016-03-21 20:08:27,408 INFO fa.game_process Game Info Complete: {u'featured_mod_versions': {u'11': 3650, u'13': 3650, u'12': 3650, u'15': 3650, u'14': 3650, u'17': 3650, u'19': 3650, u'18': 3650, u'22': 3650, u'20': 3650, u'1': 3650, u'3': 3634, u'2': 3640, u'5': 1, u'4': 1, u'6': 1, u'9': 1, u'8': 1, u'21': 3650}, u'num_players': 8, 'uid': 4566837, u'sim_mods': {}, u'max_players': 8, u'password_protected': False, u'title': u'All Welcome', u'game_type': 0, u'host': u'Calamity_Jones', u'visibility': None, u'teams': {u'3': [u'ossi', u'Huawei', u'BRNKoINSANITY', u'Heiach'], u'2': [u'REVJT', u'Raghar', u'Calamity_Jones', u'MonkeyD-Luffy']}, u'mapname': u'scmp_009', u'state': u'playing', u'command': u'game_info', 'featured_mod': u'faf', u'map_file_path': u'maps/scmp_009.zip', 'recorder': u'BRNKoINSANITY', 'launched_at': 1458605311.352064, 'complete': True}
2016-03-21 20:08:31,394 INFO fa.replayserver FA connected locally.
2016-03-21 20:08:31,512 INFO fa.replayserver Stripping prefix 'P/4566837/BRNKoINSANITY.SCFAreplay ' from replay.
2016-03-21 20:08:39,140 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'readiness', u'1', u'115228,48642', u'5467.6']
2016-03-21 20:08:39,140 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'readiness', u'1', u'115228,48642', u'5467.6']
2016-03-21 20:08:39,140 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'readiness', u'1', u'115228,48642', u'5467.6']
2016-03-21 20:08:39,140 INFO client._clientwindow Outgoing JSON Message: {"args": ["readiness", "1", "115228,48642", "5467.6"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:08:41,253 INFO fa.game_connection.GPGNetConnection GC >> : BottleneckCleared : []
2016-03-21 20:08:41,253 INFO fa.game_session.GameSession Incoming GPGNet: BottleneckCleared []
2016-03-21 20:08:41,253 INFO fa.game_session.GameSession Outgoing relay message BottleneckCleared []
2016-03-21 20:08:41,253 INFO client._clientwindow Outgoing JSON Message: {"args": [], "command": "BottleneckCleared", "target": "game"}
2016-03-21 20:22:02,967 ERROR fa.maps Web preview download failed for hexagonian_drylands.v0005
2016-03-21 20:22:02,973 ERROR fa.maps Web Preview not found for: hexagonian_drylands.v0005
2016-03-21 20:22:11,831 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'5153.0']
2016-03-21 20:22:11,831 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'5153.0']
2016-03-21 20:22:11,831 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'5153.0']
2016-03-21 20:22:11,831 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "5153.0"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:16,831 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'10153.0']
2016-03-21 20:22:16,831 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'10153.0']
2016-03-21 20:22:16,831 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'10153.0']
2016-03-21 20:22:16,832 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "10153.0"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:21,881 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'15202.4']
2016-03-21 20:22:21,881 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'15202.4']
2016-03-21 20:22:21,881 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'15202.4']
2016-03-21 20:22:21,881 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "15202.4"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:26,914 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'20236.1']
2016-03-21 20:22:26,914 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'20236.1']
2016-03-21 20:22:26,914 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'20236.1']
2016-03-21 20:22:26,914 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "20236.1"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:31,947 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'25268.9']
2016-03-21 20:22:31,947 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'25268.9']
2016-03-21 20:22:31,947 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'25268.9']
2016-03-21 20:22:31,947 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "25268.9"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:36,964 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'30286.2']
2016-03-21 20:22:36,964 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'30286.2']
2016-03-21 20:22:36,964 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'30286.2']
2016-03-21 20:22:36,964 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "30286.2"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:42,063 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'35386.1']
2016-03-21 20:22:42,065 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'35386.1']
2016-03-21 20:22:42,065 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'35386.1']
2016-03-21 20:22:42,065 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "35386.1"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:47,082 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'40403.6']
2016-03-21 20:22:47,082 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'40403.6']
2016-03-21 20:22:47,082 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'40403.6']
2016-03-21 20:22:47,082 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "40403.6"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:52,132 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'45454.9']
2016-03-21 20:22:52,132 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'45454.9']
2016-03-21 20:22:52,132 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'45454.9']
2016-03-21 20:22:52,132 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "45454.9"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:22:57,138 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'50460.9']
2016-03-21 20:22:57,138 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'50460.9']
2016-03-21 20:22:57,138 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'50460.9']
2016-03-21 20:22:57,138 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "50460.9"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:23:02,252 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'55574.0']
2016-03-21 20:23:02,252 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'55574.0']
2016-03-21 20:23:02,252 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'55574.0']
2016-03-21 20:23:02,252 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "55574.0"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:23:07,398 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'60720.8']
2016-03-21 20:23:07,398 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'60720.8']
2016-03-21 20:23:07,398 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'60720.8']
2016-03-21 20:23:07,398 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "60720.8"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:23:12,464 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'65787.4']
2016-03-21 20:23:12,466 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'65787.4']
2016-03-21 20:23:12,466 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'65787.4']
2016-03-21 20:23:12,466 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "65787.4"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:23:17,532 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'ack', u'7521', u'48642', u'70854.0']
2016-03-21 20:23:17,532 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'ack', u'7521', u'48642', u'70854.0']
2016-03-21 20:23:17,532 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'ack', u'7521', u'48642', u'70854.0']
2016-03-21 20:23:17,532 INFO client._clientwindow Outgoing JSON Message: {"args": ["ack", "7521", "48642", "70854.0"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:23:18,082 INFO fa.game_connection.GPGNetConnection GC >> : BottleneckCleared : []
2016-03-21 20:23:18,082 INFO fa.game_session.GameSession Incoming GPGNet: BottleneckCleared []
2016-03-21 20:23:18,082 INFO fa.game_session.GameSession Outgoing relay message BottleneckCleared []
2016-03-21 20:23:18,082 INFO client._clientwindow Outgoing JSON Message: {"args": [], "command": "BottleneckCleared", "target": "game"}
2016-03-21 20:23:18,634 INFO fa.game_connection.GPGNetConnection GC >> : Bottleneck : [u'data', u'7541', u'30361', u'71955.6']
2016-03-21 20:23:18,634 INFO fa.game_session.GameSession Incoming GPGNet: Bottleneck [u'data', u'7541', u'30361', u'71955.6']
2016-03-21 20:23:18,634 INFO fa.game_session.GameSession Outgoing relay message Bottleneck [u'data', u'7541', u'30361', u'71955.6']
2016-03-21 20:23:18,634 INFO client._clientwindow Outgoing JSON Message: {"args": ["data", "7541", "30361", "71955.6"], "command": "Bottleneck", "target": "game"}
2016-03-21 20:23:18,713 INFO fa.game_connection.GPGNetConnection GC >> : BottleneckCleared : []
2016-03-21 20:23:18,713 INFO fa.game_session.GameSession Incoming GPGNet: BottleneckCleared []
2016-03-21 20:23:18,713 INFO fa.game_session.GameSession Outgoing relay message BottleneckCleared []
2016-03-21 20:23:18,713 INFO client._clientwindow Outgoing JSON Message: {"args": [], "command": "BottleneckCleared", "target": "game"}
2016-03-21 20:23:19,464 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [7, u'defeat -1']
2016-03-21 20:23:19,466 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [7, u'defeat -1']
2016-03-21 20:23:19,466 INFO fa.game_session.GameSession Outgoing relay message GameResult [7, u'defeat -1']
2016-03-21 20:23:19,466 INFO client._clientwindow Outgoing JSON Message: {"args": [7, "defeat -1"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [1, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [1, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [1, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [1, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [2, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [2, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [2, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [2, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [3, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [3, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [3, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [3, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [4, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [4, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [4, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [4, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [5, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [5, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [5, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [5, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [6, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [6, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [6, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [6, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [8, u'score 0']

continued in next post due to length
BRNKoINSANITY
Evaluator
 
Posts: 951
Joined: 09 Oct 2012, 01:14
Has liked: 43 times
Been liked: 207 times
FAF User Name: BRNKoINSANITY
Top

Re: Disconnect from over 50% of team games

Postby BRNKoINSANITY » 22 Mar 2016, 03:40

The rest of the FAF log


Spoiler: show
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [8, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [8, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [8, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [9, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [9, u'score 0']
2016-03-21 20:23:19,503 INFO fa.game_session.GameSession Outgoing relay message GameResult [9, u'score 0']
2016-03-21 20:23:19,503 INFO client._clientwindow Outgoing JSON Message: {"args": [9, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:19,503 INFO fa.game_connection.GPGNetConnection GC >> : GameResult : [10, u'score 0']
2016-03-21 20:23:19,505 INFO fa.game_session.GameSession Incoming GPGNet: GameResult [10, u'score 0']
2016-03-21 20:23:19,505 INFO fa.game_session.GameSession Outgoing relay message GameResult [10, u'score 0']
2016-03-21 20:23:19,505 INFO client._clientwindow Outgoing JSON Message: {"args": [10, "score 0"], "command": "GameResult", "target": "game"}
2016-03-21 20:23:20,002 INFO fa.replayserver FA disconnected locally.
2016-03-21 20:23:20,003 INFO fa.replayserver Found Complete Replay Info
2016-03-21 20:23:20,003 INFO fa.replayserver Writing local replay as C:\ProgramData\FAForever\replays\4566837-BRNKoINSANITY.fafreplay, containing 557514 bytes of replay data.
2016-03-21 20:23:20,016 INFO fa.replayserver closing replay file
2016-03-21 20:23:20,566 INFO client._clientwindow FA has finished with exit code: 0
2016-03-21 20:23:20,566 INFO fa.game_session.GameSession Game has exited with status code: 0
2016-03-21 20:23:20,566 INFO fa.game_session.GameSession Outgoing relay message GameState ['Ended']
2016-03-21 20:23:20,566 INFO client._clientwindow Outgoing JSON Message: {"args": ["Ended"], "command": "GameState", "target": "game"}
2016-03-21 20:28:41,572 ERROR fa.maps Web preview download failed for bloodrock canyon
2016-03-21 20:28:41,576 ERROR fa.maps Web Preview not found for: bloodrock canyon
2016-03-21 20:30:40,621 ERROR fa.maps Web preview download failed for radna
2016-03-21 20:30:40,621 ERROR fa.maps Web Preview not found for: radna
2016-03-21 20:30:50,713 ERROR fa.maps Web preview download failed for bloodrock canyon
2016-03-21 20:30:50,713 ERROR fa.maps Web Preview not found for: bloodrock canyon
2016-03-21 20:33:08,453 ERROR fa.maps Web preview download failed for bloodrock canyon
2016-03-21 20:33:08,453 ERROR fa.maps Web Preview not found for: bloodrock canyon
2016-03-21 20:34:06,733 INFO fa.check Checking FA for: faf and map dual_arches.v0005
2016-03-21 20:34:06,733 INFO fa.check Version unknown, assuming latest
2016-03-21 20:34:06,733 INFO fa.check Updating FA for mod: faf, version None
2016-03-21 20:34:06,733 INFO fa.check Writing fa_path.lua config file.
2016-03-21 20:34:07,947 INFO fa.check Updating FA for map: dual_arches.v0005
2016-03-21 20:34:19,164 INFO client._clientwindow Outgoing JSON Message: {"relay_address": ["37.58.123.3", 53684], "command": "game_join", "uid": 4566898, "gameport": 6112}
2016-03-21 20:34:19,765 INFO client._clientwindow Handling game_launch via JSON {u'args': [u'/numgames 2422'], u'command': u'game_launch', u'uid': 4566898, u'mod': u'faf'}
2016-03-21 20:34:19,765 INFO fa.upnp UPnP mapping 192.168.44.11:6112
2016-03-21 20:34:19,766 ERROR fa.upnp Couldn't get StaticPortMappingCollection
2016-03-21 20:34:19,766 INFO fa.play Play received arguments: ['/mean', '1596.84', '/deviation', '61.2657', '/country ', u'US', '/clan', u'VoR']
2016-03-21 20:34:19,766 INFO fa.game_process Running FA with info: {'recorder': u'BRNKoINSANITY', 'featured_mod': u'faf', 'uid': 4566898, 'launched_at': 1458606859.767}
2016-03-21 20:34:19,766 INFO fa.game_process Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAlliance.exe" /mean 1596.84 /deviation 61.2657 /country US /clan VoR /init init_faf.lua /numgames 2422 /log "C:\ProgramData\FAForever\logs\game.log" /nobugreport /savereplay "gpgnet://localhost/4566898/BRNKoINSANITY.SCFAreplay" /gpgnet 127.0.0.1:53112
2016-03-21 20:34:19,766 INFO fa.game_process Running FA via executable: C:\ProgramData\FAForever\bin\ForgedAlliance.exe
2016-03-21 20:34:19,792 INFO client._clientwindow FA has launched in an attached process.
2016-03-21 20:34:19,792 INFO fa.game_session.GameSession Game has started
2016-03-21 20:34:24,430 INFO fa.game_session.GameSession Game connected through GPGNet
2016-03-21 20:34:24,431 INFO fa.game_connection.GPGNetConnection GC >> : GameState : [u'Idle']
2016-03-21 20:34:24,431 INFO fa.game_session.GameSession Incoming GPGNet: GameState [u'Idle']
2016-03-21 20:34:24,431 INFO fa.game_connection.GPGNetConnection GC<<: CreateLobby:(0, 6113, u'BRNKoINSANITY', 30361, 1)
2016-03-21 20:34:24,431 INFO fa.game_session.GameSession Outgoing relay message GameState [u'Idle']
2016-03-21 20:34:24,431 INFO client._clientwindow Outgoing JSON Message: {"args": ["Idle"], "command": "GameState", "target": "game"}
2016-03-21 20:34:24,594 INFO fa.game_connection.GPGNetConnection GC >> : GameState : [u'Lobby']
2016-03-21 20:34:24,594 INFO fa.game_session.GameSession Incoming GPGNet: GameState [u'Lobby']
2016-03-21 20:34:24,594 INFO fa.game_session.GameSession Outgoing relay message GameState [u'Lobby']
2016-03-21 20:34:24,594 INFO client._clientwindow Outgoing JSON Message: {"args": ["Lobby"], "command": "GameState", "target": "game"}
2016-03-21 20:34:28,743 INFO connectivity.turn Permitting sends from [u'94.213.85.59', 62998]
2016-03-21 20:37:11,706 INFO client._clientwindow FA has finished with exit code: 0
2016-03-21 20:37:11,707 INFO fa.game_session.GameSession Game has exited with status code: 0
2016-03-21 20:37:11,707 INFO fa.game_session.GameSession Outgoing relay message GameState ['Ended']
2016-03-21 20:37:11,707 INFO client._clientwindow Outgoing JSON Message: {"args": ["Ended"], "command": "GameState", "target": "game"}
2016-03-21 20:45:40,665 INFO client._clientwindow Outgoing JSON Message: {"command": "coop_list"}
2016-03-21 21:09:08,259 INFO fa.game_process Game Info Complete: {u'featured_mod_versions': {u'11': 3650, u'13': 3650, u'12': 3650, u'15': 3650, u'14': 3650, u'17': 3650, u'19': 3650, u'18': 3650, u'22': 3650, u'20': 3650, u'1': 3650, u'3': 3634, u'2': 3640, u'5': 1, u'4': 1, u'6': 1, u'9': 1, u'8': 1, u'21': 3650}, u'num_players': 9, 'uid': 4566898, u'sim_mods': {}, u'max_players': 8, u'password_protected': False, u'title': u'1k+', u'game_type': 0, u'host': u'AchievedJaguar8', u'visibility': None, u'teams': {u'3': [u'AchievedJaguar8', u'Crehture', u'Bone_Collector', u'Executioner'], u'2': [u'Sky_Law', u'ROBO-dani', u'teslaaaa', u'Lolly_Pop'], u'-1': [u'booty8787']}, u'mapname': u'gap of rohan.v0001', u'state': u'playing', u'command': u'game_info', 'featured_mod': u'faf', u'map_file_path': u'maps/gap of rohan.v0001.zip', 'recorder': u'BRNKoINSANITY', 'launched_at': 1458608952.2295218, 'complete': True}
2016-03-21 21:11:37,305 INFO fa.check Checking FA for: faf and map scmp_009
2016-03-21 21:11:37,305 INFO fa.check Version unknown, assuming latest
2016-03-21 21:11:37,305 INFO fa.check Updating FA for mod: faf, version None
2016-03-21 21:11:37,306 INFO fa.check Writing fa_path.lua config file.
2016-03-21 21:11:38,426 INFO fa.check Updating FA for map: scmp_009
2016-03-21 21:11:38,426 INFO fa.check Map is available.
2016-03-21 21:11:38,427 INFO client._clientwindow Outgoing JSON Message: {"relay_address": ["37.58.123.3", 53684], "command": "game_join", "uid": 4566998, "gameport": 6112}
2016-03-21 21:11:38,568 INFO client._clientwindow Handling game_launch via JSON {u'args': [u'/numgames 2422'], u'command': u'game_launch', u'uid': 4566998, u'mod': u'faf'}
2016-03-21 21:11:38,568 INFO fa.upnp UPnP mapping 192.168.44.11:6112
2016-03-21 21:11:38,569 ERROR fa.upnp Couldn't get StaticPortMappingCollection
BRNKoINSANITY
Evaluator
 
Posts: 951
Joined: 09 Oct 2012, 01:14
Has liked: 43 times
Been liked: 207 times
FAF User Name: BRNKoINSANITY
Top

Re: Disconnect from over 50% of team games

Postby SpdyGonzalez » 22 Mar 2016, 18:22

we did some troubleshooting in chat, and Brink requested another port forward configuration to his ISP.
he will do a couple of days of testing
SpdyGonzalez
Avatar-of-War
 
Posts: 299
Joined: 29 Apr 2015, 20:48
Has liked: 1 time
Been liked: 19 times
FAF User Name: BugsBunny
Top
Post a reply

Return to Tech Support

Who is online

Users browsing this forum: No registered users and 1 guest