Forged Alliance Forever Forged Alliance Forever Forums 2019-12-29T20:22:38+02:00 /feed.php?f=3&t=18220 2019-12-29T20:22:38+02:00 2019-12-29T20:22:38+02:00 /viewtopic.php?t=18220&p=180863#p180863 <![CDATA[Re: Help needed - intermittent desyncs]]>
in bellow log 'BobTheUltraNoob(312707)' is a relay for me, yet he was kicked out of lobby for no readying up, the game then desynced on game start.

as per bellow ICE was still trying to connect to kicked bob AFTER game launch

check the 2019-12-29 19:13:24.701 time stamp in the log, bob was no longer in the game then.

Code:
2019-12-29 19:13:24.701 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) BobTheUltraNoob(312707) (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)



Code:
2019-12-29 19:11:26.137 INFO  Sent GPGNet message: ConnectToPeer 127.0.0.1:59860 shoot2kill 15565 (c.f.iceadapter.gpgnet.GPGNetServer:133)
2019-12-29 19:11:26.155 INFO  Received GPGNet message: Disconnected 15565 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:11:27.450 INFO  IceMsg received {"srcId":15565,"destId":35163,"password":"2fuedgbb5sqhv5u0868u82sbu6","ufrag":"1vlq21dt9dm4iu","candidates":[{"foundation":"1","protocol":"udp","priority":2130706431,"ip":"192.168.0.6","port":6731,"type":"HOST_CANDIDATE","generation":0,"id":"4","relPort":0},{"foundation":"2","protocol":"udp","priority":2130706431,"ip":"fe80:0:0:0:815c:dbe4:b362:62c5","port":6731,"type":"HOST_CANDIDATE","generation":0,"id":"5","relPort":0},{"foundation":"3","protocol":"udp","priority":1677724415,"ip":"82.6.109.250","port":6731,"type":"SERVER_REFLEXIVE_CANDIDATE","generation":0,"id":"6","relAddr":"192.168.0.6","relPort":6731},{"foundation":"4","protocol":"udp","priority":2815,"ip":"148.251.238.131","port":17838,"type":"RELAYED_CANDIDATE","generation":0,"id":"7","relAddr":"82.6.109.250","relPort":6731}]} (c.f.iceadapter.rpc.RPCHandler:72)
2019-12-29 19:11:27.450 DEBUG ICE shoot2kill(15565): Got IceMsg for peer (c.f.iceadapter.ice.PeerIceModule:168)
2019-12-29 19:11:27.451 INFO  ICE shoot2kill(15565): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:11:27.452 INFO  ICE shoot2kill(15565): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:11:27.650 DEBUG ICE shoot2kill(15565): Sending own candidates to 15565 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:11:27.651 DEBUG ICE shoot2kill(15565): Starting ICE for peer 15565 (c.f.iceadapter.ice.PeerIceModule:203)
2019-12-29 19:11:27.940 DEBUG ICE shoot2kill(15565): ICE terminated (c.f.iceadapter.ice.PeerIceModule:228)
2019-12-29 19:11:27.942 INFO  Started turn refresh module for peer shoot2kill (c.f.i.ice.PeerTurnRefreshModule:60)
2019-12-29 19:11:27.943 DEBUG ICE shoot2kill(15565): Now forwarding data from ICE to FA for peer (c.f.iceadapter.ice.PeerIceModule:356)
2019-12-29 19:11:27.943 INFO  Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-12-29 19:11:53.185 DEBUG New GameState: Launching (c.f.iceadapter.gpgnet.GPGNetServer:96)
2019-12-29 19:11:53.185 INFO  Received GPGNet message: GameState Launching (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:17.485 INFO  Received GPGNet message: Bottleneck ack 38 183541 5433.4 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:20.640 WARN  Sleeping refreshThread was interrupted (c.f.i.ice.PeerTurnRefreshModule:80)
2019-12-29 19:12:20.640 WARN  ICE BobTheUltraNoob(312707): ICE connection has been lost for peer (c.f.iceadapter.ice.PeerIceModule:284)
2019-12-29 19:12:20.657 INFO  ICE BobTheUltraNoob(312707): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:12:20.659 INFO  ICE BobTheUltraNoob(312707): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:12:20.881 DEBUG ICE BobTheUltraNoob(312707): Sending own candidates to 312707 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:12:22.739 INFO  Received GPGNet message: Bottleneck ack 38 183541 10686.4 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:28.278 INFO  Received GPGNet message: Bottleneck ack 38 183541 16226.1 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:31.891 INFO  ICE BobTheUltraNoob(312707): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:12:31.892 INFO  ICE BobTheUltraNoob(312707): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:12:32.091 DEBUG ICE BobTheUltraNoob(312707): Sending own candidates to 312707 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:12:33.510 INFO  Received GPGNet message: Bottleneck ack 38 183541 21457.9 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:38.519 INFO  Received GPGNet message: Bottleneck ack 38 183541 26467.3 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:38.672 INFO  Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-12-29 19:12:39.830 INFO  Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-12-29 19:12:43.100 INFO  ICE BobTheUltraNoob(312707): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:12:43.101 INFO  ICE BobTheUltraNoob(312707): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:12:43.286 DEBUG ICE BobTheUltraNoob(312707): Sending own candidates to 312707 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:12:43.776 INFO  Received GPGNet message: Bottleneck ack 38 183541 31723.9 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:49.049 INFO  Received GPGNet message: Bottleneck ack 38 183541 36996.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:54.266 INFO  Received GPGNet message: Bottleneck ack 38 183541 42214.2 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:12:54.294 INFO  ICE BobTheUltraNoob(312707): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:12:54.295 INFO  ICE BobTheUltraNoob(312707): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:12:54.485 DEBUG ICE BobTheUltraNoob(312707): Sending own candidates to 312707 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:12:59.692 INFO  Received GPGNet message: Bottleneck ack 38 183541 47639.8 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:04.792 INFO  Received GPGNet message: Bottleneck ack 38 183541 52740.0 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:05.492 INFO  ICE BobTheUltraNoob(312707): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:13:05.493 INFO  ICE BobTheUltraNoob(312707): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:13:05.683 DEBUG ICE BobTheUltraNoob(312707): Sending own candidates to 312707 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:13:08.287 INFO  Sent turn refresh request. (c.f.i.ice.PeerTurnRefreshModule:72)
2019-12-29 19:13:10.079 INFO  Received GPGNet message: Bottleneck ack 38 183541 58026.5 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:15.122 INFO  Received GPGNet message: Bottleneck ack 38 183541 63069.7 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:16.691 INFO  ICE BobTheUltraNoob(312707): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:13:16.692 INFO  ICE BobTheUltraNoob(312707): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:13:16.899 DEBUG ICE BobTheUltraNoob(312707): Sending own candidates to 312707 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:13:16.920 WARN  Sleeping refreshThread was interrupted (c.f.i.ice.PeerTurnRefreshModule:80)
2019-12-29 19:13:16.920 WARN  ICE Batrak_Abrama(112454): ICE connection has been lost for peer (c.f.iceadapter.ice.PeerIceModule:284)
2019-12-29 19:13:16.936 INFO  ICE Batrak_Abrama(112454): Initiating ICE for peer (c.f.iceadapter.ice.PeerIceModule:77)
2019-12-29 19:13:16.937 INFO  ICE Batrak_Abrama(112454): Gathering ice candidates (c.f.iceadapter.ice.PeerIceModule:97)
2019-12-29 19:13:17.170 DEBUG ICE Batrak_Abrama(112454): Sending own candidates to 112454 (c.f.iceadapter.ice.PeerIceModule:135)
2019-12-29 19:13:20.499 INFO  Received GPGNet message: Bottleneck ack 38 183541 68447.1 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:23.802 INFO  Received GPGNet message: BottleneckCleared  (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:24.126 INFO  Received GPGNet message: GameResult 7 defeat -10 (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:24.342 INFO  Received GPGNet message: JsonStats {"stats":[{"blueprints":{"uel0001":{"built":1,"lowest_health":12000}},"type":"Human","general":{"score":0,"currentcap":{"count":2000},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4080,"currentunits":{"count":1},"mass":653.9990234375},"faction":1,"resources":{"massin":{"total":653.9990234375,"rate":0.10000000149012},"energyover":76,"energyin":{"total":4080,"rate":2},"energyout":{"total":0,"rate":0},"massover":3.799072265625,"massout":{"total":0,"rate":0}},"name":"TheDevilRise","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"url0001":{"built":1,"lowest_health":10000}},"type":"Human","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4082,"currentunits":{"count":1},"mass":654.09899902344},"faction":3,"resources":{"massin":{"total":654.09899902344,"rate":0.10000000149012},"energyover":78,"energyin":{"total":4082,"rate":2},"energyout":{"total":0,"rate":0},"massover":3.8990478515625,"massout":{"total":0,"rate":0}},"name":"ZoolanderGG","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"uel0001":{"built":1,"lowest_health":12000}},"type":"Human","general":{"score":0,"currentcap":{"count":2000},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4085.3332519531,"currentunits":{"count":1},"mass":654.265625},"faction":1,"resources":{"massin":{"total":654.265625,"rate":0.10000000149012},"energyover":80,"energyin":{"total":4085.3332519531,"rate":2},"energyout":{"total":0,"rate":0},"massover":3.9990234375,"massout":{"total":0,"rate":0}},"name":"pchelka","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"uel0001":{"built":1,"lowest_health":12000}},"type":"Human","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4087.3332519531,"currentunits":{"count":1},"mass":654.36560058594},"faction":1,"resources":{"massin":{"total":654.36560058594,"rate":0.10000000149012},"energyover":82,"energyin":{"total":4087.3332519531,"rate":2},"energyout":{"total":0,"rate":0},"massover":4.0989990234375,"massout":{"total":0,"rate":0}},"name":"healthycore","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"uel0001":{"built":1,"lowest_health":12000}},"type":"Human","general":{"score":0,"currentcap":{"count":2000},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4092,"currentunits":{"count":1},"mass":654.59887695313},"faction":1,"resources":{"massin":{"total":654.59887695313,"rate":0.10000000149012},"energyover":84,"energyin":{"total":4092,"rate":2},"energyout":{"total":0,"rate":0},"massover":4.198974609375,"massout":{"total":0,"rate":0}},"name":"Clarke","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"uel0001":{"built":1,"lowest_health":12000}},"type":"Human","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4094,"currentunits":{"count":1},"mass":654.69885253906},"faction":1,"resources":{"massin":{"total":654.69885253906,"rate":0.10000000149012},"energyover":86,"energyin":{"total":4094,"rate":2},"energyout":{"total":0,"rate":0},"massover":4.2989501953125,"massout":{"total":0,"rate":0}},"name":"orrk","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"url0001":{"built":1,"lowest_health":10000}},"type":"Human","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4104,"currentunits":{"count":1},"mass":655.19873046875},"faction":3,"resources":{"massin":{"total":655.19873046875,"rate":0.10000000149012},"energyover":104,"energyin":{"total":4104,"rate":2},"energyout":{"total":0,"rate":0},"massover":5.19873046875,"massout":{"total":0,"rate":0}},"name":"Gilobot","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"url0001":{"built":1,"lowest_health":10000}},"type":"Human","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":1,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":4106,"currentunits":{"count":1},"mass":655.29870605469},"faction":3,"resources":{"massin":{"total":655.29870605469,"rate":0.10000000149012},"energyover":106,"energyin":{"total":4106,"rate":2},"energyout":{"total":0,"rate":0},"massover":5.2987060546875,"massout":{"total":0,"rate":0}},"name":"Batrak_Abrama","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":1},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":1},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":1},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":[],"type":"AI","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":0,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":0,"currentunits":{"count":0},"mass":0},"faction":5,"resources":{"massin":{"total":0,"rate":0},"energyover":0,"energyin":{"total":0,"rate":0},"energyout":{"total":0,"rate":0},"massover":0,"massout":{"total":0,"rate":0}},"name":"civilian","units":{"air":{"lost":0,"kills":0,"built":0},"tech3":{"lost":0,"kills":0,"built":0},"cdr":{"lost":0,"kills":0,"built":0},"tech2":{"lost":0,"kills":0,"built":0},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":0},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":0},"naval":{"lost":0,"kills":0,"built":0},"sacu":{"lost":0,"kills":0,"built":0}}},{"blueprints":{"uel0202":{"built":13},"url0303":{"built":4},"uel0303":{"built":10},"uea0302":{"built":1},"urs0201":{"built":3},"url0202":{"built":12}},"type":"AI","general":{"score":0,"currentcap":{"count":1500},"lastReclaimedMass":0,"kills":{"mass":0,"count":0,"energy":0},"built":{"mass":0,"count":43,"energy":0},"lastReclaimedEnergy":0,"lost":{"mass":0,"count":0,"energy":0},"energy":0,"currentunits":{"count":0},"mass":0},"faction":5,"resources":{"massin":{"total":0,"rate":0},"energyover":0,"energyin":{"total":0,"rate":0},"energyout":{"total":0,"rate":0},"massover":0,"massout":{"total":0,"rate":0}},"name":"civilian","units":{"air":{"lost":0,"kills":0,"built":1},"tech3":{"lost":0,"kills":0,"built":15},"cdr":{"lost":0,"kills":0,"built":0},"tech2":{"lost":0,"kills":0,"built":28},"tech1":{"lost":0,"kills":0,"built":0},"transportation":{"lost":0,"kills":0,"built":0},"land":{"lost":0,"kills":0,"built":39},"experimental":{"lost":0,"kills":0,"built":0},"structures":{"lost":0,"kills":0,"built":0},"engineer":{"lost":0,"kills":0,"built":0},"naval":{"lost":0,"kills":0,"built":3},"sacu":{"lost":0,"kills":0,"built":0}}}]} (c.f.iceadapter.gpgnet.GPGNetServer:123)
2019-12-29 19:13:24.658 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-12-29 19:13:24.659 INFO  GPGNet connection lost (c.f.iceadapter.gpgnet.GPGNetServer:186)
2019-12-29 19:13:24.659 DEBUG Closing GPGNetClient (c.f.iceadapter.gpgnet.GPGNetServer:169)
2019-12-29 19:13:24.660 INFO  FA SHUTDOWN, closing everything (com.faforever.iceadapter.IceAdapter:128)
2019-12-29 19:13:24.661 WARN  Close requested, stopping... (c.f.iceadapter.rpc.RPCHandler:130)
2019-12-29 19:13:24.661 INFO  close() - stopping the adapter (com.faforever.iceadapter.IceAdapter:139)
2019-12-29 19:13:24.661 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) healthycore(206113) (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-12-29 19:13:24.664 WARN  ICE healthycore(206113): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.664 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) Clarke(247456) (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-12-29 19:13:24.665 WARN  ICE healthycore(206113): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.669 WARN  ICE Clarke(247456): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.669 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) orrk(118229) (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-12-29 19:13:24.669 WARN  ICE Clarke(247456): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.672 WARN  ICE orrk(118229): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.673 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) Gilobot(183541) (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-12-29 19:13:24.678 WARN  Sleeping refreshThread was interrupted (c.f.i.ice.PeerTurnRefreshModule:80)
2019-12-29 19:13:24.694 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) Batrak_Abrama(112454) (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-12-29 19:13:24.695 WARN  ICE orrk(118229): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.699 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) TheDevilRise(320115) (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-12-29 19:13:24.701 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) BobTheUltraNoob(312707) (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-12-29 19:13:24.718 WARN  ICE Gilobot(183541): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.718 WARN  ICE Gilobot(183541): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.722 WARN  ICE TheDevilRise(320115): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.722 WARN  ICE TheDevilRise(320115): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.725 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) ZoolanderGG(195099) (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-12-29 19:13:24.727 WARN  Sleeping refreshThread was interrupted (c.f.i.ice.PeerTurnRefreshModule:80)
2019-12-29 19:13:24.731 WARN  ICE ZoolanderGG(195099): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.732 WARN  ICE ZoolanderGG(195099): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.733 DEBUG Error while reading from local FA as peer (probably disconnecting from peer) shoot2kill(15565) (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-12-29 19:13:24.734 WARN  Sleeping refreshThread was interrupted (c.f.i.ice.PeerTurnRefreshModule:80)
2019-12-29 19:13:24.736 WARN  ICE shoot2kill(15565): Error while reading from ICE adapter (c.f.iceadapter.ice.PeerIceModule:384)
2019-12-29 19:13:24.737 WARN  ICE shoot2kill(15565): Peer not connected anymore, aborting onConnectionLost of ICE (c.f.iceadapter.ice.PeerIceModule:254)
2019-12-29 19:13:24.737 INFO  GPGNetServer stopped (c.f.iceadapter.gpgnet.GPGNetServer:262)
2019-12-29 19:13:24.736 DEBUG No longer listening for GPGPNET from FA (c.f.iceadapter.gpgnet.GPGNetServer:163)

Statistics: Posted by pchelka — 29 Dec 2019, 20:22

]]> 2019-12-26T23:35:06+02:00 2019-12-26T23:35:06+02:00 /viewtopic.php?t=18220&p=180801#p180801 <![CDATA[Re: Help needed - intermittent desyncs]]>

