[kovarex] [0.14.17] Latency compensation too optimistic on LAN
[kovarex] [0.14.17] Latency compensation too optimistic on LAN
Clients experience periodic lag spikes due to an empty buffer when playing on low latency (5-25ms) connections like a LAN. Please allow manually setting this buffer or some other form of manually adjusting the latency compensation like in previous versions of the game. There's no reason you can't have automatic compensation and manual adjustment at the same time. So far this automatic system is not an improvement over .13 for me.
Re: [0.14.17] Latency compensation too optimistic on LAN
Please upload the log after a session with the issue.
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
Where is the log file stored and do I have to set any flags on the server to generate one?
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
See my signature.Generico wrote:Where is the log file stored and do I have to set any flags on the server to generate one?
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
This is probably something very specific to your lan connection, but to be sure there is a solution I added minimum_latency_in_ticks option for the next version.
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
Is this option only relevant when hosting, or is this client-side?
Also, the config file now has "minimum_latency_in_multiplayer". Can you confirm which is the correct parameter (Or are there two?)
Also, the config file now has "minimum_latency_in_multiplayer". Can you confirm which is the correct parameter (Or are there two?)
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
It can be only setup when hosting.sillyfly wrote:Is this option only relevant when hosting, or is this client-side?
Also, the config file now has "minimum_latency_in_multiplayer". Can you confirm which is the correct parameter (Or are there two?)
The config value is for starting the game from gui (As I didn't want to put this specific option into the gui to confuse people).
The server-settings value is used only when hosting as a headless server.
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
Hi,
We (2 players) are playing on a LAN on [0.16.51] and the client is experience huge lags. More precisely, the movement lags, the player's figure jumps. It appears to happen more often if the host player is deconstructing many entities in a row. Whenever it happens, the server reports that the client has been kicked out of the game, but only for a brief period of time until everything continues as usual.
Also, moving in a vehicle is plain impossible because the vehicle keeps turning and does not properly react to keystrokes.
The host system runs smoothly as in single player, and both computers have a smooth single player experience. Only the client system suffers from this replication issue. We also exchanged roles of client and server and observed the same issue.
Note that this appears to be unrelated to the periodic blocking save.
I have experimented with the following values of minimum_latency_in_multiplayer on the host :
0 [default]: lag
200: extremely huge lags, unplayable (as expected)
20: lag
5: lag
1: lag
We do not see significant differences between 1,5,20, and 0 and have kept it at 1 for now (was the last attempt).
According to Windows 'ping', we have a ping of about 5ms.
Note that one system is Windows (typically the server) and one is Linux.
Do we experience a duplicate of the issue mentioned in this thread? Should I start a new thread? Is this some kind of known issue?
--------------------------------
client log file:
-------------------------------------------
server log file:
We (2 players) are playing on a LAN on [0.16.51] and the client is experience huge lags. More precisely, the movement lags, the player's figure jumps. It appears to happen more often if the host player is deconstructing many entities in a row. Whenever it happens, the server reports that the client has been kicked out of the game, but only for a brief period of time until everything continues as usual.
Also, moving in a vehicle is plain impossible because the vehicle keeps turning and does not properly react to keystrokes.
The host system runs smoothly as in single player, and both computers have a smooth single player experience. Only the client system suffers from this replication issue. We also exchanged roles of client and server and observed the same issue.
Note that this appears to be unrelated to the periodic blocking save.
I have experimented with the following values of minimum_latency_in_multiplayer on the host :
0 [default]: lag
200: extremely huge lags, unplayable (as expected)
20: lag
5: lag
1: lag
We do not see significant differences between 1,5,20, and 0 and have kept it at 1 for now (was the last attempt).
According to Windows 'ping', we have a ping of about 5ms.
Note that one system is Windows (typically the server) and one is Linux.
Do we experience a duplicate of the issue mentioned in this thread? Should I start a new thread? Is this some kind of known issue?
--------------------------------
client log file:
Code: Select all
0.000 2018-07-31 15:53:51; Factorio 0.16.51 (build 36654, linux64, steam)
0.103 Operating system: Linux (Ubuntu 16.04)
0.103 Program arguments: "/home/XXXXX/.local/share/Steam/steamapps/common/Factorio/bin/x64/factorio"
0.103 Read data path: /home/XXXXX/.local/share/Steam/steamapps/common/Factorio/data
0.103 Write data path: /home/XXXXX/.factorio [53059/107852MB]
0.103 Binaries path: /home/XXXXX/.local/share/Steam/steamapps/common/Factorio/bin
0.288 System info: [CPU: AMD Phenom(tm) II X4 945 Processor, 4 cores, RAM: 12014 MB]
0.288 Environment: DISPLAY=:0, WAYLAND_DISPLAY=<unset>
0.336 Display options: [FullScreen: 1] [VSync: 1] [UIScale: system (100.0%)] [MultiSampling: OFF] [Screen: 255] [Lang: de]
0.395 Available display adapters: 1
0.395 [0]: resolution 1280x1024px at [0,0]
0.395 Create display on adapter 0. Size 1230x720 at position [15, 134].
0.983 Initialised OpenGL:[0] GeForce GTX 650 Ti BOOST/PCIe/SSE2; driver: 4.5.0 NVIDIA 364.19
0.984 Video memory size (dedicated/total available/current available): 2048/2048/1843 MB
1.183 Graphics settings preset: high
1.183 Graphics options: [Graphics quality: normal] [Video memory usage: high] [Light scale: 25%] [DXT: false] [Shader: 1]
1.183 [Parallel sprite loading: 1] [Max texture size: 0/8192] [Bmp cache: 0] [Sprite slicing: 1] [Low quality rotation: 0]
2.963 Loading mod core 0.0.0 (data.lua)
3.377 Loading mod base 0.16.51 (data.lua)
4.900 Loading mod base 0.16.51 (data-updates.lua)
5.079 Checksum for core: 840319042
5.079 Checksum of base: 3323233190
5.716 Loading sounds...
7.333 Info PlayerData.cpp:67: Local player-data.json unavailable
7.333 Info PlayerData.cpp:70: Cloud player-data.json available, timestamp 1532849121
7.913 Loaded shader file /home/XXXXX/.local/share/Steam/steamapps/common/Factorio/data/core/graphics/shaders/game.glsl
7.937 Loaded shader file /home/XXXXX/.local/share/Steam/steamapps/common/Factorio/data/core/graphics/shaders/zoom-to-world.glsl
7.939 Loaded shader file /home/XXXXX/.local/share/Steam/steamapps/common/Factorio/data/core/graphics/shaders/alpha-mask.glsl
7.967 Initial atlas bitmap size is 16384
7.972 Created atlas bitmap 16384x5940 [none]
7.973 Created atlas bitmap 16384x847 [shadow]
7.974 Created atlas bitmap 16384x682 [decal]
7.975 Created atlas bitmap 8192x1492 [mipmap]
7.975 Created atlas bitmap 8192x800 [mipmap, smoke]
7.976 Created atlas bitmap 8192x2376 [linear-minification, mipmap, terrain]
7.977 Created atlas bitmap 8192x1036 [no-crop, trilinear-filtering, icon, light]
7.977 Created atlas bitmap 8192x238 [alpha-mask]
37.916 Sprites loaded
37.916 Convert atlas 8192x1492 to: mipmap
38.155 Convert atlas 8192x800 to: mipmap
38.252 Convert atlas 8192x2376 to: min-linear
38.566 Convert atlas 8192x1036 to: trilinear-filtering
38.707 Convert atlas 8192x238 to: alpha-mask
38.765 Custom inputs active: 0
38.925 Factorio initialised
62.570 Info UDPSocket.cpp:27: Opening socket at (IP ADDR:({0.0.0.0:34196}))
62.570 Info UDPSocket.cpp:27: Opening socket at (IP ADDR:({0.0.0.0:0}))
144.731 Info UDPSocket.cpp:206: Closing socket
144.732 Info UDPSocket.cpp:236: Socket closed
144.732 Info UDPSocket.cpp:206: Closing socket
144.796 Info UDPSocket.cpp:206: Closing socket
144.796 Info UDPSocket.cpp:236: Socket closed
144.796 Info UDPSocket.cpp:206: Closing socket
144.807 Joining game IP ADDR:({192.168.42.27:34197})
144.807 Info UDPSocket.cpp:33: Opening socket
144.807 Info ClientMultiplayerManager.cpp:573: MapTick(4294967295) changing state from(Ready) to(Connecting)
144.864 Info ClientSynchronizer.cpp:35: Initialized Synchronizer local peer(1) latency(32).
144.864 Info ClientMultiplayerManager.cpp:573: MapTick(4294967295) changing state from(Connecting) to(ConnectedWaitingForMap)
144.864 Info ClientRouter.cpp:238: ConnectionAccepted
146.579 Info ClientMultiplayerManager.cpp:745: Received mapReadyForDownload
146.579 Downloading file /home/XXXXX/.factorio/temp/mp-download.zip (21592527 B, 42928 blocks)
146.586 Info ClientMultiplayerManager.cpp:573: MapTick(4294967295) changing state from(ConnectedWaitingForMap) to(ConnectedDownloadingMap)
154.734 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (404) outside latency window [445, 1045)
154.734 Info UnparsedNetworkMessage.cpp:102: Message data: 27 02 94 01 00 00 02 6f df 39 00 02 35 02 cd eb df 4e 6e df 39 00 70 df 39 00 02 35 02 a6 0f a4 73 6f df 39 00
167.921 Finished download (21 s, 21.5 MB, 1.0 MB/s)
167.965 Info ClientMultiplayerManager.cpp:725: mapTick(4294967295) map download finished creating scenario
167.965 Info ClientMultiplayerManager.cpp:573: MapTick(4294967295) changing state from(ConnectedDownloadingMap) to(ConnectedLoadingMap)
167.984 Loading map /home/XXXXX/.factorio/temp/mp-download.zip: 21592527 bytes.
168.004 Loading Level.dat: 44488614 bytes.
168.020 Info Scenario.cpp:136: Map version 0.16.51-0
169.441 Loading script.dat: 176 bytes.
169.467 Checksum for script /home/XXXXX/.factorio/temp/currently-playing/control.lua: 950616531
169.501 Info ClientMultiplayerManager.cpp:573: MapTick(3792347) changing state from(ConnectedLoadingMap) to(TryingToCatchUp)
171.855 Info ClientMultiplayerManager.cpp:573: MapTick(3793866) changing state from(TryingToCatchUp) to(WaitingForCommandToStartSendingTickClosures)
171.954 Info ClientMultiplayerManager.cpp:573: MapTick(3793866) changing state from(WaitingForCommandToStartSendingTickClosures) to(InGame)
172.021 Info GameActionHandler.cpp:3476: MapTick (3793870) processed PlayerJoinGame peerID(1) playerIndex(0) mode(connect )
996.110 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (25626) outside latency window [25672, 26272)
996.110 Info UnparsedNetworkMessage.cpp:102: Message data: 07 12 1a 64 00 00 02 6a a3 3a 00 04 23 01 35 01 37 11 d3 0a 69 a3 3a 00 6b a3 3a 00 04 23 01 35 01 cd f5 f6 7f 6a a3 3a 00 02 0f 6a a3 3a 00 00 00 0f 6b a3 3a 00 00 00
996.643 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (25626) outside latency window [25688, 26288)
996.643 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 1a 64 00 00 02 6a a3 3a 00 04 23 01 35 01 37 11 d3 0a 69 a3 3a 00 6b a3 3a 00 04 23 01 35 01 cd f5 f6 7f 6a a3 3a 00 02 0f 6a a3 3a 00 00 00 0f 6b a3 3a 00 00 00
996.660 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (25626) outside latency window [25689, 26289)
996.660 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 1a 64 00 00 02 6a a3 3a 00 04 23 01 35 01 37 11 d3 0a 69 a3 3a 00 6b a3 3a 00 04 23 01 35 01 cd f5 f6 7f 6a a3 3a 00 02 0f 6a a3 3a 00 00 00 0f 6b a3 3a 00 00 00
9875.276 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (291903) outside latency window [291937, 292537)
9875.276 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 3f 74 04 00 02 f8 b8 42 00 04 23 01 35 01 65 e8 e1 f8 f7 b8 42 00 f9 b8 42 00 04 23 01 35 01 5f bc 1e 3a f8 b8 42 00 02 0f f8 b8 42 00 00 00 0f f9 b8 42 00 00 00
9876.343 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (291903) outside latency window [291951, 292551)
9876.343 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 3f 74 04 00 02 f8 b8 42 00 04 23 01 35 01 65 e8 e1 f8 f7 b8 42 00 f9 b8 42 00 04 23 01 35 01 5f bc 1e 3a f8 b8 42 00 02 0f f8 b8 42 00 00 00 0f f9 b8 42 00 00 00
9876.343 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (291903) outside latency window [291952, 292552)
9876.343 Info UnparsedNetworkMessage.cpp:102: Message data: 07 12 3f 74 04 00 02 f8 b8 42 00 04 23 01 35 01 65 e8 e1 f8 f7 b8 42 00 f9 b8 42 00 04 23 01 35 01 5f bc 1e 3a f8 b8 42 00 02 0f f8 b8 42 00 00 00 0f f9 b8 42 00 00 00
9876.343 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (291903) outside latency window [291963, 292563)
9876.343 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 3f 74 04 00 02 f8 b8 42 00 04 23 01 35 01 65 e8 e1 f8 f7 b8 42 00 f9 b8 42 00 04 23 01 35 01 5f bc 1e 3a f8 b8 42 00 02 0f f8 b8 42 00 00 00 0f f9 b8 42 00 00 00
9876.343 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (291903) outside latency window [291963, 292563)
9876.343 Info UnparsedNetworkMessage.cpp:102: Message data: 07 12 3f 74 04 00 02 f8 b8 42 00 04 23 01 35 01 65 e8 e1 f8 f7 b8 42 00 f9 b8 42 00 04 23 01 35 01 5f bc 1e 3a f8 b8 42 00 02 0f f8 b8 42 00 00 00 0f f9 b8 42 00 00 00
9876.343 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({192.168.42.27:34197}): heartbeat sequence number (291903) outside latency window [291967, 292567)
9876.343 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 3f 74 04 00 02 f8 b8 42 00 04 23 01 35 01 65 e8 e1 f8 f7 b8 42 00 f9 b8 42 00 04 23 01 35 01 5f bc 1e 3a f8 b8 42 00 02 0f f8 b8 42 00 00 00 0f f9 b8 42 00 00 00
12679.987 Info AppManagerStates.cpp:1639: Saving finished
12682.769 Info ClientMultiplayerManager.cpp:141: Disconnecting multiplayer connection.
12682.769 Info ClientMultiplayerManager.cpp:573: MapTick(4539843) changing state from(InGame) to(DisconnectScheduled)
12682.793 Info ClientMultiplayerManager.cpp:573: MapTick(4539843) changing state from(DisconnectScheduled) to(WaitingForDisconnectConfirmation)
12682.842 Info ClientMultiplayerManager.cpp:573: MapTick(4539843) changing state from(WaitingForDisconnectConfirmation) to(Disconnected)
12682.859 Info ClientMultiplayerManager.cpp:886: Disconnect notification for peer (1)
12683.217 Info UDPSocket.cpp:206: Closing socket
12683.217 Info UDPSocket.cpp:236: Socket closed
12684.552 Info GlobalContext.cpp:696: Waiting for child processes to exit:
12684.871 Steam API shutdown.
12684.967 Goodbye
server log file:
Code: Select all
0.009 2018-07-31 15:54:29; Factorio 0.16.51 (build 36654, win64, steam)
0.009 Operating system: Windows 10 (version 1709)
0.010 Program arguments: "C:\Program Files (x86)\Steam\steamapps\common\Factorio\bin\x64\Factorio.exe"
0.010 Read data path: C:/Program Files (x86)/Steam/steamapps/common/Factorio/data
0.010 Write data path: C:/Users/ludewich/AppData/Roaming/Factorio [155113/471652MB]
0.010 Binaries path: C:/Program Files (x86)/Steam/steamapps/common/Factorio/bin
0.096 System info: [CPU: AMD A10-6700 APU with Radeon(tm) HD Graphics , 4 cores, RAM: 2452/8150 MB, page: 3190/13782 MB, virtual: 228/134217727 MB, extended virtual: 0 MB]
0.097 Display options: [FullScreen: 1] [VSync: 0] [UIScale: custom (90.0%)] [MultiSampling: OFF] [Screen: 255] [Lang: de]
0.099 Available display adapters: 2
0.099 [0]: \\.\DISPLAY1 - NVIDIA GeForce GTX 750 Ti {0x05, [0,0], 1280x1024, 32bit, 60Hz}
0.099 [1]: \\.\DISPLAY2 - NVIDIA GeForce GTX 750 Ti {0x01, [1280,0], 1024x768, 32bit, 75Hz}
0.099 Create display on adapter 0. Size 1230x720 at position [15, 134].
0.316 Initialised Direct3D:[0] NVIDIA GeForce GTX 750 Ti; driver: nvldumdx.dll 24.21.13.9836
0.326 Video memory size (dedicated video/dedicated system/shared system/available): 2007/0/4075/4091 MB
0.376 DSound: Starting _dsound_update thread
0.376 DSound: Enter _dsound_update; tid=1848
0.377 Device reset internal.
0.380 Desktop composition is active.
0.380 Graphics settings preset: high
0.380 Graphics options: [Graphics quality: normal] [Video memory usage: high] [Light scale: 25%] [DXT: false] [Shader: 1]
0.380 [Parallel sprite loading: 1] [Max texture size: 0/8192] [Bmp cache: 0] [Sprite slicing: 1] [Low quality rotation: 0]
0.667 Loading mod core 0.0.0 (data.lua)
0.775 Loading mod base 0.16.51 (data.lua)
1.968 Loading mod base 0.16.51 (data-updates.lua)
2.105 Checksum for core: 840319042
2.105 Checksum of base: 3323233190
2.329 Loading sounds...
9.967 Info PlayerData.cpp:67: Local player-data.json unavailable
9.967 Info PlayerData.cpp:70: Cloud player-data.json available, timestamp 1532724611
10.542 Loaded shader file C:/Program Files (x86)/Steam/steamapps/common/Factorio/data/core/graphics/shaders/game.cso
10.581 Loaded shader file C:/Program Files (x86)/Steam/steamapps/common/Factorio/data/core/graphics/shaders/zoom-to-world.cso
10.604 Loaded shader file C:/Program Files (x86)/Steam/steamapps/common/Factorio/data/core/graphics/shaders/alpha-mask.cso
10.633 Initial atlas bitmap size is 16384
10.639 Created atlas bitmap 16384x5944 [none]
11.133 Created atlas bitmap 16384x847 [shadow]
11.170 Created atlas bitmap 16384x682 [decal]
11.212 Created atlas bitmap 8192x1496 [mipmap]
11.242 Created atlas bitmap 8192x800 [mipmap, smoke]
11.259 Created atlas bitmap 8192x2376 [linear-minification, mipmap, terrain]
11.330 Created atlas bitmap 8192x1036 [no-crop, trilinear-filtering, icon, light]
11.358 Created atlas bitmap 8192x238 [alpha-mask]
77.000 Sprites loaded
77.000 Convert atlas 8192x1496 to: mipmap
77.094 Convert atlas 8192x800 to: mipmap
77.154 Convert atlas 8192x2376 to: min-linear
77.309 Convert atlas 8192x1036 to: trilinear-filtering
77.379 Convert atlas 8192x238 to: alpha-mask
77.909 Custom inputs active: 0
78.067 Factorio initialised
100.074 Info ServerSynchronizer.cpp:29: nextHeartbeatSequenceNumber(0) initialized Synchronizer nextTickClosureTick(0).
100.074 Info ServerMultiplayerManager.cpp:699: mapTick(4294967295) changing state from(Ready) to(PreparedToHostGame)
100.074 Info ServerMultiplayerManager.cpp:699: mapTick(4294967295) changing state from(PreparedToHostGame) to(CreatingGame)
100.075 Loading map C:\Users\ludewich\AppData\Roaming\Factorio\saves\papa.zip: 22320579 bytes.
100.491 Loading Level.dat: 44488429 bytes.
100.499 Info Scenario.cpp:136: Map version 0.16.51-0
102.306 Loading script.dat: 176 bytes.
102.352 Checksum for script C:/Users/ludewich/AppData/Roaming/Factorio/temp/currently-playing/control.lua: 950616531
102.366 Info UDPSocket.cpp:27: Opening socket at (IP ADDR:({0.0.0.0:34197}))
102.379 Hosting game at IP ADDR:({0.0.0.0:34197})
102.379 Info ServerMultiplayerManager.cpp:699: mapTick(3792166) changing state from(CreatingGame) to(InGame)
102.379 Info UDPSocket.cpp:39: Opening socket for broadcast
102.380 Info GameActionHandler.cpp:3476: MapTick (3792166) processed PlayerJoinGame peerID(0) playerIndex(1) mode(connect )
105.557 ConnectionRequestReplyConfirm (type(ConnectionRequestReplyConfirm) ) from(IP ADDR:({192.168.42.31:35050}))
105.576 Info ServerRouter.cpp:409: Replying to connectionRequest for address(IP ADDR:({192.168.42.31:35050})).
105.576 Info ServerSynchronizer.cpp:557: nextHeartbeatSequenceNumber(155) adding peer(1)
105.643 Info ServerMultiplayerManager.cpp:699: mapTick(3792347) changing state from(InGame) to(InGameSavingMap)
105.644 Info ServerMultiplayerManager.cpp:795: mapTick(3792347) received stateChanged peerID(1) oldState(Ready) newState(ConnectedWaitingForMap)
107.271 Info ServerMultiplayerManager.cpp:855: MapTick(3792347) Serving map(C:\Users\ludewich\AppData\Roaming\Factorio\temp\mp-save-0.zip) for peer(1) size(21592527) crc(2700192502)
107.271 Info ServerMultiplayerManager.cpp:699: mapTick(3792347) changing state from(InGameSavingMap) to(InGame)
107.340 Info ServerMultiplayerManager.cpp:795: mapTick(3792354) received stateChanged peerID(1) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
128.708 Info ServerMultiplayerManager.cpp:795: mapTick(3793635) received stateChanged peerID(1) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
130.274 Info ServerMultiplayerManager.cpp:795: mapTick(3793729) received stateChanged peerID(1) oldState(ConnectedLoadingMap) newState(TryingToCatchUp)
132.624 Info ServerMultiplayerManager.cpp:795: mapTick(3793870) received stateChanged peerID(1) oldState(TryingToCatchUp) newState(WaitingForCommandToStartSendingTickClosures)
132.624 Info GameActionHandler.cpp:3476: MapTick (3793870) processed PlayerJoinGame peerID(1) playerIndex(0) mode(connect )
132.691 Info ServerMultiplayerManager.cpp:795: mapTick(3793874) received stateChanged peerID(1) oldState(WaitingForCommandToStartSendingTickClosures) newState(InGame)
201.459 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
203.153 Info AppManagerStates.cpp:1639: Saving finished
503.141 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
504.814 Info AppManagerStates.cpp:1639: Saving finished
804.842 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
806.567 Info AppManagerStates.cpp:1639: Saving finished
1106.558 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
1108.180 Info AppManagerStates.cpp:1639: Saving finished
1408.209 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
1409.958 Info AppManagerStates.cpp:1639: Saving finished
1709.942 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
1711.580 Info AppManagerStates.cpp:1639: Saving finished
2011.576 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
2013.254 Info AppManagerStates.cpp:1639: Saving finished
2313.243 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
2315.156 Info AppManagerStates.cpp:1639: Saving finished
2615.158 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
2616.884 Info AppManagerStates.cpp:1639: Saving finished
2916.908 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
2918.576 Info AppManagerStates.cpp:1639: Saving finished
3218.574 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
3220.185 Info AppManagerStates.cpp:1639: Saving finished
3520.675 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
3522.318 Info AppManagerStates.cpp:1639: Saving finished
3822.308 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
3823.951 Info AppManagerStates.cpp:1639: Saving finished
4123.941 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
4125.588 Info AppManagerStates.cpp:1639: Saving finished
4425.575 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
4427.281 Info AppManagerStates.cpp:1639: Saving finished
4727.308 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
4728.948 Info AppManagerStates.cpp:1639: Saving finished
5028.942 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
5030.664 Info AppManagerStates.cpp:1639: Saving finished
5330.659 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
5332.309 Info AppManagerStates.cpp:1639: Saving finished
5632.309 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
5634.034 Info AppManagerStates.cpp:1639: Saving finished
5934.026 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
5935.663 Info AppManagerStates.cpp:1639: Saving finished
6235.658 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
6237.353 Info AppManagerStates.cpp:1639: Saving finished
6537.341 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
6539.139 Info AppManagerStates.cpp:1639: Saving finished
6839.142 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
6840.900 Info AppManagerStates.cpp:1639: Saving finished
7140.891 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
7142.653 Info AppManagerStates.cpp:1639: Saving finished
7442.659 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
7444.264 Info AppManagerStates.cpp:1639: Saving finished
7744.258 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
7745.907 Info AppManagerStates.cpp:1639: Saving finished
8045.925 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
8047.628 Info AppManagerStates.cpp:1639: Saving finished
8347.625 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
8349.335 Info AppManagerStates.cpp:1639: Saving finished
8649.342 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
8651.080 Info AppManagerStates.cpp:1639: Saving finished
8951.076 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
8952.740 Info AppManagerStates.cpp:1639: Saving finished
9252.724 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
9254.401 Info AppManagerStates.cpp:1639: Saving finished
9554.393 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
9556.005 Info AppManagerStates.cpp:1639: Saving finished
9855.992 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
9857.608 Info AppManagerStates.cpp:1639: Saving finished
10006.918 Info AppManagerStates.cpp:1639: Saving finished
10159.375 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
10161.340 Info AppManagerStates.cpp:1639: Saving finished
10461.342 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
10463.161 Info AppManagerStates.cpp:1639: Saving finished
10763.224 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
10765.057 Info AppManagerStates.cpp:1639: Saving finished
11065.059 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
11066.915 Info AppManagerStates.cpp:1639: Saving finished
11366.908 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
11368.772 Info AppManagerStates.cpp:1639: Saving finished
11668.759 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
11670.619 Info AppManagerStates.cpp:1639: Saving finished
11970.609 Info AppManager.cpp:260: Saving to _autosave1 (blocking).
11972.574 Info AppManagerStates.cpp:1639: Saving finished
12272.576 Info AppManager.cpp:260: Saving to _autosave2 (blocking).
12274.422 Info AppManagerStates.cpp:1639: Saving finished
12574.408 Info AppManager.cpp:260: Saving to _autosave3 (blocking).
12576.281 Info AppManagerStates.cpp:1639: Saving finished
12643.457 Info ServerMultiplayerManager.cpp:901: Disconnect notification for peer (1)
12643.457 Info ServerMultiplayerManager.cpp:795: mapTick(4539843) received stateChanged peerID(1) oldState(InGame) newState(DisconnectScheduled)
12643.457 Info ServerSynchronizer.cpp:576: nextHeartbeatSequenceNumber(376129) removing peer(1).
12645.368 Info AppManagerStates.cpp:1639: Saving finished
12648.306 Info ServerMultiplayerManager.cpp:118: Disconnecting multiplayer connection.
12648.306 Info ServerMultiplayerManager.cpp:699: mapTick(4540020) changing state from(InGame) to(DisconnectingScheduled)
12648.306 Info ServerMultiplayerManager.cpp:699: mapTick(4540020) changing state from(DisconnectingScheduled) to(Disconnecting)
12648.324 Info ServerMultiplayerManager.cpp:699: mapTick(4540020) changing state from(Disconnecting) to(Disconnected)
12648.325 Info ServerMultiplayerManager.cpp:142: Quitting multiplayer connection.
12648.325 Info ServerMultiplayerManager.cpp:699: mapTick(4540020) changing state from(Disconnected) to(Closed)
12648.648 Info UDPSocket.cpp:206: Closing socket
12648.648 Info UDPSocket.cpp:236: Socket closed
12648.648 Info UDPSocket.cpp:206: Closing socket
12648.782 Info UDPSocket.cpp:206: Closing socket
12648.782 Info UDPSocket.cpp:236: Socket closed
12650.174 DSound: Stopping voice
12650.174 DSound: Joining thread
12650.179 DSound: Exit _dsound_update; tid=1848
12650.179 DSound: Waiting for voice to stop ... signaled
12650.180 DSound: Joined thread
12650.180 DSound: Destroying thread
12650.180 DSound: Thread destroyed
12650.180 DSound: Releasing buffer
12650.180 DSound: Voice stopped
12650.180 DSound: Deallocating voice
12650.180 DSound: Deallocated voice
12650.226 Steam API shutdown.
12650.231 Goodbye
Re: [kovarex] [0.14.17] Latency compensation too optimistic on LAN
Regarding my last comment:
I finally found out that the lags were due to our local wireless configuration. I installed a cable and everything is fine.
The issue showed itself as spikes in ping response times: typically, both computers in the local network had a ping of less than 5ms. But now and then, they come with pings of up to 1000ms, resulting in an average of about 300ms. This might be related to wireless autoconfig, but turning this off did not improve issues.
I suppose the perdiodic (?) or occasional lags in factorio were caused by these wireless ping spikes . Apparently, factorio is highly sensitive to a fast network. We never had such issues with other games.
So: not a duplicate and maybe not even a bug. Perhaps this could be used as feature request for factorio, some of sorts "please improve the networking code such that it handles more network types as other games do".
I finally found out that the lags were due to our local wireless configuration. I installed a cable and everything is fine.
The issue showed itself as spikes in ping response times: typically, both computers in the local network had a ping of less than 5ms. But now and then, they come with pings of up to 1000ms, resulting in an average of about 300ms. This might be related to wireless autoconfig, but turning this off did not improve issues.
I suppose the perdiodic (?) or occasional lags in factorio were caused by these wireless ping spikes . Apparently, factorio is highly sensitive to a fast network. We never had such issues with other games.
So: not a duplicate and maybe not even a bug. Perhaps this could be used as feature request for factorio, some of sorts "please improve the networking code such that it handles more network types as other games do".