[Linux] My first desync

[hrw]

I have Factorio running on my own Linux server (Debian 'buster') where I have one simple game without any mods.

Joined game in the morning, expanded base a bit and had to leave home. Left game running and when I was back I saw desync information.

Reconnected and left game.

Server output:

Code: Select all

2020-09-17 08:40:04 [JOIN] hrw joined the game
1201656.562 Info AppManager.cpp:298: Saving to _autosave4 (blocking).
1201660.058 Info AppManagerStates.cpp:1837: Saving finished
1202260.045 Info AppManager.cpp:298: Saving to _autosave5 (blocking).
1202263.498 Info AppManagerStates.cpp:1837: Saving finished
1202863.483 Info AppManager.cpp:298: Saving to _autosave1 (blocking).
1202866.895 Info AppManagerStates.cpp:1837: Saving finished
1203466.869 Info AppManager.cpp:298: Saving to _autosave2 (blocking).
1203470.286 Info AppManagerStates.cpp:1837: Saving finished
1204070.277 Info AppManager.cpp:298: Saving to _autosave3 (blocking).
1204073.668 Info AppManagerStates.cpp:1837: Saving finished
1204673.648 Info AppManager.cpp:298: Saving to _autosave4 (blocking).
1204677.059 Info AppManagerStates.cpp:1837: Saving finished
1205277.036 Info AppManager.cpp:298: Saving to _autosave5 (blocking).
1205280.482 Info AppManagerStates.cpp:1837: Saving finished
1205352.789 Info ServerMultiplayerManager.cpp:917: updateTick(35824341) received stateChanged peerID(62) oldState(InGame) newState(DesyncedWaitingForMap)
1205352.789 Info ServerMultiplayerManager.cpp:1006: updateTick(35824341) received playerDesynced peer (62)
1205352.789 Info ServerMultiplayerManager.cpp:769: updateTick(35824341) changing state from(InGame) to(InGameSavingMapForDesyncReport)
1205377.589 Info ServerMultiplayerManager.cpp:984: UpdateTick(35824341) Serving map(/opt/factorio/temp/mp-save-62.zip) for peer(62) size(199357678) crc(1966007825)
1205377.589 Info ServerMultiplayerManager.cpp:769: updateTick(35824341) changing state from(InGameSavingMapForDesyncReport) to(InGame)
1205377.690 Info ServerMultiplayerManager.cpp:917: updateTick(35824341) received stateChanged peerID(62) oldState(DesyncedWaitingForMap) newState(DesyncedCatchingUpWithMapReadyForDownload)
1205377.724 Info ServerMultiplayerManager.cpp:917: updateTick(35824341) received stateChanged peerID(62) oldState(DesyncedCatchingUpWithMapReadyForDownload) newState(DesyncedSavingLocalVariantOfMap)
1205390.536 Info ServerMultiplayerManager.cpp:917: updateTick(35824341) received stateChanged peerID(62) oldState(DesyncedSavingLocalVariantOfMap) newState(DesyncedDownloadingMap)
1205425.604 Info ServerMultiplayerManager.cpp:917: updateTick(35824341) received stateChanged peerID(62) oldState(DesyncedDownloadingMap) newState(DesyncedCreatingReport)
1205435.612 Info ServerMultiplayerManager.cpp:1035: Disconnect notification for peer (62)
1205435.612 Info ServerMultiplayerManager.cpp:917: updateTick(35824940) received stateChanged peerID(62) oldState(DesyncedCreatingReport) newState(DisconnectScheduled)
2020-09-17 09:49:20 [LEAVE] hrw left the game
1205435.629 Info ServerSynchronizer.cpp:618: nextHeartbeatSequenceNumber(36367236) removing peer(62).
1205436.620 Info ServerMultiplayerManager.cpp:1185: Auto saving map as /opt/factorio/saves/muminek.zip
1205436.620 Info AppManager.cpp:404: Saving game as /opt/factorio/saves/muminek.zip
1205440.005 Info AppManagerStates.cpp:1837: Saving finished
1210237.184 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({89.67.26.161:54035}))
1210237.184 Info ServerRouter.cpp:453: Replying to connectionRequest for address(IP ADDR:({89.67.26.161:54035})).
1210237.184 Info ServerSynchronizer.cpp:599: nextHeartbeatSequenceNumber(36509661) adding peer(63)
1210237.202 Info ServerMultiplayerManager.cpp:769: updateTick(35824942) changing state from(InGame) to(InGameSavingMap)
1210237.269 Info ServerMultiplayerManager.cpp:917: updateTick(35824942) received stateChanged peerID(63) oldState(Ready) newState(ConnectedWaitingForMap)
1210240.660 Info ServerMultiplayerManager.cpp:984: UpdateTick(35824942) Serving map(/opt/factorio/temp/mp-save-63.zip) for peer(63) size(52014964) crc(819437229)
1210240.660 Info ServerMultiplayerManager.cpp:769: updateTick(35824942) changing state from(InGameSavingMap) to(InGame)
1210240.778 Info ServerMultiplayerManager.cpp:917: updateTick(35824942) received stateChanged peerID(63) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
1210251.194 Info ServerMultiplayerManager.cpp:917: updateTick(35824942) received stateChanged peerID(63) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
1210253.714 Info ServerMultiplayerManager.cpp:917: updateTick(35825092) received stateChanged peerID(63) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
1210254.696 Info ServerMultiplayerManager.cpp:917: updateTick(35825151) received stateChanged peerID(63) oldState(TryingToCatchUp) newState(WaitingForCommandToStartSendingTickClosures)
1210254.696 Info GameActionHandler.cpp:4725: UpdateTick (35825151) processed PlayerJoinGame peerID(63) playerIndex(0) mode(connect) 
1210254.812 Info ServerMultiplayerManager.cpp:917: updateTick(35825158) received stateChanged peerID(63) oldState(WaitingForCommandToStartSendingTickClosures) newState(InGame)
2020-09-17 11:09:39 [JOIN] hrw joined the game
1210274.744 Info ServerMultiplayerManager.cpp:1035: Disconnect notification for peer (63)
1210274.744 Info ServerMultiplayerManager.cpp:917: updateTick(35826354) received stateChanged peerID(63) oldState(InGame) newState(DisconnectScheduled)
2020-09-17 11:09:59 [LEAVE] hrw left the game
1210274.745 Info ServerSynchronizer.cpp:618: nextHeartbeatSequenceNumber(36510874) removing peer(63).
1210275.751 Info ServerMultiplayerManager.cpp:1185: Auto saving map as /opt/factorio/saves/muminek.zip
1210275.751 Info AppManager.cpp:404: Saving game as /opt/factorio/saves/muminek.zip
1210279.312 Info AppManagerStates.cpp:1837: Saving finished

Desync report: https://marcin.juszkiewicz.com.pl/test/ ... -49-10.zip

[hrw]

Some minutes later another desync and game crashed quietly after reconnect button press.

Restarted, tried to connect and got "user with your name is already in the game" message. After retry it connected.

[boskid]

In desync report there is quite a lot of differences, however the desynced-level is corrupted. I am not going to investigate the desync when there is high risk of memory corruption. If you have any steps to reproduce this desync i will look at it.

[hrw]

I took spidertron packed with rockets and went north to biters' villages. Rockets started, I put lot of artillery points and then game desynced again.

https://marcin.juszkiewicz.com.pl/test/ ... -35-55.zip

Feel free to connect and try to reproduce.

[hrw]

Turned out that my machine had memory issues.

Thanks go to @boskid for helping with debugging issue.