Howdy,
My ranked games are frequently and usually getting stuck on the "setting up automatch" screen. Here are logs of an attempt with UPnP turned OFF:
2012-12-11 01:23:48,543 INFO faf.games Starting Ranked Search as /uef, port: 6112
2012-12-11 01:23:48,545 INFO faf.client Outgoing JSON Message: {"state": "start", "mod": "ladder1v1", "command": "game_matchmaking", "faction": "/uef", "gameport": 6112}
2012-12-11 01:23:58,549 DEBUG faf.games Expanding search to 0.05
2012-12-11 01:23:58,549 INFO faf.client Outgoing JSON Message: {"rate": 0.05, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2012-12-11 01:24:08,551 DEBUG faf.games Expanding search to 0.1
2012-12-11 01:24:08,551 INFO faf.client Outgoing JSON Message: {"rate": 0.1, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2012-12-11 01:24:17,548 DEBUG faf.games Stopping Ranked Search
2012-12-11 01:24:17,548 INFO faf.client Outgoing JSON Message: {"state": "stop", "command": "game_matchmaking", "mod": "ladder1v1"}
2012-12-11 01:24:34,759 INFO faf.fa Checking FA for: ladder1v1 and map None
2012-12-11 01:24:34,759 INFO faf.fa Updating FA for mod: ladder1v1, version None
2012-12-11 01:24:36,151 INFO faf.fa Writing fa_path.lua config file.
2012-12-11 01:24:36,173 INFO faf.games Starting Ranked Search as /uef, port: 6112
2012-12-11 01:24:36,173 INFO faf.client Outgoing JSON Message: {"state": "start", "mod": "ladder1v1", "command": "game_matchmaking", "faction": "/uef", "gameport": 6112}
2012-12-11 01:24:46,177 DEBUG faf.games Expanding search to 0.05
2012-12-11 01:24:46,177 INFO faf.client Outgoing JSON Message: {"rate": 0.05, "command": "game_matchmaking", "state": "expand", "mod": "ladder1v1"}
2012-12-11 01:24:51,145 INFO faf.client Handling game_launch via JSON {u'uid': 478138, u'args': [u'/players 2', u'/team 2'], u'mapname': u'dry canyon v3', u'reason': u'ranked', u'command': u'game_launch', u'mod': u'ladder1v1'}
2012-12-11 01:24:51,145 INFO faf.fa Updating FA for map: dry canyon v3
2012-12-11 01:24:51,147 INFO faf.fa Map is available.
2012-12-11 01:24:51,148 INFO faf.fa Running FA with info: {'recorder': u'Squirrels', 'game_time': 1355214291.147, 'uid': 478138, 'version_info': {'lobby': '0.7.586'}, 'featured_mod': u'ladder1v1'}
2012-12-11 01:24:51,148 INFO faf.fa Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAlliance.exe" /players 2 /team 2 /uef /mean 1359.64 /deviation 217.474 /init init_ladder1v1.lua /savereplay gpgnet://localhost/478138/Squirrels.SCFAreplay /nobugreport /gpgnet 127.0.0.1:57784
2012-12-11 01:24:51,171 INFO faf.client FA has launched in an attached process.
2012-12-11 01:24:51,171 DEBUG faf.games Stopping Ranked Search
2012-12-11 01:24:51,171 INFO faf.client Outgoing JSON Message: {"state": "stop", "command": "game_matchmaking", "mod": "ladder1v1"}
2012-12-11 01:24:59,430 DEBUG faf.fa.relayserver incoming connection to relay server...
2012-12-11 01:24:59,430 INFO faf.fa.relayer FA connected locally.
2012-12-11 01:24:59,430 INFO faf.fa.relayer FA will use local port 51036
2012-12-11 01:24:59,430 INFO faf.fa.relayer UDP relay port 6112
2012-12-11 01:24:59,588 DEBUG faf.fa.relayer faf server faforever.com:7001
2012-12-11 01:25:00,322 INFO faf.client port used : 60434
2012-12-11 01:30:05,773 INFO faf.fa.relayer FA disconnected locally.
2012-12-11 01:30:05,773 INFO faf.fa.relayer remove relay
2012-12-11 01:30:05,773 DEBUG faf.fa.relayer destructor called
2012-12-11 01:30:06,446 INFO faf.client FA has finished with exit code: 0
Here is an attempt with UPnP turned on:
2012-12-11 01:39:45,091 INFO faf.client FA has finished with exit code: 0
2012-12-11 01:40:03,464 INFO faf.fa Checking FA for: ladder1v1 and map None
2012-12-11 01:40:03,466 INFO faf.fa Updating FA for mod: ladder1v1, version None
2012-12-11 01:40:04,959 INFO faf.fa Writing fa_path.lua config file.
2012-12-11 01:40:04,992 INFO faf.fa.upnp Creating UPnP port mappings...
2012-12-11 01:40:05,614 ERROR faf.fa.upnp Exception in UPnP createPortMapping.
Traceback (most recent call last):
File "fa\upnp.pyc", line 36, in createPortMapping
File "<COMObject <unknown>>", line 4, in Add
com_error: (-2147352567, 'Exception occurred.', (0, None, None, None, 0, -2147024726), None)
2012-12-11 01:40:05,615 INFO faf.games Starting Ranked Search as /uef, port: 6112
2012-12-11 01:40:05,615 INFO faf.client Outgoing JSON Message: {"state": "start", "mod": "ladder1v1", "command": "game_matchmaking", "faction": "/uef", "gameport": 6112}
2012-12-11 01:40:05,881 INFO faf.client Handling game_launch via JSON {u'uid': 478149, u'args': [u'/players 2', u'/team 1'], u'mapname': u'red rocks', u'reason': u'ranked', u'command': u'game_launch', u'mod': u'ladder1v1'}
2012-12-11 01:40:05,882 INFO faf.fa Updating FA for map: red rocks
2012-12-11 01:40:05,884 INFO faf.fa Map is available.
2012-12-11 01:40:05,884 INFO faf.fa.upnp Creating UPnP port mappings...
2012-12-11 01:40:06,624 ERROR faf.fa.upnp Exception in UPnP createPortMapping.
Traceback (most recent call last):
File "fa\upnp.pyc", line 36, in createPortMapping
File "<COMObject <unknown>>", line 4, in Add
com_error: (-2147352567, 'Exception occurred.', (0, None, None, None, 0, -2147024726), None)
2012-12-11 01:40:06,624 INFO faf.fa Running FA with info: {'recorder': u'Squirrels', 'game_time': 1355215206.624, 'uid': 478149, 'version_info': {'lobby': '0.7.586'}, 'featured_mod': u'ladder1v1'}
2012-12-11 01:40:06,625 INFO faf.fa Running FA via command: "C:\ProgramData\FAForever\bin\ForgedAlliance.exe" /players 2 /team 1 /uef /mean 1359.64 /deviation 217.474 /init init_ladder1v1.lua /savereplay gpgnet://localhost/478149/Squirrels.SCFAreplay /nobugreport /gpgnet 127.0.0.1:57784
2012-12-11 01:40:06,648 INFO faf.client FA has launched in an attached process.
2012-12-11 01:40:06,648 DEBUG faf.games Stopping Ranked Search
2012-12-11 01:40:06,648 INFO faf.client Outgoing JSON Message: {"state": "stop", "command": "game_matchmaking", "mod": "ladder1v1"}
2012-12-11 01:40:15,062 DEBUG faf.fa.relayserver incoming connection to relay server...
2012-12-11 01:40:15,062 INFO faf.fa.relayer FA connected locally.
2012-12-11 01:40:15,062 INFO faf.fa.relayer FA will use local port 50986
2012-12-11 01:40:15,062 INFO faf.fa.relayer UDP relay port 6112
2012-12-11 01:40:15,220 DEBUG faf.fa.relayer faf server faforever.com:7001
2012-12-11 01:40:15,963 INFO faf.client port used : 60496