Hello
I'm a bit struggling with faf lately, the game intermittently desyncs when playing bigger games (i.e. setons ). I' trying to diagnose this issue but so far to no avail.
- Ports are open on router
- PC firewall is OFF (router is ON)
- generally i can connect but games desync and its always me that's behind, as said not all games but a lot. Enough that ppl try to avoid me.
- 1v1 or smaller (2v2) games always work
- Connection is very fast fiber optic and the issue started some time after java client was forced on
So first can someone explain to me 'ice' adapter debug tables and values meaning ?
This forum is archived and read only.
Please move to the new forum!
- It is currently 07 Apr 2021, 03:14
Forged Alliance Forever
Forged Alliance Forever Forums
Help needed - intermittent desyncs
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
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
12 posts
• Page 1 of 2 • 1, 2
Help needed - intermittent desyncs
by pchelka » 01 Oct 2019, 23:32
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
Re: Help needed - intermittent desyncs
by Geosearchef » 02 Oct 2019, 10:23
- what do you mean by ports are open? Which ports? Also, that isn't necessary anymore.
- Your router has no Firewall, just a NAT, and the ice adapter performs NAT traversal
The ice adapter debug window shows you an entry for each peer the server has told you to connect to and not yet sent you a disconnect command. It shows you FAF player id, player login.
The status:
gathering - your ice module for that peer is currently gathering possible addresses (candidates) at which you can be reached
awaitimgCandidates - you have sent yoir candidates to that peer and are waiting for their candidatds
checking - the adapter is building pairs from both sides' candidates and testing the for connection
Connected - a pair succeeded, was nominated and validated and a connection was established, FA data will be forwarded and the offerer will start a connectivity checker
Disconnected - no candidates have been received, no pair succeded or a connection loss has been detected by the connectivity checker
Once you are connected to a peer, it will show you the candidates of the pair that succeeded. So local candidate and remotr candidate just tells you the type of address used by the other peer to send data to you.
host - an address bound to one of your PC's interfaces, e. g. your local subnet's address
srflx - server reflexive, an address learned by asking a STUN server for the address it sees you at, so just your global ip address, this would still mean a direct connection
prflx - peer reflexive, an address learned from one of the peers you're already connected to
relay - an address borrowed from the FAF TURN server, all your traffic communicating is relayed via that server, this candidate should never fail, a relay candidate on one side doesn"t indicate which side failed as communicating with a relay server (without NAT) is never a problem, therefore it doesn't matter if one or both peers use a relay
The offer entry tells you if you were the one to offer the connection request or answered it (who sent candidates first) and controls the connection, e. g. nominating pairs),this is important as only the offerer will check the connection,so the two values behind only makesense when you are the offerer,you will always be offerer when you are the host of the game.
The two values behind that are not -1 when you are offerer as your adapter is sending echo requests through thr connection and will disconnect and send new candidates when it doesn't get a reply for 10 seconds (allowing reconnects after IP addr changes)
The column behind that,in case you are offerer, shows you the last measured round trip time (RTT/latency), this value won't change if you're not getting any replies though!!!! The next column is updated once a second and shows the milliseconds that passed since the last echo reply arrived, if the connection goes quiet it will count to 10s and then abort and renegotiate the connection.
- Your router has no Firewall, just a NAT, and the ice adapter performs NAT traversal
The ice adapter debug window shows you an entry for each peer the server has told you to connect to and not yet sent you a disconnect command. It shows you FAF player id, player login.
The status:
gathering - your ice module for that peer is currently gathering possible addresses (candidates) at which you can be reached
awaitimgCandidates - you have sent yoir candidates to that peer and are waiting for their candidatds
checking - the adapter is building pairs from both sides' candidates and testing the for connection
Connected - a pair succeeded, was nominated and validated and a connection was established, FA data will be forwarded and the offerer will start a connectivity checker
Disconnected - no candidates have been received, no pair succeded or a connection loss has been detected by the connectivity checker
Once you are connected to a peer, it will show you the candidates of the pair that succeeded. So local candidate and remotr candidate just tells you the type of address used by the other peer to send data to you.
host - an address bound to one of your PC's interfaces, e. g. your local subnet's address
srflx - server reflexive, an address learned by asking a STUN server for the address it sees you at, so just your global ip address, this would still mean a direct connection
prflx - peer reflexive, an address learned from one of the peers you're already connected to
relay - an address borrowed from the FAF TURN server, all your traffic communicating is relayed via that server, this candidate should never fail, a relay candidate on one side doesn"t indicate which side failed as communicating with a relay server (without NAT) is never a problem, therefore it doesn't matter if one or both peers use a relay
The offer entry tells you if you were the one to offer the connection request or answered it (who sent candidates first) and controls the connection, e. g. nominating pairs),this is important as only the offerer will check the connection,so the two values behind only makesense when you are the offerer,you will always be offerer when you are the host of the game.
The two values behind that are not -1 when you are offerer as your adapter is sending echo requests through thr connection and will disconnect and send new candidates when it doesn't get a reply for 10 seconds (allowing reconnects after IP addr changes)
The column behind that,in case you are offerer, shows you the last measured round trip time (RTT/latency), this value won't change if you're not getting any replies though!!!! The next column is updated once a second and shows the milliseconds that passed since the last echo reply arrived, if the connection goes quiet it will count to 10s and then abort and renegotiate the connection.
Developer, Server Admin, ICE, currently working on Team Matchmaking, FAF Client
-
Geosearchef 
- Posts: 392
- Joined: 18 Oct 2013, 14:08
- Location: Germany
- Has liked: 6 times
- Been liked: 127 times
- FAF User Name: Geosearchef
Re: Help needed - intermittent desyncs
by pchelka » 02 Oct 2019, 21:31
Thanks, this will help - ill rechcek how this behaves during one of the frequent desyncs and post a log
As for ports I had 6112 upd open as per some online 'how to' for faf.
As for ports I had 6112 upd open as per some online 'how to' for faf.
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
Re: Help needed - intermittent desyncs
by pchelka » 03 Oct 2019, 22:53
OK i got a log form last game that desynced on start, maybe somone sees anything here but to be honest doesn seem anything is at fault on FAF side, it jsut lost connection and god knows why
- Code: Select all
2019-10-03 22:34:04.422 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:34:04.737 INFO onConnectToPeer 323293 CDR_Shelby, offer: false (com.faforever.iceadapter.IceAdapter:89)
2019-10-03 22:34:04.737 DEBUG Peer created: 323293, CDR_Shelby, localOffer: false (com.faforever.iceadapter.ice.Peer:32)
2019-10-03 22:34:04.738 DEBUG Now forwarding data to peer CDR_Shelby(323293) (com.faforever.iceadapter.ice.Peer:53)
2019-10-03 22:34:04.739 INFO Sent GPGNet message: ConnectToPeer 127.0.0.1:58161 CDR_Shelby 323293 (c.f.iceadapter.gpgnet.GPGNetServer:133)
2019-10-03 22:34:04.751 INFO Received GPGNet message: Disconnected 323293 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:34:05.923 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:34:05.924 DEBUG ICE CDR_Shelby(323293): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-10-03 22:34:05.925 INFO ICE CDR_Shelby(323293): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-10-03 22:34:05.926 INFO ICE CDR_Shelby(323293): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-10-03 22:34:06.082 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:34:06.082 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:34:06.120 DEBUG ICE CDR_Shelby(323293): Sending own candidates to 323293 (c.f.iceadapter.ice.PeerIceModule:134)
2019-10-03 22:34:06.123 DEBUG ICE CDR_Shelby(323293): Starting ICE for peer 323293 (c.f.iceadapter.ice.PeerIceModule:202)
2019-10-03 22:34:06.371 DEBUG ICE CDR_Shelby(323293): ICE terminated (c.f.iceadapter.ice.PeerIceModule:227)
2019-10-03 22:34:06.373 DEBUG ICE CDR_Shelby(323293): Now forwarding data from ICE to FA for peer (c.f.iceadapter.ice.PeerIceModule:351)
2019-10-03 22:34:11.808 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:34:11.808 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:41:46.809 INFO Received GPGNet message: Chat yeah switch to 2v2 map (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:41:47.588 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:41:47.588 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:41:48.905 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:41:48.905 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:41:55.628 INFO Received GPGNet message: Chat no point waisting time (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:41:55.920 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:41:55.920 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:41:57.186 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:41:57.186 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:41:58.074 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:41:58.075 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:00.326 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:00.326 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:00.337 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:00.337 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:03.622 INFO Received GPGNet message: Chat ...... (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:42:07.342 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:07.342 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:08.209 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:08.209 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:08.425 INFO Received GPGNet message: Chat go for twin rivers (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:42:10.145 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:10.145 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:11.158 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:11.159 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:12.421 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:12.421 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:18.790 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:18.790 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:19.437 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:19.437 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:22.213 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:22.213 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:22.262 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:22.262 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:25.147 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:25.147 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:30.447 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:30.447 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:30.687 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:30.688 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:33.374 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:33.374 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:34.378 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:34.378 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:37.644 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:37.644 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:41.787 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:41.787 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:41.921 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:41.921 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:44.480 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:44.480 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:45.589 INFO onDisconnectFromPeer 318122 (com.faforever.iceadapter.IceAdapter:100)
2019-10-03 22:42:45.590 INFO Sent GPGNet message: DisconnectFromPeer 318122 (c.f.iceadapter.gpgnet.GPGNetServer:133)
2019-10-03 22:42:45.604 INFO Received GPGNet message: Disconnected 318122 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:42:46.496 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:46.496 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:49.858 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:49.858 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:53.189 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:53.189 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:53.309 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:53.310 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:55.719 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:55.719 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:42:58.579 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:42:58.579 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:02.159 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:02.159 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:04.418 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:04.418 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:04.801 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:04.801 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:06.704 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:06.704 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:10.695 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:10.695 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:14.491 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:14.491 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:15.693 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:15.694 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:16.300 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:16.300 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:17.817 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:17.817 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:22.848 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:22.848 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:26.733 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:26.733 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:26.948 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:26.948 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:27.836 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:27.836 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:28.928 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:28.928 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:30.782 DEBUG New GameState: Launching (c.f.iceadapter.gpgnet.GPGNetServer:96)
2019-10-03 22:43:30.782 INFO Received GPGNet message: GameState Launching (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:43:34.981 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:34.981 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:38.194 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:38.194 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:38.951 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:38.951 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:39.360 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:39.361 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:40.040 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:40.040 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:47.078 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:47.079 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:49.448 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:49.449 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:50.870 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:50.870 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:51.146 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:51.146 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:51.205 INFO Received GPGNet message: Bottleneck data 6 242113 5301.0 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:43:51.405 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:51.405 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:43:56.247 INFO Received GPGNet message: Bottleneck data 6 242113 10342.6 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:43:59.188 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:43:59.189 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:00.690 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:00.690 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:01.249 INFO Received GPGNet message: Bottleneck data 6 242113 15344.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:02.262 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:02.262 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:02.447 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:02.447 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:03.722 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:03.722 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:06.735 INFO Received GPGNet message: Bottleneck data 6 242113 20830.6 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:11.274 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:11.275 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:11.973 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:11.974 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:12.475 INFO Received GPGNet message: Bottleneck data 6 242113 26570.2 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:13.375 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:13.375 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:13.965 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:13.965 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:16.046 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:16.046 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:17.482 INFO Received GPGNet message: Bottleneck data 6 242113 31577.5 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:22.538 INFO Received GPGNet message: Bottleneck data 6 242113 36633.5 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:23.219 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:23.219 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:23.395 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:23.395 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:24.479 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:24.479 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:25.524 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:25.524 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:27.687 INFO Received GPGNet message: Bottleneck data 6 242113 41782.6 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:28.442 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:28.442 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:33.338 INFO Received GPGNet message: Bottleneck data 6 242113 47433.1 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:34.451 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:34.452 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:35.456 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:35.456 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:35.589 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:35.589 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:37.080 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:37.080 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:38.427 INFO Received GPGNet message: Bottleneck data 6 242113 52522.5 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:40.724 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:40.724 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:43.479 INFO Received GPGNet message: Bottleneck data 6 242113 57574.4 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:45.879 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:45.879 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:46.700 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:46.701 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:47.574 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:47.574 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:48.577 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:48.578 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:49.334 INFO Received GPGNet message: Bottleneck data 6 242113 63429.2 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:53.004 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:53.004 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:55.292 INFO Received GPGNet message: Bottleneck data 6 242113 69387.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:44:55.829 WARN ICE Moskito(291345): ICE connection has been lost for peer (c.f.iceadapter.ice.PeerIceModule:283)
2019-10-03 22:44:55.835 INFO ICE Moskito(291345): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-10-03 22:44:55.836 INFO ICE Moskito(291345): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-10-03 22:44:56.025 DEBUG ICE Moskito(291345): Sending own candidates to 291345 (c.f.iceadapter.ice.PeerIceModule:134)
2019-10-03 22:44:56.925 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:56.925 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:58.055 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:58.055 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:44:59.803 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:44:59.804 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:00.141 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:00.142 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:00.756 INFO Received GPGNet message: Bottleneck data 6 242113 74851.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:45:05.462 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:05.462 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:06.699 INFO Received GPGNet message: Bottleneck data 6 242113 80794.9 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:45:07.033 INFO ICE Moskito(291345): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-10-03 22:45:07.035 INFO ICE Moskito(291345): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-10-03 22:45:07.216 DEBUG ICE Moskito(291345): Sending own candidates to 291345 (c.f.iceadapter.ice.PeerIceModule:134)
2019-10-03 22:45:08.167 ERROR ICE MESSAGE IGNORED for id: 193295 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:08.168 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:08.943 ERROR ICE MESSAGE IGNORED for id: 45214 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:08.943 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:11.638 ERROR ICE MESSAGE IGNORED for id: 156814 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:11.638 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:11.702 INFO Received GPGNet message: Bottleneck data 6 242113 85797.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:45:11.968 ERROR ICE MESSAGE IGNORED for id: 296342 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:11.968 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:16.706 INFO Received GPGNet message: Bottleneck data 6 242113 90801.3 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:45:17.833 ERROR ICE MESSAGE IGNORED for id: 36705 (c.f.iceadapter.rpc.RPCHandler:69)
2019-10-03 22:45:17.834 INFO IceMsg received %s (c.f.iceadapter.rpc.RPCHandler:72)
2019-10-03 22:45:18.224 INFO ICE Moskito(291345): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-10-03 22:45:18.225 INFO ICE Moskito(291345): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-10-03 22:45:18.391 INFO Received GPGNet message: BottleneckCleared (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:45:18.410 INFO Received GPGNet message: GameResult 2 defeat -10 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-03 22:45:18.411 DEBUG ICE Moskito(291345): Sending own candidates to 291345 (c.f.iceadapter.ice.PeerIceModule:134)
2019-10-03 22:45:19.004 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-10-03 22:45:19.004 INFO GPGNet connection lost (c.f.iceadapter.gpgnet.GPGNetServer:186)
2019-10-03 22:45:19.005 DEBUG Closing GPGNetClient (c.f.iceadapter.gpgnet.GPGNetServer:169)
2019-10-03 22:45:19.005 INFO FA SHUTDOWN, closing everything (com.faforever.iceadapter.IceAdapter:125)
2019-10-03 22:45:19.006 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) iTz_MoNztEr(242113) (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-10-03 22:45:19.008 WARN ICE iTz_MoNztEr(242113): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:379)
2019-10-03 22:45:19.008 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) Moskito(291345) (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-10-03 22:45:19.008 WARN ICE iTz_MoNztEr(242113): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:253)
2019-10-03 22:45:19.009 WARN Close requested, stopping... (c.f.iceadapter.rpc.RPCHandler:130)
2019-10-03 22:45:19.010 INFO close() - stopping the adapter (com.faforever.iceadapter.IceAdapter:136)
2019-10-03 22:45:19.011 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) CDR_Shelby(323293) (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-10-03 22:45:19.013 DEBUG No longer listening for GPGPNET from FA (c.f.iceadapter.gpgnet.GPGNetServer:163)
2019-10-03 22:45:19.013 INFO GPGNetServer stopped (c.f.iceadapter.gpgnet.GPGNetServer:262)
2019-10-03 22:45:19.012 WARN ICE CDR_Shelby(323293): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:379)
2019-10-03 22:45:19.013 WARN ICE CDR_Shelby(323293): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:253)
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
Re: Help needed - intermittent desyncs
by Uveso » 04 Oct 2019, 01:22
Hello pchelka,
i am a bit confused.
What do you mean with "desync" ?
1. You got a popup window in game that says you are desynced and you can continue playing after checking the checkbox ?
2. You got disconnected from other players, and you can't continue playing ?
---
Desync means that your game checksum is not equal to other players and its mostly caused by different game or mod files.
The game.log will show many desync messages in this case.
And the game will continue on all players, but with different outcome.
i am a bit confused.
What do you mean with "desync" ?
1. You got a popup window in game that says you are desynced and you can continue playing after checking the checkbox ?
2. You got disconnected from other players, and you can't continue playing ?
---
Desync means that your game checksum is not equal to other players and its mostly caused by different game or mod files.
The game.log will show many desync messages in this case.
And the game will continue on all players, but with different outcome.
-
Uveso - Supreme Commander
- Posts: 1788
- Joined: 11 Dec 2015, 20:56
- Location: Germany
- Has liked: 70 times
- Been liked: 291 times
- FAF User Name: Uveso
Re: Help needed - intermittent desyncs
by pchelka » 04 Oct 2019, 22:23
Basically this is the behavior:
- The game starts - and map loads, usually i have to wait for other players to finish loading map as im on raid-0 SSD (this may be a factor in the desync)
- The camera zooms in And in this moment first building is placed and game freezes for everyone (we can use chat though)
- Checking the F11 i am usually behind other players
- Debug for ICE window says we are connected but i don't see updates on the RTT latency (-1)
At this point ppl start shouting that my connection (fast fiber optic) sucks and quit
And tats basically it, as per my understanding this is a desynceded game but my nomenclature may be incorrect here
- The game starts - and map loads, usually i have to wait for other players to finish loading map as im on raid-0 SSD (this may be a factor in the desync)
- The camera zooms in And in this moment first building is placed and game freezes for everyone (we can use chat though)
- Checking the F11 i am usually behind other players
- Debug for ICE window says we are connected but i don't see updates on the RTT latency (-1)
At this point ppl start shouting that my connection (fast fiber optic) sucks and quit
And tats basically it, as per my understanding this is a desynceded game but my nomenclature may be incorrect here
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
Re: Help needed - intermittent desyncs
by PhilipJFry » 05 Oct 2019, 09:33
can you post logs?
cats>dogs
post logs
post logs
-
PhilipJFry - Supreme Commander
- Posts: 2635
- Joined: 23 Mar 2016, 21:16
- Location: Austria
- Has liked: 232 times
- Been liked: 348 times
- FAF User Name: PhilipJFry
Re: Help needed - intermittent desyncs
by pchelka » 05 Oct 2019, 12:54
Sure i will get fresh log but an example is few post above,
By the way if this is not the log that is needed please let me know where i should pull this out form
By the way if this is not the log that is needed please let me know where i should pull this out form
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
Re: Help needed - intermittent desyncs
by pchelka » 07 Oct 2019, 21:51
Ok got some logs,
Seems this is where it goes south, not sure if i was the reason though :
ice-adapter.log- (48.13 KiB) Downloaded 11 times
- downlords-faf-client.log
- (34.86 KiB) Downloaded 13 times
Seems this is where it goes south, not sure if i was the reason though :
- Code: Select all
019-10-07 21:43:20.826 DEBUG ICE Arbok(112561): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:167)
2019-10-07 21:43:20.827 INFO ICE Arbok(112561): Received new candidates/offer, stopping... (c.f.iceadapter.ice.PeerIceModule:178)
2019-10-07 21:43:20.828 WARN ICE Arbok(112561): ICE connection has been lost for peer (c.f.iceadapter.ice.PeerIceModule:283)
2019-10-07 21:43:20.835 INFO ICE Arbok(112561): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-10-07 21:43:20.836 INFO ICE Arbok(112561): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-10-07 21:43:21.007 DEBUG ICE Arbok(112561): Sending own candidates to 112561 (c.f.iceadapter.ice.PeerIceModule:134)
2019-10-07 21:43:21.010 DEBUG ICE Arbok(112561): Starting ICE for peer 112561 (c.f.iceadapter.ice.PeerIceModule:202)
2019-10-07 21:43:21.360 DEBUG ICE Arbok(112561): ICE terminated (c.f.iceadapter.ice.PeerIceModule:227)
2019-10-07 21:43:21.361 INFO Started turn refresh module for peer Arbok (c.f.i.ice.PeerTurnRefreshModule:60)
2019-10-07 21:43:21.361 DEBUG ICE Arbok(112561): Now forwarding data from ICE to FA for peer (c.f.iceadapter.ice.PeerIceModule:351)
2019-10-07 21:43:21.361 INFO Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-10-07 21:43:25.821 INFO Received GPGNet message: Bottleneck ack 35 112561 28239.5 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:43:31.616 INFO Received GPGNet message: Bottleneck ack 35 112561 34034.3 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:43:36.618 INFO Received GPGNet message: Bottleneck ack 35 112561 39035.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:43:41.619 INFO Received GPGNet message: Bottleneck ack 35 112561 44037.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:43:46.798 INFO Received GPGNet message: Bottleneck ack 35 112561 49217.3 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:43:51.809 INFO Received GPGNet message: Bottleneck ack 35 112561 54227.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:43:56.880 INFO Received GPGNet message: Bottleneck ack 35 112561 59298.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:02.665 INFO Received GPGNet message: Bottleneck ack 35 112561 65083.1 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:06.009 INFO Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-10-07 21:44:06.198 INFO Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-10-07 21:44:07.960 INFO Received GPGNet message: Bottleneck ack 35 112561 70377.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:08.565 INFO Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-10-07 21:44:13.091 INFO Received GPGNet message: Bottleneck ack 35 112561 75509.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:18.094 INFO Received GPGNet message: Bottleneck ack 35 112561 80512.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:23.871 INFO Received GPGNet message: Bottleneck ack 35 112561 86289.2 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:29.644 INFO Received GPGNet message: Bottleneck ack 35 112561 92062.5 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:30.170 WARN Sleeping refreshThread was interrupted (c.f.i.ice.PeerTurnRefreshModule:80)
2019-10-07 21:44:30.170 WARN ICE Dysterika(111164): ICE connection has been lost for peer (c.f.iceadapter.ice.PeerIceModule:283)
2019-10-07 21:44:30.175 INFO ICE Dysterika(111164): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:76)
2019-10-07 21:44:30.176 INFO ICE Dysterika(111164): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:96)
2019-10-07 21:44:30.348 DEBUG ICE Dysterika(111164): Sending own candidates to 111164 (c.f.iceadapter.ice.PeerIceModule:134)
2019-10-07 21:44:35.012 INFO Received GPGNet message: Bottleneck ack 35 112561 97430.0 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:37.098 INFO Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-10-07 21:44:39.607 INFO Received GPGNet message: BottleneckCleared (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:39.653 INFO Received GPGNet message: Bottleneck data 55 35163 102071.9 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:39.704 INFO Received GPGNet message: BottleneckCleared (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:39.954 INFO Received GPGNet message: GameResult 10 defeat -10 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-10-07 21:44:40.314 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-10-07 21:44:40.315 INFO GPGNet connection lost (c.f.iceadapter.gpgnet.GPGNetServer:186)
2019-10-07 21:44:40.315 DEBUG Closing GPGNetClient (c.f.iceadapter.gpgnet.GPGNetServer:169)
2019-10-07 21:44:40.316 INFO FA SHUTDOWN, closing everything (com.faforever.iceadapter.IceAdapter:125)
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
Re: Help needed - intermittent desyncs
by pchelka » 25 Dec 2019, 23:43
OK so got another log today, maybe someone can spot any issue here
- Attachments
-
- ice-adapter.log
- ICA adapter log
- (99.6 KiB) Downloaded 11 times
- pchelka
- Crusader
- Posts: 21
- Joined: 24 Mar 2019, 17:26
- Has liked: 0 time
- Been liked: 0 time
12 posts
• Page 1 of 2 • 1, 2
Who is online
Users browsing this forum: No registered users and 1 guest