Code:
2019-12-26 22:31:56.169  INFO 12004 --- [JavaFX Application Thread] com.faforever.client.game.GameService    : Joining game: 'Noobs welcome LowCpu' (10746012)
2019-12-26 22:31:56.221  WARN 12004 --- [JavaFX Application Thread] c.f.c.patch.BireusFeaturedModUpdater     : Error while testing http://content.faforever.com/bireus/faf/

java.io.FileNotFoundException: http://content.faforever.com/bireus/faf//info.json
   at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(Unknown Source)
   at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source)
   at java.base/java.net.URL.openStream(Unknown Source)
   at com.fasterxml.jackson.core.JsonFactory._optimizedStreamFromURL(JsonFactory.java:1595)
   at com.fasterxml.jackson.core.JsonFactory.createParser(JsonFactory.java:793)
   at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:2959)
   at com.faforever.client.patch.BireusFeaturedModUpdater.canUpdate(BireusFeaturedModUpdater.java:57)
   at com.faforever.client.patch.GameUpdaterImpl.updateFeaturedMod(GameUpdaterImpl.java:159)
   at com.faforever.client.patch.GameUpdaterImpl.update(GameUpdaterImpl.java:70)
   at com.faforever.client.game.GameService.updateGameIfNecessary(GameService.java:465)
   at com.faforever.client.game.GameService.lambda$joinGame$6(GameService.java:298)
   at java.base/java.util.concurrent.CompletableFuture.uniComposeStage(Unknown Source)
   at java.base/java.util.concurrent.CompletableFuture.thenCompose(Unknown Source)
   at com.faforever.client.game.GameService.joinGame(GameService.java:298)
   at com.faforever.client.game.JoinGameHelper.join(JoinGameHelper.java:85)
   at com.faforever.client.game.JoinGameHelper.join(JoinGameHelper.java:59)
   at com.faforever.client.game.GamesTableController.lambda$gamesRowFactory$33(GamesTableController.java:219)
   at javafx.base/com.sun.javafx.event.CompositeEventHandler.dispatchBubblingEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventHandlerManager.dispatchBubblingEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventHandlerManager.dispatchBubblingEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.CompositeEventDispatcher.dispatchBubblingEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.BasicEventDispatcher.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventDispatchChainImpl.dispatchEvent(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventUtil.fireEventImpl(Unknown Source)
   at javafx.base/com.sun.javafx.event.EventUtil.fireEvent(Unknown Source)
   at javafx.base/javafx.event.Event.fireEvent(Unknown Source)
   at javafx.graphics/javafx.scene.Scene$ClickGenerator.postProcess(Unknown Source)
   at javafx.graphics/javafx.scene.Scene$ClickGenerator.access$8300(Unknown Source)
   at javafx.graphics/javafx.scene.Scene$MouseHandler.process(Unknown Source)
   at javafx.graphics/javafx.scene.Scene$MouseHandler.access$1300(Unknown Source)
   at javafx.graphics/javafx.scene.Scene.processMouseEvent(Unknown Source)
   at javafx.graphics/javafx.scene.Scene$ScenePeerListener.mouseEvent(Unknown Source)
   at javafx.graphics/com.sun.javafx.tk.quantum.GlassViewEventHandler$MouseEventNotification.run(Unknown Source)
   at javafx.graphics/com.sun.javafx.tk.quantum.GlassViewEventHandler$MouseEventNotification.run(Unknown Source)
   at java.base/java.security.AccessController.doPrivileged(Native Method)
   at javafx.graphics/com.sun.javafx.tk.quantum.GlassViewEventHandler.lambda$handleMouseEvent$2(Unknown Source)
   at javafx.graphics/com.sun.javafx.tk.quantum.QuantumToolkit.runWithoutRenderLock(Unknown Source)
   at javafx.graphics/com.sun.javafx.tk.quantum.GlassViewEventHandler.handleMouseEvent(Unknown Source)
   at javafx.graphics/com.sun.glass.ui.View.handleMouseEvent(Unknown Source)
   at javafx.graphics/com.sun.glass.ui.View.notifyMouse(Unknown Source)
   at javafx.graphics/com.sun.glass.ui.win.WinApplication._runLoop(Native Method)
   at javafx.graphics/com.sun.glass.ui.win.WinApplication.lambda$runLoop$3(Unknown Source)
   at java.base/java.lang.Thread.run(Unknown Source)

2019-12-26 22:31:56.958  INFO 12004 --- [pool-3-thread-8] c.f.c.patch.GameBinariesUpdateTaskImpl   : Updating binaries to 3709

Statistics: Posted by pchelka — 26 Dec 2019, 23:35

]]> 2019-12-25T23:43:22+02:00 2019-12-25T23:43:22+02:00 /viewtopic.php?t=18220&p=180787#p180787 <![CDATA[Re: Help needed - intermittent desyncs]]> Statistics: Posted by pchelka — 25 Dec 2019, 23:43

]]> 2019-10-07T21:51:40+02:00 2019-10-07T21:51:40+02:00 /viewtopic.php?t=18220&p=178790#p178790 <![CDATA[Re: Help needed - intermittent desyncs]]>
ice-adapter.log
downlords-faf-client.log


