New to FAF, attempting to join any game, have been unable too. Following is the Log provided by ICE.
I have attempted:
- Uninstall/Reinstall FAF
- Uninstall/Reinstall JAVA
- Uninstall/Reinstall Supreme
- VPN (Nord)
- Disable Firewall (MalwareBytes/Windows Defender)
- Disable Anti-Virus (^)
- C:\ProgramData\FAForever open access for everyone, or try give yourself full access with subfolders
- Disable IPv6
- Tried another FAF client.
Judging by the wording, im guessing it has something to do with a closed port "Socket closed"
Any advice would be greatly appreciated. Vanialla Supreme runs multiplayer fine.
All logs partially REDACTED to try and remove all identifiable information.
2022-10-15 19:41:32.697 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) REDACTED (com.faforever.iceadapter.ice.Peer:84)
java.net.SocketException: Socket closed
at java.base/sun.nio.ch.DatagramSocketAdaptor.receive(DatagramSocketAdaptor.java:252)
at java.base/java.net.DatagramSocket.receive(DatagramSocket.java:700)
at com.faforever.iceadapter.ice.Peer.faListener(Peer.java:81)
at java.base/java.lang.Thread.run(Thread.java:833)
Caused by: java.nio.channels.AsynchronousCloseException: null
at java.base/sun.nio.ch.DatagramChannelImpl.endRead(DatagramChannelImpl.java:523)
at java.base/sun.nio.ch.DatagramChannelImpl.trustedBlockingReceive(DatagramChannelImpl.java:677)
at java.base/sun.nio.ch.DatagramChannelImpl.blockingReceive(DatagramChannelImpl.java:635)
at java.base/sun.nio.ch.DatagramSocketAdaptor.receive(DatagramSocketAdaptor.java:240)
... 3 common frames omitted
2022-10-15 19:41:32.697 WARN Close requested, stopping... (c.f.iceadapter.rpc.RPCHandler:130)
2022-10-15 19:41:32.698 INFO close() - stopping the adapter (com.faforever.iceadapter.IceAdapter:142)
2022-10-15 19:41:32.699 DEBUG No longer listening for GPGPNET from FA (c.f.iceadapter.gpgnet.GPGNetServer:163)
2022-10-15 19:41:32.699 INFO GPGNetServer stopped (c.f.iceadapter.gpgnet.GPGNetServer:262)
2022-10-15 19:41:32.776 ERROR ICE REDACTED: Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:131)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(CompletableFuture.java:2478)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:118)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Thread.java:833)
game.log
info: GPGNET: setting nat handler to 0x11189004
info: LOBBY: Game port 40568[UDP] opened.
info: LOBBY: starting with local uid of 460451 [**REDACTED**]
info: GPGNET: entering lobby state.
info: NET: using deflate compression for sends to %CPU_NAME%:53820.
info: LOBBY: Connecting to host "**REDACTED**" [%CPU_NAME%:53820, uid=348824]
info: Can't add chat text -- no chat display
info: text=Connecting to game host...
info: DisconnectFromPeer (uid=459054)
info: LOBBY: deleting unknown peer uid 459054.
info: ConnectToPeer (name=**REDACTED**, uid=459054, address=127.0.0.1:53822, USE PROXY)
info: NET: using deflate compression for sends to %CPU_NAME%:53822.
info: LOBBY: Adding peer "**REDACTED**" [%CPU_NAME%:53822, uid=459054]
info: Can't add chat text -- no chat display
info: text=Connecting to **REDACTED**...
info: DisconnectFromPeer (uid=456322)
info: LOBBY: deleting unknown peer uid 456322.
info: ConnectToPeer (name=**REDACTED**, uid=456322, address=127.0.0.1:53823, USE PROXY)
info: NET: using deflate compression for sends to %CPU_NAME%:53823.
info: LOBBY: Adding peer "**REDACTED**" [%CPU_NAME%:53823, uid=456322]
info: Can't add chat text -- no chat display
info: text=Connecting to **REDACTED**...
info: GPGNET: setting nat handler to 0x00000000
info: CNetTCPBuf::Read(): recv() failed: WSAEINTR
info: Run time: 0h00m36s
client.log
2022-10-15 19:40:53.530 WARN 6972 --- [ForkJoinPool.commonPool-worker-5] c.f.client.fa.relay.ice.IceAdapterImpl : Could not connect to ICE adapter (attempt 1/50)
2022-10-15 19:40:53.781 WARN 6972 --- [ForkJoinPool.commonPool-worker-5] c.f.client.fa.relay.ice.IceAdapterImpl : Could not connect to ICE adapter (attempt 2/50)
2022-10-15 19:40:54.033 WARN 6972 --- [ForkJoinPool.commonPool-worker-5] c.f.client.fa.relay.ice.IceAdapterImpl : Could not connect to ICE adapter (attempt 3/50)
2022-10-15 19:40:56.933 INFO 6972 --- [reactor-http-nio-1] c.f.client.fa.ForgedAllianceService : Starting Forged Alliance with command: [C:\ProgramData\FAForever\bin\ForgedAlliance.exe, /init, init.lua, /nobugreport, /log, %USER_PROFILE%\AppData\Roaming\Forged Alliance Forever\logs\game_18286894.log, /gpgnet, 127.0.0.1:52543, /mean, 1500.0, /deviation, 500.0, /savereplay, gpgnet://127.0.0.1:1336/18286894/Ironnhawk.SCFAreplay, /country, US, /numgames, 0, /numgames, 0] in directory: C:\ProgramData\FAForever\bin
2022-10-15 19:41:03.490 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE adapter connection state changed to: Connected
2022-10-15 19:41:03.501 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : Message from game: 'GameState' '["Idle"]'
2022-10-15 19:41:03.790 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : Message from game: 'GameState' '["Lobby"]'
2022-10-15 19:41:03.984 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '459054' changed to: gathering
2022-10-15 19:41:03.993 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '456322' changed to: gathering
2022-10-15 19:41:04.040 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : Message from game: 'Disconnected' '["459054"]'
2022-10-15 19:41:04.041 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : Message from game: 'Disconnected' '["456322"]'
2022-10-15 19:41:04.667 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: gathering
2022-10-15 19:41:09.039 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '456322' changed to: disconnected
2022-10-15 19:41:09.041 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '459054' changed to: disconnected
2022-10-15 19:41:09.675 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: checking
2022-10-15 19:41:09.682 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: disconnected
2022-10-15 19:41:14.056 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '459054' changed to: gathering
2022-10-15 19:41:14.057 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '456322' changed to: gathering
2022-10-15 19:41:16.225 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: gathering
2022-10-15 19:41:19.064 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '459054' changed to: disconnected
2022-10-15 19:41:19.074 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '456322' changed to: disconnected
2022-10-15 19:41:21.237 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: checking
2022-10-15 19:41:21.238 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: disconnected
2022-10-15 19:41:24.071 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '459054' changed to: gathering
2022-10-15 19:41:24.094 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '456322' changed to: gathering
2022-10-15 19:41:27.753 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: gathering
2022-10-15 19:41:29.084 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '459054' changed to: disconnected
2022-10-15 19:41:29.112 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '456322' changed to: disconnected
2022-10-15 19:41:32.696 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE adapter connection state changed to: Disconnected
2022-10-15 19:41:32.777 DEBUG 6972 --- [JJsonPeer] c.f.c.fa.relay.ice.IceAdapterCallbacks : ICE connection state for peer '348824' changed to: checking
2022-10-15 19:41:33.179 INFO 6972 --- [ForkJoinPool.commonPool-worker-5] com.faforever.client.game.GameService : Forged Alliance terminated with exit code 0
2022-10-15 19:41:33.186 INFO 6972 --- [ForkJoinPool.commonPool-worker-5] c.f.client.fa.relay.ice.IceAdapterImpl : Ignoring call to ICE adapter as we are not connected: quit([])
2022-10-15 19:41:33.250 INFO 6972 --- [ForkJoinPool.commonPool-worker-5] c.f.client.fa.relay.ice.IceAdapterImpl : ICE adapter terminated normally