0.14.12 Desync loop every time I join the server
Posted: Mon Oct 03, 2016 10:48 pm
I set up a little server on one of Google's vps machines, the cpu usage almost never goes above 10% and ram is 500mb/2gb
When I start the server I can play on it for as long as I want but as soon as i quit and try to join the server later again I get a constant desync loop. The only fix is to restart the server and then it works until i quit again.
I'm using only one mod on the server "simple teams".
I'm the only player on the server for the time being.
I had the same issue with 0.14.11, which is why I tried .12.
To make matters worse I cannot find any documentation about the fabled desync reports so I can find them on the server and upload them somwhere or at least just look at them.
Also is the --latency argument depreciated, why does it never show in the ./factorio --help list?
What I can share is a syslog extract: (Yes I am logged in with a valid account for the game)
Oct 3 22:34:16 factorio factorio[1092]: 9764.120 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(68.148.79.114:61139)
Oct 3 22:34:16 factorio rsyslogd0: action 'action 18' resumed (module 'builtin:ompipe') [try http://www.rsyslog.com/e/0 ]
Oct 3 22:34:16 factorio rsyslogd-2359: action 'action 18' resumed (module 'builtin:ompipe') [try http://www.rsyslog.com/e/2359 ]
Oct 3 22:34:16 factorio factorio[1092]: 9764.122 Refusing connection for address(************:61139). User verification is required for this game.
Oct 3 22:34:17 factorio factorio[1092]: 9764.776 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(*******************:56726)
Oct 3 22:34:17 factorio factorio[1092]: 9764.777 Info ServerRouter.cpp:422: Replying to connectionRequest for address(*************:56726).
Oct 3 22:34:17 factorio factorio[1092]: 9764.777 Info ServerSynchronizer.cpp:524: nextHeartbeatSequenceNumber(287309) adding peer(2)
Oct 3 22:34:17 factorio factorio[1092]: 9764.794 Info ServerMultiplayerManager.cpp:636: mapTick(1920740) changing state from(InGame) to(InGameSavingMap)
Oct 3 22:34:17 factorio factorio[1092]: 9764.878 Info ServerMultiplayerManager.cpp:720: mapTick(1920740) received stateChanged peerID(2) oldState(Ready) newState(ConnectedWaitingForMap)
Oct 3 22:34:17 factorio factorio[1092]: 9765.502 Info ServerMultiplayerManager.cpp:757: MapTick(1920740) Serving map(/opt/factorio/temp/mp-save-1.zip) for peer(2) size(6048057) crc(1174184990)
Oct 3 22:34:17 factorio factorio[1092]: 9765.503 Info ServerMultiplayerManager.cpp:636: mapTick(1920740) changing state from(InGameSavingMap) to(InGame)
Oct 3 22:34:18 factorio factorio[1092]: 9765.625 Info ServerMultiplayerManager.cpp:720: mapTick(1920748) received stateChanged peerID(2) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
Oct 3 22:34:20 factorio factorio[1092]: 9767.590 Info ServerMultiplayerManager.cpp:720: mapTick(1920866) received stateChanged peerID(2) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
Oct 3 22:34:20 factorio factorio[1092]: 9768.322 Info ServerMultiplayerManager.cpp:720: mapTick(1920910) received stateChanged peerID(2) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
Oct 3 22:34:21 factorio factorio[1092]: 9768.618 Info ServerMultiplayerManager.cpp:720: mapTick(1920927) received stateChanged peerID(2) oldState(TryingToCatchUp) newState(WaitingForCommandToStartSendingTickClosures)
Oct 3 22:34:21 factorio factorio[1092]: 9768.619 Info GameActionHandler.cpp:2549: MapTick(1920927) processed PlayerJoinGame peerID(2) playerIndex(0) mode(connect)
Oct 3 22:34:21 factorio factorio[1092]: 9768.742 Info ServerMultiplayerManager.cpp:720: mapTick(1920935) received stateChanged peerID(2) oldState(WaitingForCommandToStartSendingTickClosures) newState(InGame)
Oct 3 22:34:21 factorio factorio[1092]: 9768.953 Info ServerMultiplayerManager.cpp:720: mapTick(1920948) received stateChanged peerID(2) oldState(InGame) newState(DesyncedWaitingForMap)
Oct 3 22:34:21 factorio factorio[1092]: 9768.953 Info ServerMultiplayerManager.cpp:770: mapTick(1920948) received playerDesynced peer (2)
Oct 3 22:34:21 factorio factorio[1092]: 9768.953 Info ServerMultiplayerManager.cpp:636: mapTick(1920948) changing state from(InGame) to(InGameSavingMapForDesyncReport)
Oct 3 22:34:29 factorio factorio[1092]: 9777.086 Info ServerMultiplayerManager.cpp:757: MapTick(1920948) Serving map(/opt/factorio/temp/mp-save-2.zip) for peer(2) size(45029549) crc(2312515209)
Oct 3 22:34:29 factorio factorio[1092]: 9777.087 Info ServerMultiplayerManager.cpp:636: mapTick(1920948) changing state from(InGameSavingMapForDesyncReport) to(InGame)
Oct 3 22:34:29 factorio factorio[1092]: 9777.208 Info ServerMultiplayerManager.cpp:720: mapTick(1920959) received stateChanged peerID(2) oldState(DesyncedWaitingForMap) newState(DesyncedCatchingUpWithMapReadyForDownload)
Oct 3 22:34:29 factorio factorio[1092]: 9777.226 Info ServerMultiplayerManager.cpp:720: mapTick(1920960) received stateChanged peerID(2) oldState(DesyncedCatchingUpWithMapReadyForDownload) newState(DesyncedSavingLocalVariantOfMap)
Oct 3 22:34:39 factorio factorio[1092]: 9787.284 Info ServerMultiplayerManager.cpp:720: mapTick(1921563) received stateChanged peerID(2) oldState(DesyncedSavingLocalVariantOfMap) newState(DesyncedDownloadingMap)
Oct 3 22:34:45 factorio factorio[1092]: 9792.844 Info ServerMultiplayerManager.cpp:720: mapTick(1921897) received stateChanged peerID(2) oldState(DesyncedDownloadingMap) newState(DesyncedCreatingReport)
Oct 3 22:34:49 factorio factorio[1092]: 9796.615 Info ServerMultiplayerManager.cpp:720: mapTick(1922123) received stateChanged peerID(2) oldState(DesyncedCreatingReport) newState(ConnectedLoadingMap)
Oct 3 22:34:49 factorio factorio[1092]: 9797.324 Info ServerMultiplayerManager.cpp:720: mapTick(1922166) received stateChanged peerID(2) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
Oct 3 22:34:49 factorio factorio[1092]: 9797.375 Info ServerMultiplayerManager.cpp:720: mapTick(1922169) received stateChanged peerID(2) oldState(TryingToCatchUp) newState(DesyncedWaitingForMap)
Oct 3 22:34:49 factorio factorio[1092]: 9797.376 Info ServerMultiplayerManager.cpp:770: mapTick(1922169) received playerDesynced peer (2)
Oct 3 22:34:49 factorio factorio[1092]: 9797.376 Info ServerMultiplayerManager.cpp:636: mapTick(1922169) changing state from(InGame) to(InGameSavingMapForDesyncReport)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:799: Disconnect notification for peer (2)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:720: mapTick(1922169) received stateChanged peerID(2) oldState(DesyncedWaitingForMap) newState(DisconnectScheduled)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:720: mapTick(1922169) received stateChanged peerID(2) oldState(DisconnectScheduled) newState(DesyncedWaitingForMap)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:770: mapTick(1922169) received playerDesynced peer (2)
Oct 3 22:34:56 factorio factorio[1092]: 9803.842 Info ServerSynchronizer.cpp:539: nextHeartbeatSequenceNumber(288444) removing peer(2).
Oct 3 22:34:57 factorio factorio[1092]: 9804.995 Info ServerMultiplayerManager.cpp:757: MapTick(1922169) Serving map(/opt/factorio/temp/mp-save-3.zip) for peer(2) size(45025482) crc(2956362272)
Oct 3 22:34:57 factorio factorio[1092]: 9804.996 Warning TransferSource.cpp:40: We are already uploading for peer 2
Oct 3 22:34:57 factorio factorio[1092]: 9804.996 Info ServerMultiplayerManager.cpp:757: MapTick(1922169) Serving map(/opt/factorio/temp/mp-save-3.zip) for peer(2) size(45025482) crc(2956362272)
Oct 3 22:34:57 factorio factorio[1092]: 9804.996 Info ServerMultiplayerManager.cpp:636: mapTick(1922169) changing state from(InGameSavingMapForDesyncReport) to(InGame)
When I start the server I can play on it for as long as I want but as soon as i quit and try to join the server later again I get a constant desync loop. The only fix is to restart the server and then it works until i quit again.
I'm using only one mod on the server "simple teams".
I'm the only player on the server for the time being.
I had the same issue with 0.14.11, which is why I tried .12.
To make matters worse I cannot find any documentation about the fabled desync reports so I can find them on the server and upload them somwhere or at least just look at them.
Also is the --latency argument depreciated, why does it never show in the ./factorio --help list?
What I can share is a syslog extract: (Yes I am logged in with a valid account for the game)
Oct 3 22:34:16 factorio factorio[1092]: 9764.120 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(68.148.79.114:61139)
Oct 3 22:34:16 factorio rsyslogd0: action 'action 18' resumed (module 'builtin:ompipe') [try http://www.rsyslog.com/e/0 ]
Oct 3 22:34:16 factorio rsyslogd-2359: action 'action 18' resumed (module 'builtin:ompipe') [try http://www.rsyslog.com/e/2359 ]
Oct 3 22:34:16 factorio factorio[1092]: 9764.122 Refusing connection for address(************:61139). User verification is required for this game.
Oct 3 22:34:17 factorio factorio[1092]: 9764.776 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(*******************:56726)
Oct 3 22:34:17 factorio factorio[1092]: 9764.777 Info ServerRouter.cpp:422: Replying to connectionRequest for address(*************:56726).
Oct 3 22:34:17 factorio factorio[1092]: 9764.777 Info ServerSynchronizer.cpp:524: nextHeartbeatSequenceNumber(287309) adding peer(2)
Oct 3 22:34:17 factorio factorio[1092]: 9764.794 Info ServerMultiplayerManager.cpp:636: mapTick(1920740) changing state from(InGame) to(InGameSavingMap)
Oct 3 22:34:17 factorio factorio[1092]: 9764.878 Info ServerMultiplayerManager.cpp:720: mapTick(1920740) received stateChanged peerID(2) oldState(Ready) newState(ConnectedWaitingForMap)
Oct 3 22:34:17 factorio factorio[1092]: 9765.502 Info ServerMultiplayerManager.cpp:757: MapTick(1920740) Serving map(/opt/factorio/temp/mp-save-1.zip) for peer(2) size(6048057) crc(1174184990)
Oct 3 22:34:17 factorio factorio[1092]: 9765.503 Info ServerMultiplayerManager.cpp:636: mapTick(1920740) changing state from(InGameSavingMap) to(InGame)
Oct 3 22:34:18 factorio factorio[1092]: 9765.625 Info ServerMultiplayerManager.cpp:720: mapTick(1920748) received stateChanged peerID(2) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
Oct 3 22:34:20 factorio factorio[1092]: 9767.590 Info ServerMultiplayerManager.cpp:720: mapTick(1920866) received stateChanged peerID(2) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
Oct 3 22:34:20 factorio factorio[1092]: 9768.322 Info ServerMultiplayerManager.cpp:720: mapTick(1920910) received stateChanged peerID(2) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
Oct 3 22:34:21 factorio factorio[1092]: 9768.618 Info ServerMultiplayerManager.cpp:720: mapTick(1920927) received stateChanged peerID(2) oldState(TryingToCatchUp) newState(WaitingForCommandToStartSendingTickClosures)
Oct 3 22:34:21 factorio factorio[1092]: 9768.619 Info GameActionHandler.cpp:2549: MapTick(1920927) processed PlayerJoinGame peerID(2) playerIndex(0) mode(connect)
Oct 3 22:34:21 factorio factorio[1092]: 9768.742 Info ServerMultiplayerManager.cpp:720: mapTick(1920935) received stateChanged peerID(2) oldState(WaitingForCommandToStartSendingTickClosures) newState(InGame)
Oct 3 22:34:21 factorio factorio[1092]: 9768.953 Info ServerMultiplayerManager.cpp:720: mapTick(1920948) received stateChanged peerID(2) oldState(InGame) newState(DesyncedWaitingForMap)
Oct 3 22:34:21 factorio factorio[1092]: 9768.953 Info ServerMultiplayerManager.cpp:770: mapTick(1920948) received playerDesynced peer (2)
Oct 3 22:34:21 factorio factorio[1092]: 9768.953 Info ServerMultiplayerManager.cpp:636: mapTick(1920948) changing state from(InGame) to(InGameSavingMapForDesyncReport)
Oct 3 22:34:29 factorio factorio[1092]: 9777.086 Info ServerMultiplayerManager.cpp:757: MapTick(1920948) Serving map(/opt/factorio/temp/mp-save-2.zip) for peer(2) size(45029549) crc(2312515209)
Oct 3 22:34:29 factorio factorio[1092]: 9777.087 Info ServerMultiplayerManager.cpp:636: mapTick(1920948) changing state from(InGameSavingMapForDesyncReport) to(InGame)
Oct 3 22:34:29 factorio factorio[1092]: 9777.208 Info ServerMultiplayerManager.cpp:720: mapTick(1920959) received stateChanged peerID(2) oldState(DesyncedWaitingForMap) newState(DesyncedCatchingUpWithMapReadyForDownload)
Oct 3 22:34:29 factorio factorio[1092]: 9777.226 Info ServerMultiplayerManager.cpp:720: mapTick(1920960) received stateChanged peerID(2) oldState(DesyncedCatchingUpWithMapReadyForDownload) newState(DesyncedSavingLocalVariantOfMap)
Oct 3 22:34:39 factorio factorio[1092]: 9787.284 Info ServerMultiplayerManager.cpp:720: mapTick(1921563) received stateChanged peerID(2) oldState(DesyncedSavingLocalVariantOfMap) newState(DesyncedDownloadingMap)
Oct 3 22:34:45 factorio factorio[1092]: 9792.844 Info ServerMultiplayerManager.cpp:720: mapTick(1921897) received stateChanged peerID(2) oldState(DesyncedDownloadingMap) newState(DesyncedCreatingReport)
Oct 3 22:34:49 factorio factorio[1092]: 9796.615 Info ServerMultiplayerManager.cpp:720: mapTick(1922123) received stateChanged peerID(2) oldState(DesyncedCreatingReport) newState(ConnectedLoadingMap)
Oct 3 22:34:49 factorio factorio[1092]: 9797.324 Info ServerMultiplayerManager.cpp:720: mapTick(1922166) received stateChanged peerID(2) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
Oct 3 22:34:49 factorio factorio[1092]: 9797.375 Info ServerMultiplayerManager.cpp:720: mapTick(1922169) received stateChanged peerID(2) oldState(TryingToCatchUp) newState(DesyncedWaitingForMap)
Oct 3 22:34:49 factorio factorio[1092]: 9797.376 Info ServerMultiplayerManager.cpp:770: mapTick(1922169) received playerDesynced peer (2)
Oct 3 22:34:49 factorio factorio[1092]: 9797.376 Info ServerMultiplayerManager.cpp:636: mapTick(1922169) changing state from(InGame) to(InGameSavingMapForDesyncReport)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:799: Disconnect notification for peer (2)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:720: mapTick(1922169) received stateChanged peerID(2) oldState(DesyncedWaitingForMap) newState(DisconnectScheduled)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:720: mapTick(1922169) received stateChanged peerID(2) oldState(DisconnectScheduled) newState(DesyncedWaitingForMap)
Oct 3 22:34:56 factorio factorio[1092]: 9803.825 Info ServerMultiplayerManager.cpp:770: mapTick(1922169) received playerDesynced peer (2)
Oct 3 22:34:56 factorio factorio[1092]: 9803.842 Info ServerSynchronizer.cpp:539: nextHeartbeatSequenceNumber(288444) removing peer(2).
Oct 3 22:34:57 factorio factorio[1092]: 9804.995 Info ServerMultiplayerManager.cpp:757: MapTick(1922169) Serving map(/opt/factorio/temp/mp-save-3.zip) for peer(2) size(45025482) crc(2956362272)
Oct 3 22:34:57 factorio factorio[1092]: 9804.996 Warning TransferSource.cpp:40: We are already uploading for peer 2
Oct 3 22:34:57 factorio factorio[1092]: 9804.996 Info ServerMultiplayerManager.cpp:757: MapTick(1922169) Serving map(/opt/factorio/temp/mp-save-3.zip) for peer(2) size(45025482) crc(2956362272)
Oct 3 22:34:57 factorio factorio[1092]: 9804.996 Info ServerMultiplayerManager.cpp:636: mapTick(1922169) changing state from(InGameSavingMapForDesyncReport) to(InGame)