Seems this is where it goes south, not sure if i was the reason though :


Code:
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)

Statistics: Posted by pchelka — 07 Oct 2019, 21:51

]]> 2019-10-05T12:54:41+02:00 2019-10-05T12:54:41+02:00 /viewtopic.php?t=18220&p=178728#p178728 <![CDATA[Re: Help needed - intermittent desyncs]]> By the way if this is not the log that is needed please let me know where i should pull this out form

Statistics: Posted by pchelka — 05 Oct 2019, 12:54

]]> 2019-10-05T09:33:44+02:00 2019-10-05T09:33:44+02:00 /viewtopic.php?t=18220&p=178725#p178725 <![CDATA[Re: Help needed - intermittent desyncs]]> Statistics: Posted by PhilipJFry — 05 Oct 2019, 09:33

]]> 2019-10-04T22:23:21+02:00 2019-10-04T22:23:21+02:00 /viewtopic.php?t=18220&p=178716#p178716 <![CDATA[Re: Help needed - intermittent desyncs]]>
- 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

Statistics: Posted by pchelka — 04 Oct 2019, 22:23

]]> 2019-10-04T01:22:32+02:00 2019-10-04T01:22:32+02:00 /viewtopic.php?t=18220&p=178697#p178697 <![CDATA[Re: Help needed - intermittent desyncs]]>
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.

