problem with network save game ST-112.3 (r6520) pak128-2.3.0

[pkm]

Hello it's me again with a network game related problem.

I was playing yesterday on a large map. Today when I try to connect to the server i can join but have a desnc within 10 secs after I join the game.

I did restart the client and the server. I saved the game on the server, loaded it on the client, played a few days and uploaded the save game to the server. I still get disconnected after a few secs.

Client Log

Code Select Expand


Message: convoi_t::laden_abschliessen():        v: pos(2600,710,-1) steps(31) len=128 ribi=1 prev (2600,710) step(29)
Warning: karte_t::laden():      loaded savegame from 8/1972, next month=1296039936, ticks=1293419883 (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[504]
Warning: nwc_ready_t::execute:  set sync_step=2 where map_counter=26689
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[504]
Message: nwc_auth_player_t::execute:    plnr = 255  unlock = 32767  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 wkzid=8224 defpar=Welcome, M! init=1 flags=0
Warning: network_check_activity():      received cmd id=8 nwc_tool_t from socket[504]
Message: nwc_tool_t::pre_execute:       append sync_step=3 wkz=8224 init
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[504]
Message: NWC_CHECK:     time difference to server -167
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 wkzid=8224 defpar=Aktuell 1 Mitspieler. init=1 flags=0
Warning: network_check_activity():      received cmd id=8 nwc_tool_t from socket[504]
Message: nwc_tool_t::pre_execute:       append sync_step=5 wkz=8224 init
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[504]
Message: NWC_CHECK:     time difference to server 331
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[504]
Message: NWC_CHECK:     time difference to server 542
Warning: karte_t::interactive:  sync_step=4  server=[rand=2537384411 halt=1 line=1 cnvy=1025] client=[rand=2537384411 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 518
Warning: karte_t::interactive:  sync_step=8  server=[rand=1798477875 halt=1 line=1 cnvy=1025] client=[rand=1798477875 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 499
Warning: karte_t::interactive:  sync_step=12  server=[rand=3275780651 halt=1 line=1 cnvy=1025] client=[rand=3275780651 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 470
Warning: karte_t::interactive:  sync_step=16  server=[rand=3719751267 halt=1 line=1 cnvy=1025] client=[rand=3719751267 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 441
Warning: karte_t::interactive:  sync_step=20  server=[rand=3019936753 halt=1 line=1 cnvy=1025] client=[rand=3019936753 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 424
Warning: karte_t::interactive:  sync_step=24  server=[rand=2773208386 halt=1 line=1 cnvy=1025] client=[rand=2773208386 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 419
Warning: karte_t::interactive:  sync_step=28  server=[rand=367514160 halt=1 line=1 cnvy=1025] client=[rand=367514160 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 387
Warning: karte_t::interactive:  sync_step=32  server=[rand=131354004 halt=1 line=1 cnvy=1025] client=[rand=131354004 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 337
Warning: karte_t::interactive:  sync_step=36  server=[rand=1114373782 halt=1 line=1 cnvy=1025] client=[rand=1114373782 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 280
Warning: karte_t::interactive:  sync_step=40  server=[rand=741560594 halt=1 line=1 cnvy=1025] client=[rand=741560594 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 241
Warning: karte_t::interactive:  sync_step=44  server=[rand=475652216 halt=1 line=1 cnvy=1025] client=[rand=475652216 halt=1 line=1 cnvy=1025]
Warning: karte_t::interactive:  sync_step=48  server=[rand=2904827734 halt=1 line=1 cnvy=1025] client=[rand=2904827734 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 192
Warning: karte_t::interactive:  sync_step=52  server=[rand=3078781066 halt=1 line=1 cnvy=1025] client=[rand=3078781066 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 104
Warning: karte_t::interactive:  sync_step=56  server=[rand=107677182 halt=1 line=1 cnvy=1025] client=[rand=107677182 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 10
Warning: karte_t::interactive:  sync_step=60  server=[rand=402684000 halt=1 line=1 cnvy=1025] client=[rand=402684000 halt=1 line=1 cnvy=1025]
Warning: karte_t::interactive:  sync_step=64  server=[rand=2577656188 halt=1 line=1 cnvy=1025] client=[rand=2577656188 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 522
Warning: karte_t::interactive:  sync_step=128  server=[rand=2595832493 halt=1 line=1 cnvy=1025] client=[rand=2595832493 halt=1 line=1 cnvy=1025]
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[504]
Message: NWC_CHECK:     time difference to server 8
Warning: karte_t::interactive:  sync_step=256  server=[rand=3922207916 halt=1 line=1 cnvy=1025] client=[rand=2678081073 halt=1 line=1 cnvy=1025]
Warning: karte_t::interactive:  disconnecting due to checklist mismatch
Warning: karte_t::network_disconnect(): Lost synchronisation with server.
World finished ...
Show banner ...


Server Log

Code Select Expand


Message: nwc_tool_t::do_command:        id=32 init=1 defpar=Aktuell 1 Mitspieler. flag=4
Message: message_t::add_msg():                     Aktuell 1 Mitspieler. (at -1,-1)
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: route_t::calc_route(): No route from 1556,989 to 388,956 found
Message: aircraft_t::calc_route():      search runway start near (388,956,2)
Message: route_t::append_straight_route():      start from (390,953) to (1552,996)
Message: route_t::append_straight_route():      to (1552,996) found.
Message: route_t::append_straight_route():      start from (1552,996) to (1552,986)
Message: route_t::append_straight_route():      to (1552,986) found.
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Message: route_t::calc_route(): No route from 386,956 to 1098,1874 found
Message: aircraft_t::calc_route():      search runway start near (1098,1874,3)
Message: route_t::append_straight_route():      start from (1115,1872) to (380,951)
Message: route_t::append_straight_route():      to (380,951) found.
Message: route_t::append_straight_route():      start from (380,951) to (390,951)
Message: route_t::append_straight_route():      to (390,951) found.
Message: network_command_t::rdwr:       write packet_id=9, client_id=0
Message: packet_t::send:        sent 32 bytes to socket[6]; id=9, size=32
Warning: network_receive_data:  connection [6] already closed
Message: socket_list_t::remove_client:  remove client socket[6]


The server log doesn't give me any clue. In the client log, one of the last messages I see everytime is: Warning: karte_t::interactive:  disconnecting due to checklist mismatch

What and where is this checklist?
If I search for this error message i get linked to the Simutrans-Experimental part. Since this is my private server and i didn't change Simutrans or the pak file in the last few months I don't think it has to do with a missmatching pakset.


Server Settings:
Map is 3000x2140
Companies 4
Cities: 286
Factories: 641
Vehicles: 1485
Halts: 1022

bit per month is 22 and I'm playing in the year 1972

FPS on the server is 12
server frames ahead 5
server frames between checks 128 (was 64 yesterday)
pause server no clients 1


Could this be a corrupt savegame? Why can i play this one in single player mode?

[Dwachs]

The checklist is something that server and clients check to verify that the simulation of the game's world is synchronized.

This mismatch means that there is going something wrong. It could be related to
(a) different program versions on server and client
(b) server and client compiled with different settings
(c) paksets differ on server and client (this is checked - if you can join via the in-game dialog  this should be fine)
(d) a bug in the program

Can you verify that (a) (b) (c) do not apply to your situation? If this is the case then please upload the savegame.

These checklist stuff is not checked in single player mode - there is nothing to check against.

[pkm]

wow I'm once again surprised by the response time from you guys

(a) different program versions on server and client
I don't think this is the case. The Client (Win) is downloaded from the official website, the server (FreeBSD) is compiled by myself

(b) server and client compiled with different settings
This I can imagine. Where do I find the compile settings for the official binaries? Or even better... How can i compile the windowsversion including the pal128? Im having a hard time with this one.
Now I download the client, check the svn revision and download that one on the server and compile the server binary, copy the pakset (the same i use on windows)

To be sure: we are not talking about the simuconf.tab settings right?

config.default

Code Select Expand


BACKEND       = posix
COLOUR_DEPTH  = 0
OSTYPE        = freebsd
DEBUG         = 3    # Level 1-3, higher number means more debug-friendly, see Makefile
OPTIMISE      = 1 # Add umpteen optimisation flags
WITH_REVISION = 1 # adds the revision from svn; required for networkgames
PNG_CONFIG    = pkg-config libpng


(c) paksets differ on server and client (this is checked - if you can join via the in-game dialog  this should be fine)
Yes, I'm using the in-game dialog to connect to the server

(d) a bug in the program
I hope not

I'm not sure if the other guy connected with a different game version and built stuff. Could this be the case?

The savegame can be found http://94.247.217.58/server13353-network.sve (3.1M)

[Dwachs]

Your compile settings look fine. The settings in simuconf.tab are saved in the savegame file and should not be an issue here. Other clients should not have an influence here.

Edit: another problem could be that the text files for possible stop names streetlist_LANG.txt (LANG=en, de, etc) differ on clients and server.

[prissi]

Also, I see with revision: Did you compiler a nightly?

[pkm]

Edit: another problem could be that the text files for possible stop names streetlist_LANG.txt (LANG=en, de, etc) differ on clients and server.

Oh really? that is definitely the case here. I copied 2 or 3 language files into one, created that map and copied the savegame to the server (which has the default language files).

When I create a new city i see the name e.g. new york and get disconnected. when i then login again the city has another name and keeps than one. I guess I first see the clients name and get disconnected because of the difference and when i reconnect i get the servers city name

Also, I see with revision: Did you compiler a nightly?

I download the windows client from the website, check the svn revision it has and fetch that revision from the official server compile the my server binary, copy the pakset (the same i use on windows) and go

[Dwachs]

Oh really? that is definitely the case here. I copied 2 or 3 language files into one, created that map and copied the savegame to the server (which has the default language files).

Then this is the culprit. It needs a fix to make the generation of city names robust against such changes.

[pkm]

Thanks Dwachs for your input!

How can I make sure that every client uses the same language files?

If my Client is an english one, the english citylist will be used and if my client is set to german the german citylist will be used. So this problem can occur on every server out there right?
Or are the "standard" citylist save to use and the server handles the difference?

What I don't get is that this results in a corrupt savegame.
I tought that with the "city nameing correction from the server" (disconnect and when reconnecting the city has a new name) the problem is fixed. Since my client now has the same cityname as the server..

Is there any hope for the savegame? e.g take the game offline, give every city a name that is in the original citylist and restart the game?

[Dwachs]

This problem only occurs if a client founds a new city. The savegame is not broken due to this.

This problem should now be fixed in r6940.