[0.17.44] Non blocking save geting stuck on waiting to save
Posted: Fri May 31, 2019 5:16 pm
by Cooldude2606
I have as erver which has non blokcing save enabled but from time to time when I try to connect it will get stuck on "waiting for the server to save the map" when I look at the log for the server it seams like it might be saving the map before my client is ready to recive it, see log extracts below for relvant connections. Of note is that we have had no problems with autosaves only when joining and that the problem seams to resolve its self after some amount of time without any changes to how I am connecting.
Client-Server Details
Server:
Factorio 0.17.45 (build 45219, linux64, headless)
Operating system: Linux (Ubuntu 18.04)
Client:
Factorio 0.17.45 (build 45219, win64, steam)
Operating system: Windows 10 (version 1809)
Factorio 0.17.45 (build 45219, linux64, headless)
Operating system: Linux (Ubuntu 18.04)
Client:
Factorio 0.17.45 (build 45219, win64, steam)
Operating system: Windows 10 (version 1809)
Server Side
Code: Select all
3167.376 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({IPv4:55110}))
3167.376 Refusing connection for address (IP ADDR:({IPv4:55110})), username (Cooldude2606). UserVerificationMissing
3167.878 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({IPv4:55110}))
3167.879 Refusing connection for address (IP ADDR:({IPv4:55110})), username (Cooldude2606). UserVerificationMissing
3168.708 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({IPv4:58058}))
3168.708 Info ServerRouter.cpp:423: Replying to connectionRequest for address(IP ADDR:({IPv4:58058})).
3168.708 Info ServerSynchronizer.cpp:564: nextHeartbeatSequenceNumber(92586) adding peer(28)
3168.726 Info ServerMultiplayerManager.cpp:776: updateTick(160016) changing state from(InGame) to(InGameSavingMap)
3169.035 Info ServerMultiplayerManager.cpp:977: UpdateTick(160016) Serving map(/opt/factorio-servers/factorio-eu-01/temp/mp-save-27.zip) for peer(28) size(4984722) crc(1841797037)
3169.035 Info ServerMultiplayerManager.cpp:776: updateTick(160016) changing state from(InGameSavingMap) to(InGame)
3169.208 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({IPv4:58058}))
3169.208 Info ServerRouter.cpp:423: Replying to connectionRequest for address(IP ADDR:({IPv4:58058})).
3169.469 Info ServerMultiplayerManager.cpp:909: updateTick(160042) received stateChanged peerID(28) oldState(Ready) newState(ConnectedWaitingForMap)
Client Side
Code: Select all
1613.263 Joining game IP ADDR:({IPv4:34197})
1613.263 Info UDPSocket.cpp:33: Opening socket
1613.264 Info ClientMultiplayerManager.cpp:579: UpdateTick(4294967295) changing state from(Ready) to(Connecting)
1613.509 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.509 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.509 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.509 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.509 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.526 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.559 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.592 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.626 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.659 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.693 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.726 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.759 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1613.809 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({IPv4:34197}): invalid state (WaitingForAccept) for packet type
1614.009 Info ClientSynchronizer.cpp:35: Initialized Synchronizer local peer(1) latency(32).
1614.009 Info ClientMultiplayerManager.cpp:579: UpdateTick(4294967295) changing state from(Connecting) to(ConnectedWaitingForMap)
1614.010 Info ClientRouter.cpp:224: ConnectionAccepted
1614.010 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ConnectionAcceptOrDeny) received from IP ADDR:({IPv4:34197}): invalid state (Connected) for packet type
1614.559 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ConnectionAcceptOrDeny) received from IP ADDR:({IPv4:34197}): invalid state (Connected) for packet type
Server Side Success
Note: ip addresses have been removed, username has been removed in successful attempt since it was not me.Code: Select all
3274.749 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({IPv4:55872}))
3274.750 Refusing connection for address (IP ADDR:({IPv4:55872})), username (<removed>). UserVerificationMissing
3276.926 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({IPv4:64157}))
3276.926 Info ServerRouter.cpp:423: Replying to connectionRequest for address(IP ADDR:({IPv4:64157})).
3276.927 Info ServerSynchronizer.cpp:564: nextHeartbeatSequenceNumber(95707) adding peer(29)
3276.945 Info ServerMultiplayerManager.cpp:776: updateTick(166471) changing state from(InGame) to(InGameSavingMap)
3277.254 Info ServerMultiplayerManager.cpp:977: UpdateTick(166471) Serving map(/opt/factorio-servers/factorio-eu-01/temp/mp-save-28.zip) for peer(29) size(5036337) crc(819439324)
3277.254 Info ServerMultiplayerManager.cpp:776: updateTick(166471) changing state from(InGameSavingMap) to(InGame)
3278.169 Info ServerMultiplayerManager.cpp:909: updateTick(166525) received stateChanged peerID(29) oldState(Ready) newState(ConnectedWaitingForMap)
3278.324 Info ServerMultiplayerManager.cpp:909: updateTick(166534) received stateChanged peerID(29) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
3280.363 Info ServerMultiplayerManager.cpp:909: updateTick(166656) received stateChanged peerID(29) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
3284.946 Info ServerMultiplayerManager.cpp:909: updateTick(166931) received stateChanged peerID(29) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
3285.721 Info ServerMultiplayerManager.cpp:909: updateTick(166978) received stateChanged peerID(29) oldState(TryingToCatchUp) newState(WaitingForCommandToStartSendingTickClosures)
3285.722 Info GameActionHandler.cpp:4067: UpdateTick (166978) processed PlayerJoinGame peerID(29) playerIndex(7) mode(create)