The International Simutrans Forum

 

Author Topic: Loss of synchronisation immediately on connecting to the Bridgewater-Brunel server  (Read 1087 times)

0 Members and 1 Guest are viewing this topic.

Offline freddyhayward au

  • *
  • Posts: 73
  • Languages: EN
Perhaps this warrants a new thread but I keep getting instantly de-synced after joining the game. It seems to have coincided with a jam in my rail network, so perhaps it's related to convoy routing?

Offline jamespetts gb

  • Simutrans-Extended project coordinator
  • Moderator
  • *
  • Posts: 18763
  • Cake baker
    • Bridgewater-Brunel
  • Languages: EN
I see that there are two clients currently connected, so this seems not to be universal: can you make sure that you have the latest version of pakset and executable?

Offline ACarlotti

  • *
  • Posts: 483
Also, turn on debug output (appending '-debug 5' to the command line will output everything, and '-log' will write it to a file), and look for a line mentioning "lost synchronisation". The information on or near that line might give some clue as to what is causing it. (E.g. sometimes desyncs are due to network errors rather than a mismatch of internal logic; some further information about the cause of the desync can usually be determined from the debug output).

Offline jamespetts gb

  • Simutrans-Extended project coordinator
  • Moderator
  • *
  • Posts: 18763
  • Cake baker
    • Bridgewater-Brunel
  • Languages: EN
Thank you for that.

I have split this topic from the original topic as this seems to be a separate issue.

Offline jamespetts gb

  • Simutrans-Extended project coordinator
  • Moderator
  • *
  • Posts: 18763
  • Cake baker
    • Bridgewater-Brunel
  • Languages: EN
Can I assume that this was a transient issue?

Offline freddyhayward au

  • *
  • Posts: 73
  • Languages: EN
It seems to have passed for now, although I still see the occasional desync.

Offline freddyhayward au

  • *
  • Posts: 73
  • Languages: EN
