Gathering timeout. Windows 10. [SOLVED]

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

Moderator: PhilipJFry

Gathering timeout. Windows 10.

Postby SPL » 20 Nov 2019, 16:53

Hello there!
3 days ago I upgraded my win7 to win10. When I had used win7 everything worked just perfect.
However when i installed win10 the things were completely fucked up.
I cant get connection with any lobby in FAF.
ICE shows infinte gathering->disconnected loop.
Chat and lobby page work good.

Some people in discord adviced me disabling all the NICs i dont use. Well, it didnt work.

Here's some ICE logs:

2019-11-19 18:42:36.199 INFO Version: SNAPSHOT (com.faforever.iceadapter.IceAdapter:52)
2019-11-19 18:42:36.205 INFO Using GPGNET_PORT: 25026 (c.f.iceadapter.gpgnet.GPGNetServer:38)
2019-11-19 18:42:36.210 INFO Generated LOBBY_PORT: 46344 (c.f.iceadapter.gpgnet.GPGNetServer:43)
2019-11-19 18:42:36.212 INFO GPGNetServer started (c.f.iceadapter.gpgnet.GPGNetServer:56)
2019-11-19 18:42:36.240 INFO Creating RPC server on port 57749 (c.f.iceadapter.rpc.RPCService:32)
2019-11-19 18:42:36.377 DEBUG LobbyInitMode set to normal (c.f.iceadapter.rpc.RPCHandler:53)
2019-11-19 18:42:36.565 INFO Ice Servers set, total addresses: 3 (c.f.iceadapter.ice.GameSession:122)
2019-11-19 18:42:38.843 DEBUG Listening for GPG messages (c.f.iceadapter.gpgnet.GPGNetServer:144)
2019-11-19 18:42:38.844 INFO GPGNetClient has connected (c.f.iceadapter.gpgnet.GPGNetServer:85)
2019-11-19 18:42:38.848 DEBUG New GameState: Idle (c.f.iceadapter.gpgnet.GPGNetServer:96)
2019-11-19 18:42:38.849 INFO Sent GPGNet message: CreateLobby 0 46344 SPL 182979 1 (c.f.iceadapter.gpgnet.GPGNetServer:133)
2019-11-19 18:42:44.711 INFO Created debug window. (c.f.iceadapter.debug.DebugWindow:78)
2019-11-19 18:42:44.712 INFO Received GPGNet message: GameState Idle (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-11-19 18:42:44.713 DEBUG New GameState: Lobby (c.f.iceadapter.gpgnet.GPGNetServer:96)
2019-11-19 18:42:44.713 INFO Received GPGNet message: GameState Lobby (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-11-19 18:42:45.008 INFO onJoinGame 314619 Lysergide (com.faforever.iceadapter.IceAdapter:72)
2019-11-19 18:42:45.010 DEBUG Peer created: 314619, Lysergide, localOffer: false (com.faforever.iceadapter.ice.Peer:32)
2019-11-19 18:42:45.011 DEBUG Now forwarding data to peer Lysergide(314619) (com.faforever.iceadapter.ice.Peer:53)
2019-11-19 18:42:45.014 INFO Sent GPGNet message: JoinGame 127.0.0.1:58367 Lysergide 314619 (c.f.iceadapter.gpgnet.GPGNetServer:133)
2019-11-19 18:42:46.896 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:42:46.896 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:42:46.899 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:42:47.423 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:42:52.432 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:42:57.413 INFO Created info window. (c.f.iceadapter.debug.InfoWindow:55)
2019-11-19 18:42:58.688 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:42:58.689 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:42:58.690 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:42:58.691 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:43:03.692 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:43:10.428 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:43:10.428 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:43:10.429 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:43:10.430 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:43:15.431 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:43:15.433 DEBUG ICE Lysergide(314619): Starting ICE for peer 314619 (c.f.iceadapter.ice.PeerIceModule:202)
2019-11-19 18:43:22.212 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:43:22.212 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:43:22.213 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:43:22.215 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:43:27.216 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:43:27.218 DEBUG ICE Lysergide(314619): Starting ICE for peer 314619 (c.f.iceadapter.ice.PeerIceModule:202)
2019-11-19 18:43:34.008 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:43:34.008 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:43:34.009 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:43:34.010 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:43:39.011 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:43:39.012 DEBUG ICE Lysergide(314619): Starting ICE for peer 314619 (c.f.iceadapter.ice.PeerIceModule:202)
2019-11-19 18:43:45.784 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:43:45.784 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:43:45.785 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:43:45.786 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:43:50.788 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:43:50.790 DEBUG ICE Lysergide(314619): Starting ICE for peer 314619 (c.f.iceadapter.ice.PeerIceModule:202)
2019-11-19 18:43:57.484 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:43:57.484 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:43:57.485 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:43:57.486 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:44:02.487 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:44:02.489 DEBUG ICE Lysergide(314619): Starting ICE for peer 314619 (c.f.iceadapter.ice.PeerIceModule:202)
2019-11-19 18:44:09.137 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:44:09.137 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:44:09.138 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:44:09.139 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:44:14.139 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:44:20.808 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:44:20.808 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:44:20.809 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:44:20.810 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:44:25.811 ERROR ICE Lysergide(314619): Gathering candidates timed out (c.f.iceadapter.ice.PeerIceModule:126)
java.util.concurrent.CancellationException: null
at java.base/java.util.concurrent.CompletableFuture.cancel(Unknown Source)
at com.faforever.iceadapter.ice.PeerIceModule.lambda$gatherCandidates$4(PeerIceModule.java:113)
at com.faforever.iceadapter.util.Executor.lambda$executeDelayed$0(Executor.java:16)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:44:25.813 DEBUG ICE Lysergide(314619): Starting ICE for peer 314619 (c.f.iceadapter.ice.PeerIceModule:202)
2019-11-19 18:44:32.512 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-11-19 18:44:32.512 DEBUG ICE Lysergide(314619): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-11-19 18:44:32.513 INFO ICE Lysergide(314619): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-11-19 18:44:32.514 INFO ICE Lysergide(314619): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-11-19 18:44:37.012 ERROR Error while communicating with FA (input), assuming shutdown (c.f.iceadapter.gpgnet.GPGNetServer:160)
java.io.EOFException: null
at com.google.common.io.LittleEndianDataInputStream.readAndCheckByte(LittleEndianDataInputStream.java:226)
at com.google.common.io.LittleEndianDataInputStream.readInt(LittleEndianDataInputStream.java:116)
at com.faforever.iceadapter.gpgnet.FaDataInputStream.readInt(FaDataInputStream.java:56)
at com.faforever.iceadapter.gpgnet.FaDataInputStream.readString(FaDataInputStream.java:65)
at com.faforever.iceadapter.gpgnet.GPGNetServer$GPGNetClient.listenerThread(GPGNetServer.java:150)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:44:37.012 INFO GPGNet connection lost (c.f.iceadapter.gpgnet.GPGNetServer:186)
2019-11-19 18:44:37.013 DEBUG Closing GPGNetClient (c.f.iceadapter.gpgnet.GPGNetServer:169)
2019-11-19 18:44:37.013 INFO FA SHUTDOWN, closing everything (com.faforever.iceadapter.IceAdapter:125)
2019-11-19 18:44:37.014 WARN Close requested, stopping... (c.f.iceadapter.rpc.RPCHandler:130)
2019-11-19 18:44:37.014 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) Lysergide(314619) (com.faforever.iceadapter.ice.Peer:84)
java.net.SocketException: socket closed
at java.base/java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
at java.base/java.net.DualStackPlainDatagramSocketImpl.receive0(Unknown Source)
at java.base/java.net.AbstractPlainDatagramSocketImpl.receive(Unknown Source)
at java.base/java.net.DatagramSocket.receive(Unknown Source)
at com.faforever.iceadapter.ice.Peer.faListener(Peer.java:81)
at java.base/java.lang.Thread.run(Unknown Source)
2019-11-19 18:44:37.014 INFO close() - stopping the adapter (com.faforever.iceadapter.IceAdapter:136)
2019-11-19 18:44:37.017 DEBUG No longer listening for GPGPNET from FA (c.f.iceadapter.gpgnet.GPGNetServer:163)
2019-11-19 18:44:37.017 INFO GPGNetServer stopped (c.f.iceadapter.gpgnet.GPGNetServer:262)
Nᴏᴡ sᴀʏ ᴛʜᴇ ᴡᴏʀᴅ.
User avatar
SPL
Crusader
 
Posts: 22
Joined: 21 Sep 2019, 14:00
Has liked: 2 times
Been liked: 2 times
FAF User Name: SPL

Re: Gathering timeout. Windows 10.

Postby Geosearchef » 20 Nov 2019, 18:33

For some reason the ICE library I used has problems with some adapters when gathering candidates for the connectivity establishment. This causes the gathering to take forever (which is then stopped by me timing it out after 5 seconds). I suggest disabling all unneeded virtual network adapters. If that doesn't help, try to switch your network setup. Try to switch your operating system. (preferrably not windows)

If you want to investigate this yourself, it happens when the ice library starts gathering candidates: https://github.com/FAForever/java-ice-a ... .java#L106
Developer, Server Admin, ICE, currently working on Team Matchmaking, FAF Client
User avatar
Geosearchef
Contributor
 
Posts: 392
Joined: 18 Oct 2013, 14:08
Location: Germany
Has liked: 6 times
Been liked: 127 times
FAF User Name: Geosearchef

Re: Gathering timeout. Windows 10.

Postby SPL » 20 Nov 2019, 20:38

Geosearchef wrote:For some reason the ICE library I used has problems with some adapters when gathering candidates for the connectivity establishment. This causes the gathering to take forever (which is then stopped by me timing it out after 5 seconds). I suggest disabling all unneeded virtual network adapters. If that doesn't help, try to switch your network setup. Try to switch your operating system. (preferrably not windows)

If you want to investigate this yourself, it happens when the ice library starts gathering candidates: https://github.com/FAForever/java-ice-a ... .java#L106


Well, im not into programming. And this page doesnt tell me much u know :D
And Im not gonna change my OS only because of SupCom, lol.
The most part of the FAF community uses win10 without any issues. Any other games and soft work just fine for me.
I just cant get whats the exact reason causes this issue. I use the same internet provider, the same connection type, the same router. Nothing has changed except my platform AM4 (actually that's the main reason I installed win10 deepshit, coz win7 seemed like it didnt want to work with am4)
Nᴏᴡ sᴀʏ ᴛʜᴇ ᴡᴏʀᴅ.
User avatar
SPL
Crusader
 
Posts: 22
Joined: 21 Sep 2019, 14:00
Has liked: 2 times
Been liked: 2 times
FAF User Name: SPL

Re: Gathering timeout. Windows 10.

Postby SPL » 21 Nov 2019, 18:41

Bump?
Nᴏᴡ sᴀʏ ᴛʜᴇ ᴡᴏʀᴅ.
User avatar
SPL
Crusader
 
Posts: 22
Joined: 21 Sep 2019, 14:00
Has liked: 2 times
Been liked: 2 times
FAF User Name: SPL

Re: Gathering timeout. Windows 10.

Postby Geosearchef » 21 Nov 2019, 19:20

I just cant get whats the exact reason causes this issue.

Neither can I. I am aware of this issue. I can build you an ice adapter that doesn't time out when I find time, but that'll mean you'll spend 60 secs+ to connect (if it even works).
But unless you have a hundred hours of free time lying around somewhere, I'm not able to or going to debug ice4j.

The only thing I can think of is switch your network configuration (use a different network card, play around with the configuration, ...) or switch your operating system (and if you reinstall win 10, that might work aswell, I don't know what causes it).
Developer, Server Admin, ICE, currently working on Team Matchmaking, FAF Client
User avatar
Geosearchef
Contributor
 
Posts: 392
Joined: 18 Oct 2013, 14:08
Location: Germany
Has liked: 6 times
Been liked: 127 times
FAF User Name: Geosearchef

Re: Gathering timeout. Windows 10.  [SOLVED]

Postby SPL » 23 Nov 2019, 00:03

I've just installed win7 with integrated chipset drivers, so everything works just perfect now.
Thanks for wasting ur time :D
Problem solved now.
Nᴏᴡ sᴀʏ ᴛʜᴇ ᴡᴏʀᴅ.
User avatar
SPL
Crusader
 
Posts: 22
Joined: 21 Sep 2019, 14:00
Has liked: 2 times
Been liked: 2 times
FAF User Name: SPL


Return to Tech Support

Who is online

Users browsing this forum: No registered users and 1 guest