Server desync

[Vladki]

I have got some debug output on desync - just when building a semaphore (distant)

Code Select Expand


Warning: NWC_CHECK:     time difference to server 40
Warning: karte_t:::do_network_world_command:    sync_step=36512  server=[ss=36512 st=9128 nfc=0 rand=308616853 halt=1 line=1 cnvy=1 ssr=1117859445,308616853,0,0,0,0,0,0 str=308616853,308616853,308616853,308616853,308616853,308616853,308616853,0,308616853,308616853,308616853,308616853,308616853,8831220,312502,0 exr=0,0,0,0,0,0,0,0  client=[ss=36512 st=9128 nfc=0 rand=308616853 halt=1 line=1 cnvy=1 ssr=1117859445,308616853,0,0,0,0,0,0 str=308616853,308616853,308616853,308616853,308616853,308616853,308616853,0,308616853,308616853,308616853,308616853,308616853,8831220,312502,0 exr=0,0,0,0,0,0,0,0 
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[8]
Warning: NWC_CHECK:     time difference to server -280
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[8]
Warning: NWC_CHECK:     time difference to server -280
Message: network_command_t::rdwr:       write packet_id=8, client_id=2
Warning: nwc_tool_t::rdwr:      rdwr id=8 client=0 plnr=0 pos=43,52,0 tool_id=4116 defpar=lq-semaphore-distant,16,1,1,51,51,0 init=0 flags=0
Message: packet_t::send:        sent 229 bytes to socket[8]; id=8, size=229
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[8]
Warning: NWC_CHECK:     time difference to server 640
Warning: karte_t:::do_network_world_command:    sync_step=36608  server=[ss=36608 st=9152 nfc=0 rand=930007874 halt=1 line=1 cnvy=1 ssr=217293025,2807993870,0,0,0,0,0,0 str=2807993870,2807993870,2807993870,2807993870,2807993870,2807993870,2807993870,0,930007874,930007874,930007874,930007874,930007874,9860100,348910,0 exr=0,0,0,0,0,0,0,0  client=[ss=36608 st=9152 nfc=0 rand=930007874 halt=1 line=1 cnvy=1 ssr=217293025,2807993870,0,0,0,0,0,0 str=2807993870,2807993870,2807993870,2807993870,2807993870,2807993870,2807993870,0,930007874,930007874,930007874,930007874,930007874,9860100,348910,0 exr=0,0,0,0,0,0,0,0 
Message: network_command_t::rdwr:       read packet_id=8, client_id=0
Warning: nwc_tool_t::rdwr:      rdwr id=8 client=2 plnr=0 pos=43,52,0 tool_id=4116 defpar=lq-semaphore-distant,16,1,1,51,51,0 init=0 flags=0
Warning: network_check_activity():      received cmd id=8 nwc_tool_t from socket[8]
Warning: network_world_command_t::execute:      wanted to execute(8) in the past
Warning: karte_t::network_disconnect(): Lost synchronisation with server. Random flags: 0
Warning: nwc_routesearch_t::reset:      all static variables are reset
World finished ...
Show banner ...


[Ves]

And now I got an out of bound:

FATAL ERROR: vector_tpl<T>::[] - class koord3d: index out of bounds: 65530 not in 0..20

Appears to be the same as the other one.

[Vladki]

More debug output (from server):

Code Select Expand


Warning: NWC_CHECK:     time difference to server 120
Warning: karte_t:::do_network_world_command:    sync_step=33024  server=[ss=33024 st=8256 nfc=0 rand=2928070699 halt=1 line=1 cnvy=1 ssr=2445345475,2928070699,0,0,0,0,0,0 str=2928070699,2928070699,2928070699,2928070699,2928070699,2928070699,2928070699,0,2928070699,2928070699,2928070699,2928070699,2928070699,265066742,9385901,0 exr=0,0,0,0,0,0,0,0  client=[ss=33024 st=8256 nfc=0 rand=2928070699 halt=1 line=1 cnvy=1 ssr=2445345475,2928070699,0,0,0,0,0,0 str=2928070699,2928070699,2928070699,2928070699,2928070699,2928070699,2928070699,0,2928070699,2928070699,2928070699,2928070699,2928070699,265066742,9385901,0 exr=0,0,0,0,0,0,0,0 
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[8]
Warning: NWC_CHECK:     time difference to server 40
Warning: karte_t:::do_network_world_command:    sync_step=33056  server=[ss=33056 st=8264 nfc=0 rand=2612630715 halt=1 line=1 cnvy=1 ssr=2064525739,2612630715,0,0,0,0,0,0 str=2612630715,2612630715,2612630715,2612630715,2612630715,2612630715,2612630715,0,2612630715,2612630715,2612630715,2612630715,2612630715,265409926,9398053,0 exr=0,0,0,0,0,0,0,0  client=[ss=33056 st=8264 nfc=0 rand=2612630715 halt=1 line=1 cnvy=1 ssr=2064525739,2612630715,0,0,0,0,0,0 str=2612630715,2612630715,2612630715,2612630715,2612630715,2612630715,2612630715,0,2612630715,2612630715,2612630715,2612630715,2612630715,265409926,9398053,0 exr=0,0,0,0,0,0,0,0 
FATAL ERROR: vector_tpl<T>::[] - 7koord3d: index out of bounds: 65530 not in 0..20
Aborting program execution ...

For help with this error or to file a bug report please see the Simutrans forum at
http://forum.simutrans.com
Neúspěšně ukončen (SIGABRT)


Next fail had also this:

ERROR: rail_vehicle_t::activate_choose_signal():        could not reserved route after find_route!
FATAL ERROR: vector_tpl<T>::[] - 7koord3d: index out of bounds: 65530 not in 0..20

[Vladki]

desyncs were caused by my home wifi connection. Tonight I connected by wire, and got no problems

[jamespetts]

Thank you for your reports. Has the latest fix prevented the crashes, or are there still some cases in which these occur?

[Vladki]

No crashes so far.