Statistics: Posted by Uveso — 04 Oct 2019, 01:22

]]> 2019-10-03T22:53:01+02:00 2019-10-03T22:53:01+02:00 /viewtopic.php?t=18220&p=178696#p178696 <![CDATA[Re: Help needed - intermittent desyncs]]>

Code:

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)

Statistics: Posted by pchelka — 03 Oct 2019, 22:53

]]> 2019-10-02T21:31:55+02:00 2019-10-02T21:31:55+02:00 /viewtopic.php?t=18220&p=178680#p178680 <![CDATA[Re: Help needed - intermittent desyncs]]> As for ports I had 6112 upd open as per some online 'how to' for faf.

Statistics: Posted by pchelka — 02 Oct 2019, 21:31

]]> 2019-10-02T10:23:58+02:00 2019-10-02T10:23:58+02:00 /viewtopic.php?t=18220&p=178666#p178666 <![CDATA[Re: Help needed - intermittent desyncs]]> - 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.

Statistics: Posted by Geosearchef — 02 Oct 2019, 10:23

]]> 2019-10-01T23:32:56+02:00 2019-10-01T23:32:56+02:00 /viewtopic.php?t=18220&p=178662#p178662 <![CDATA[Help needed - intermittent desyncs]]>
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 ?

Statistics: Posted by pchelka — 01 Oct 2019, 23:32

]]>