I've been running into this issue more regularly again, with a desync occurring with almost every attempt at connection, and after almost every autosave. I have no idea what's causing this, but I did save some debug messages if it's any help:
Code: [Select]
Warning: karte_t::load: Fileversion: 120004
Warning: karte_t::load(): loaded savegame from 11/1990, next month=-754974720, ticks=-758884511 (per month=1<<22)
Message: network_command_t::rdwr: read packet_id=7, client_id=0
Warning: network_check_activity(): received cmd id=7 nwc_ready_t from socket[7]
Warning: nwc_ready_t::execute: set sync_step=2 where map_counter=102552
Warning: karte_t::network_game_set_pause: steps=0 sync_steps=2 pause=0
Message: network_command_t::rdwr: read packet_id=12, client_id=0
Warning: network_check_activity(): received cmd id=12 nwc_auth_player_t from socket[7]
Message: nwc_auth_player_t::execute: plnr = 255  unlock = 26996  our_client_id = 0
Message: network_command_t::rdwr: read packet_id=8, client_id=0
Warning: nwc_tool_t::rdwr: rdwr id=8 client=0 plnr=255 pos=koord3d invalid tool_id=8224 defpar=32768,Welcome, Freddy! init=1 flags=0
Warning: network_check_activity(): received cmd id=8 nwc_tool_t from socket[7]
Warning: network_receive_data: connection [7] already closed
Message: socket_list_t::remove_client: remove client socket[7]
Warning: karte_t::process_network_commands: lost connection to server
Warning: karte_t::network_disconnect(): Lost synchronisation with server. Random flags: 0
Warning: nwc_routesearch_t::reset: all static variables are reset
Edit: another desync log here, this time occuring mid-game:
Code: [Select]
Message: network_command_t::rdwr: read packet_id=9, client_id=0
Warning: network_check_activity(): received cmd id=9 nwc_check_t from socket[7]
Warning: NWC_CHECK: time difference to server -100
Warning: karte_t:::do_network_world_command: sync_step=46927  server=[ss=46927 st=11731 nfc=3 rand=1968361550 halt=9806 line=1025 cnvy=8067 ssr=2677258397,1968361550,0,0,0,0,0,0 str=1442473237,1442473237,1442473237,1442473237,1442473237,2652003880,2652003880,2652003880,2652003880,2652003880,2652003880,2218697796,1716377086,2162595697,1768260486,2652003880 exr=0,0,0,0,0,0,0,0 sums=2213820003,3660762994,0,0,0,0,0,0client=[ss=46927 st=11731 nfc=3 rand=1968361550 halt=9806 line=1025 cnvy=8067 ssr=2677258397,1968361550,0,0,0,0,0,0 str=1442473237,1442473237,1442473237,1442473237,1442473237,2652003880,2652003880,2652003880,2652003880,2652003880,2652003880,2218697796,1716377086,2162595697,1768260486,2652003880 exr=0,0,0,0,0,0,0,0 sums=3145887559,3191001126,0,0,0,0,0,0
Warning: karte_t:::do_network_world_command: disconnecting due to checklist mismatch
Warning: karte_t::network_disconnect(): Lost synchronisation with server. Random flags: 0
Warning: nwc_routesearch_t::reset: all static variables are reset
Edit: another occuring when copying a convoy:
Code: [Select]
Warning: karte_t:::do_network_world_command: sync_step=80831  server=[ss=80831 st=20207 nfc=3 rand=571164551 halt=9812 line=452 cnvy=7531 ssr=3465933849,571164551,0,0,0,0,0,0 str=4183773490,4183773490,4183773490,4183773490,4183773490,818146916,818146916,818146916,818146916,818146916,818146916,3717314547,622063320,2901315491,1443266054,818146916 exr=0,0,0,0,0,0,0,0 sums=2054135726,1881702132,0,0,0,0,0,0client=[ss=80831 st=20207 nfc=3 rand=571164551 halt=9812 line=452 cnvy=7531 ssr=3465933849,571164551,0,0,0,0,0,0 str=4183773490,4183773490,4183773490,4183773490,4183773490,818146916,818146916,818146916,818146916,818146916,818146916,3717314547,622063320,2901315491,1443266054,818146916 exr=0,0,0,0,0,0,0,0 sums=2054135726,1881702132,0,0,0,0,0,0
Warning: karte_t:::do_network_world_command: sync_step=80832  server=[ss=80832 st=20208 nfc=0 rand=3559098716 halt=9812 line=452 cnvy=7531 ssr=571164551,1335932173,0,0,0,0,0,0 str=1335932173,1335932173,1335932173,1335932173,1335932173,4092447919,4092447919,4092447919,4092447919,4092447919,4092447919,840806092,3559098716,2909165580,1443983751,4092447919 exr=0,0,0,0,0,0,0,0 sums=3323633572,1620971732,0,0,0,0,0,0client=[ss=80832 st=20208 nfc=0 rand=3559098716 halt=9812 line=452 cnvy=7531 ssr=571164551,1335932173,0,0,0,0,0,0 str=1335932173,1335932173,1335932173,1335932173,1335932173,4092447919,4092447919,4092447919,4092447919,4092447919,4092447919,840806092,3559098716,2909165580,1443983751,4092447919 exr=0,0,0,0,0,0,0,0 sums=3323633572,1620971732,0,0,0,0,0,0
Warning: karte_t:::do_network_world_command: sync_step=80833  server=[ss=80833 st=20208 nfc=1 rand=4036582663 halt=9812 line=452 cnvy=7531 ssr=3559098716,4036582663,0,0,0,0,0,0 str=1335932173,1335932173,1335932173,1335932173,1335932173,4092447919,4092447919,4092447919,4092447919,4092447919,4092447919,840806092,3559098716,2909165580,1443983751,4092447919 exr=0,0,0,0,0,0,0,0 sums=3596544633,3009980318,0,0,0,0,0,0client=[ss=80833 st=20208 nfc=1 rand=4036582663 halt=9812 line=452 cnvy=7531 ssr=3559098716,4036582663,0,0,0,0,0,0 str=1335932173,1335932173,1335932173,1335932173,1335932173,4092447919,4092447919,4092447919,4092447919,4092447919,4092447919,840806092,3559098716,2909165580,1443983751,4092447919 exr=0,0,0,0,0,0,0,0 sums=3596544633,3009980318,0,0,0,0,0,0
Warning: karte_t:::do_network_world_command: sync_step=80834  server=[ss=80834 st=20208 nfc=2 rand=649545586 halt=9812 line=452 cnvy=7532 ssr=4036582663,649545586,0,0,0,0,0,0 str=1335932173,1335932173,1335932173,1335932173,1335932173,4092447919,4092447919,4092447919,4092447919,4092447919,4092447919,840806092,3559098716,2909165580,1443983751,4092447919 exr=0,0,0,0,0,0,0,0 sums=1358451135,3190341004,0,0,0,0,0,0client=[ss=80834 st=20208 nfc=2 rand=649545586 halt=9812 line=452 cnvy=7531 ssr=4036582663,649545586,0,0,0,0,0,0 str=1335932173,1335932173,1335932173,1335932173,1335932173,4092447919,4092447919,4092447919,4092447919,4092447919,4092447919,840806092,3559098716,2909165580,1443983751,4092447919 exr=0,0,0,0,0,0,0,0 sums=1358451135,3190341004,0,0,0,0,0,0
Warning: karte_t:::do_network_world_command: disconnecting due to checklist mismatch
Warning: karte_t::network_disconnect(): Lost synchronisation with server. Random flags: 0
Edit: and another following an autosave:
Code: [Select]
Message: network_command_t::rdwr: read packet_id=7, client_id=0
Warning: network_check_activity(): received cmd id=7 nwc_ready_t from socket[7]
Warning: nwc_ready_t::execute: set sync_step=81479 where map_counter=31565845
Warning: karte_t::network_game_set_pause: steps=20369 sync_steps=81479 pause=0
Message: network_command_t::rdwr: read packet_id=12, client_id=0
Warning: network_check_activity(): received cmd id=12 nwc_auth_player_t from socket[7]
Message: nwc_auth_player_t::execute: plnr = 255  unlock = 27508  our_client_id = 0
Warning: network_receive_data: connection [7] already closed
Message: socket_list_t::remove_client: remove client socket[7]
Warning: karte_t::process_network_commands: lost connection to server
Warning: karte_t::network_disconnect(): Lost synchronisation with server. Random flags: 0
Edit: another following an autosave:
Code: [Select]
Warning: network_receive_data: error 104 while receiving from [7]
Message: socket_list_t::remove_client: remove client socket[7]
Warning: karte_t::process_network_commands: lost connection to server
Warning: karte_t::network_disconnect(): Lost synchronisation with server. Random flags: 0
« Last Edit: September 29, 2019, 12:20:10 PM by freddyhayward »

Offline ACarlotti

  • *
  • Posts: 483
Code: [Select]
Warning: network_receive_data: connection [7] already closed

It looks like the socket has been closed by the server. I think one reason this could happen is if your client takes too long to load the game and resume responding to the server.