Page 1 of 1

Constant "Server is not responding" messages - diagnosis?

Posted: Sat Oct 15, 2016 1:12 pm
by sillyfly
I'm trying to play multiplayer, but I keep getting "Server is not responding" and lag every few seconds, which makes the game completely unplayable (this post on reddit has a video demo of a similar problem, but no answers).

Granted, my connection isn't super-fast, but with 15Mbit down and 0.8Mbit up, and on a fairly small map (the official Factorio team challenge) I didn't expect it to be a limiting factor. I'm getting a good ping (typically 70-80 ms), and looking at a resource monitor it seems like I'm only hitting about 10KB/S up and down.

How can I diagnose what's causing the problem? I've turned on the multiplayer statistics view, but I don't know what the numbers mean.


Edit: Looking at the log, I have a suspicion the game may be too optimistic at lowering the latency and then has trouble keeping up. Does this make sense? If so - could it be possible to tell the game not to lower the latency under a specific value (or at least not too fast)?

Code: Select all

 168.571 Info ClientMultiplayerManager.cpp:538: MapTick(48692700) changing state from(ConnectedLoadingMap) to(TryingToCatchUp)
 175.263 Info ClientMultiplayerManager.cpp:538: MapTick(48697061) changing state from(TryingToCatchUp) to(WaitingForCommandToStartSendingTickClosures)
 175.406 Info ClientMultiplayerManager.cpp:538: MapTick(48697061) changing state from(WaitingForCommandToStartSendingTickClosures) to(InGame)
 175.540 Info GameActionHandler.cpp:2547: MapTick(48697069) processed PlayerJoinGame peerID(54) playerIndex(276) mode(connect)
 182.959 Info ClientSynchronizer.cpp:255: Changing local latency from 32 to 27 (nextTickToSend: 48697506, ticksClientWillJumpOver: 0 {})
 182.959 Info ClientSynchronizer.cpp:255: Changing local latency from 27 to 30 (nextTickToSend: 48697506, ticksClientWillJumpOver: 0 {})
 187.595 Info ClientSynchronizer.cpp:255: Changing local latency from 30 to 55 (nextTickToSend: 48697817, ticksClientWillJumpOver: 0 {})
 187.595 Info ClientSynchronizer.cpp:255: Changing local latency from 55 to 59 (nextTickToSend: 48697842, ticksClientWillJumpOver: 0 {})
 192.796 Info ClientSynchronizer.cpp:255: Changing local latency from 59 to 66 (nextTickToSend: 48698097, ticksClientWillJumpOver: 6 {48698097, 48698098, 48698099, 48698100, 48698101, 48698102})
 197.714 Info ClientSynchronizer.cpp:255: Changing local latency from 66 to 94 (nextTickToSend: 48698442, ticksClientWillJumpOver: 0 {})
 202.766 Info ClientSynchronizer.cpp:255: Changing local latency from 94 to 102 (nextTickToSend: 48698696, ticksClientWillJumpOver: 5 {48698696, 48698697, 48698698, 48698699, 48698700})
 209.518 Info ClientSynchronizer.cpp:255: Changing local latency from 102 to 128 (nextTickToSend: 48699103, ticksClientWillJumpOver: 0 {})
 217.603 Info ClientSynchronizer.cpp:255: Changing local latency from 128 to 158 (nextTickToSend: 48699667, ticksClientWillJumpOver: 0 {})
 230.073 Info ClientSynchronizer.cpp:255: Changing local latency from 158 to 183 (nextTickToSend: 48700335, ticksClientWillJumpOver: 0 {})
 232.774 Info ClientSynchronizer.cpp:255: Changing local latency from 183 to 194 (nextTickToSend: 48700605, ticksClientWillJumpOver: 0 {})
 242.580 Info ClientSynchronizer.cpp:255: Changing local latency from 194 to 222 (nextTickToSend: 48701199, ticksClientWillJumpOver: 0 {})
 242.614 Info ClientSynchronizer.cpp:255: Changing local latency from 222 to 226 (nextTickToSend: 48701229, ticksClientWillJumpOver: 0 {})
 252.582 Info ClientSynchronizer.cpp:255: Changing local latency from 226 to 251 (nextTickToSend: 48701814, ticksClientWillJumpOver: 0 {})
 267.619 Info ClientSynchronizer.cpp:255: Changing local latency from 251 to 254 (nextTickToSend: 48702729, ticksClientWillJumpOver: 0 {})
 267.835 Info ClientSynchronizer.cpp:255: Changing local latency from 254 to 19 (nextTickToSend: 48702745, ticksClientWillJumpOver: 0 {})
 272.771 Info ClientSynchronizer.cpp:255: Changing local latency from 19 to 42 (nextTickToSend: 48702918, ticksClientWillJumpOver: 0 {})
 282.607 Info ClientSynchronizer.cpp:255: Changing local latency from 42 to 67 (nextTickToSend: 48703524, ticksClientWillJumpOver: 0 {})
 292.593 Info ClientSynchronizer.cpp:255: Changing local latency from 67 to 91 (nextTickToSend: 48704136, ticksClientWillJumpOver: 0 {})
 302.582 Info ClientSynchronizer.cpp:255: Changing local latency from 91 to 115 (nextTickToSend: 48704747, ticksClientWillJumpOver: 2 {48704747, 48704748})
 308.866 Info ClientSynchronizer.cpp:255: Changing local latency from 115 to 138 (nextTickToSend: 48705064, ticksClientWillJumpOver: 0 {})
 308.866 Info ClientSynchronizer.cpp:255: Changing local latency from 138 to 140 (nextTickToSend: 48705087, ticksClientWillJumpOver: 0 {})
 313.202 Info ClientSynchronizer.cpp:255: Changing local latency from 140 to 164 (nextTickToSend: 48705322, ticksClientWillJumpOver: 24 {48705322, 48705323, 48705324, 48705325, 48705326, 48705327, 48705328, 48705329, 48705330, 48705331, 48705332, 48705333, 48705334, 48705335, 48705336, 48705337, 48705338, 48705339, 48705340, 48705341, 48705342, 48705343, 48705344, 48705345})
 317.585 Info ClientSynchronizer.cpp:255: Changing local latency from 164 to 189 (nextTickToSend: 48705683, ticksClientWillJumpOver: 0 {})
 329.372 Info ClientSynchronizer.cpp:255: Changing local latency from 189 to 215 (nextTickToSend: 48706294, ticksClientWillJumpOver: 0 {})
 332.572 Info ClientSynchronizer.cpp:255: Changing local latency from 215 to 224 (nextTickToSend: 48706609, ticksClientWillJumpOver: 0 {})
 342.612 Info ClientSynchronizer.cpp:255: Changing local latency from 224 to 116 (nextTickToSend: 48707221, ticksClientWillJumpOver: 0 {})
 347.762 Info ClientSynchronizer.cpp:255: Changing local latency from 116 to 240 (nextTickToSend: 48707276, ticksClientWillJumpOver: 124 {48707276, 48707277, 48707278, 48707279, 48707280, 48707281, 48707282, 48707283, 48707284, 48707285, 48707286, 48707287, 48707288, 48707289, 48707290, 48707291, 48707292, 48707293, 48707294, 48707295, 48707296, 48707297, 48707298, 48707299, 48707300, 48707301, 48707302, 48707303, 48707304, 48707305, 48707306, 48707307, 48707308, 48707309, 48707310, 48707311, 48707312, 48707313, 48707314, 48707315, 48707316, 48707317, 48707318, 48707319, 48707320, 48707321, 48707322, 48707323, 48707324, 48707325, 48707326, 48707327, 48707328, 48707329, 48707330, 48707331, 48707332, 48707333, 48707334, 48707335, 48707336, 48707337, 48707338, 48707339, 48707340, 48707341, 48707342, 48707343, 48707344, 48707345, 48707346, 48707347, 48707348, 48707349, 48707350, 48707351, 48707352, 48707353, 48707354, 48707355, 48707356, 48707357, 48707358, 48707359, 48707360, 48707361, 48707362, 48707363, 48707364, 48707365, 48707366, 48707367, 48707368, 48707369, 48707370, 48707371, 48707372, 48707373, 48707374, 48707375, 48707376, 48707377, 48707378, 48707379, 48707380, 48707381, 48707382, 48707383, 48707384, 48707385, 48707386, 48707387, 48707388, 48707389, 48707390, 48707391, 48707392, 48707393, 48707394, 48707395, 48707396, 48707397, 48707398, 48707399})
 347.763 Info ClientSynchronizer.cpp:255: Changing local latency from 240 to 244 (nextTickToSend: 48707400, ticksClientWillJumpOver: 126 {48707400, 48707401, 48707402, 48707403, 48707404, 48707405, 48707406, 48707407, 48707408, 48707409, 48707410, 48707411, 48707412, 48707413, 48707414, 48707415, 48707416, 48707417, 48707418, 48707419, 48707420, 48707421, 48707422, 48707423, 48707424, 48707425, 48707426, 48707427, 48707428, 48707429, 48707430, 48707431, 48707432, 48707433, 48707434, 48707435, 48707436, 48707437, 48707438, 48707439, 48707440, 48707441, 48707442, 48707443, 48707444, 48707445, 48707446, 48707447, 48707448, 48707449, 48707450, 48707451, 48707452, 48707453, 48707454, 48707455, 48707456, 48707457, 48707458, 48707459, 48707460, 48707461, 48707462, 48707463, 48707464, 48707465, 48707466, 48707467, 48707468, 48707469, 48707470, 48707471, 48707472, 48707473, 48707474, 48707475, 48707476, 48707477, 48707478, 48707479, 48707480, 48707481, 48707482, 48707483, 48707484, 48707485, 48707486, 48707487, 48707488, 48707489, 48707490, 48707491, 48707492, 48707493, 48707494, 48707495, 48707496, 48707497, 48707498, 48707499, 48707500, 48707501, 48707502, 48707503, 48707504, 48707505, 48707506, 48707507, 48707508, 48707509, 48707510, 48707511, 48707512, 48707513, 48707514, 48707515, 48707516, 48707517, 48707518, 48707519, 48707520, 48707521, 48707522, 48707523, 48707524, 48707525})
 352.596 Info ClientSynchronizer.cpp:255: Changing local latency from 244 to 254 (nextTickToSend: 48707825, ticksClientWillJumpOver: 0 {})
 352.612 Info ClientSynchronizer.cpp:255: Changing local latency from 254 to 6 (nextTickToSend: 48707836, ticksClientWillJumpOver: 0 {})
 357.597 Info ClientSynchronizer.cpp:255: Changing local latency from 6 to 32 (nextTickToSend: 48708005, ticksClientWillJumpOver: 0 {})
 368.301 Info ClientSynchronizer.cpp:255: Changing local latency from 32 to 57 (nextTickToSend: 48708629, ticksClientWillJumpOver: 0 {})
 373.302 Info ClientSynchronizer.cpp:255: Changing local latency from 57 to 58 (nextTickToSend: 48708899, ticksClientWillJumpOver: 2 {48708899, 48708900})
 373.302 Info ClientSynchronizer.cpp:255: Changing local latency from 58 to 62 (nextTickToSend: 48708900, ticksClientWillJumpOver: 4 {48708900, 48708901, 48708902, 48708903})
 378.121 Info ClientSynchronizer.cpp:255: Changing local latency from 62 to 87 (nextTickToSend: 48709234, ticksClientWillJumpOver: 0 {})
 388.107 Info ClientSynchronizer.cpp:255: Changing local latency from 87 to 114 (nextTickToSend: 48709846, ticksClientWillJumpOver: 0 {})
 398.095 Info ClientSynchronizer.cpp:255: Changing local latency from 114 to 141 (nextTickToSend: 48710458, ticksClientWillJumpOver: 0 {})
 413.298 Info ClientSynchronizer.cpp:255: Changing local latency from 141 to 67 (nextTickToSend: 48711397, ticksClientWillJumpOver: 0 {})
 418.116 Info ClientSynchronizer.cpp:255: Changing local latency from 67 to 43 (nextTickToSend: 48711636, ticksClientWillJumpOver: 0 {})
 423.185 Info ClientSynchronizer.cpp:255: Changing local latency from 43 to 67 (nextTickToSend: 48711928, ticksClientWillJumpOver: 0 {})
 423.185 Info ClientSynchronizer.cpp:255: Changing local latency from 67 to 69 (nextTickToSend: 48711952, ticksClientWillJumpOver: 0 {})
 438.106 Info ClientSynchronizer.cpp:255: Changing local latency from 69 to 91 (nextTickToSend: 48712837, ticksClientWillJumpOver: 0 {})
 449.091 Info ClientSynchronizer.cpp:255: Changing local latency from 91 to 113 (nextTickToSend: 48713446, ticksClientWillJumpOver: 0 {})
 449.091 Info ClientSynchronizer.cpp:255: Changing local latency from 113 to 115 (nextTickToSend: 48713468, ticksClientWillJumpOver: 0 {})
 454.910 Info ClientSynchronizer.cpp:255: Changing local latency from 115 to 127 (nextTickToSend: 48713796, ticksClientWillJumpOver: 0 {})
 458.260 Info ClientSynchronizer.cpp:255: Changing local latency from 127 to 140 (nextTickToSend: 48714033, ticksClientWillJumpOver: 7 {48714033, 48714034, 48714035, 48714036, 48714037, 48714038, 48714039})
 463.114 Info ClientSynchronizer.cpp:255: Changing local latency from 140 to 163 (nextTickToSend: 48714373, ticksClientWillJumpOver: 0 {})
 473.101 Info ClientSynchronizer.cpp:255: Changing local latency from 163 to 189 (nextTickToSend: 48714983, ticksClientWillJumpOver: 0 {})
 478.067 Info ClientSynchronizer.cpp:255: Changing local latency from 189 to 208 (nextTickToSend: 48715273, ticksClientWillJumpOver: 0 {})
 478.402 Info ClientSynchronizer.cpp:255: Changing local latency from 208 to 212 (nextTickToSend: 48715323, ticksClientWillJumpOver: 0 {})
 505.145 Info ClientMultiplayerManager.cpp:538: MapTick(48715447) changing state from(InGame) to(WaitingForUserToSaveOrQuitAfterServerLeft)
 506.962 Info ClientMultiplayerManager.cpp:177: Quitting multiplayer connection.
 506.962 Info ClientMultiplayerManager.cpp:538: MapTick(48715447) changing state from(WaitingForUserToSaveOrQuitAfterServerLeft) to(Disconnected)
 506.999 Verbose RouterBase.cpp:60: Finishing router thread.
 506.999 Info UDPSocket.cpp:153: Socket closed
 508.682 Goodbye

Re: Constant "Server is not responding" messages - diagnosis?

Posted: Mon Nov 07, 2016 12:59 am
by draktok
I'm getting the same issue.

Re: Constant "Server is not responding" messages - diagnosis?

Posted: Thu Nov 10, 2016 11:11 pm
by Punchofdeath
I have this same problem as well. A friend and I have been playing and it doesn't matter which of us hosts we still get a lot of lag and "Server is not responding" messages. I've set up a port forward for Factorio hoping it'd help but does not. I done a speed test based on a server that is further away than he lives to me and this was the results: Image

I am on wifi and he is on ethernet, and we usually join each other through Steam by using the "Join Game" thing in the chat.

Re: Constant "Server is not responding" messages - diagnosis?

Posted: Mon Nov 28, 2016 12:08 am
by Moppleton
I'm getting the same thing happening every few hours. It's causing major problems for us.

We've tried restarting computers, routers, etc on both sides. Nothing seems to reliably solve this until it just decides it's going to go away.

My log shows similar messages. Minimal "ticksClientWillJumpOver" until it happens, also shifts in the latency values.
2096.369 Info ClientSynchronizer.cpp:255: Changing local latency from 9 to 21 (nextTickToSend: 689107, ticksClientWillJumpOver: 0 {})
2101.370 Info ClientSynchronizer.cpp:255: Changing local latency from 21 to 25 (nextTickToSend: 689413, ticksClientWillJumpOver: 0 {})
2111.370 Info ClientSynchronizer.cpp:255: Changing local latency from 25 to 17 (nextTickToSend: 690017, ticksClientWillJumpOver: 0 {})
2121.369 Info ClientSynchronizer.cpp:255: Changing local latency from 17 to 15 (nextTickToSend: 690611, ticksClientWillJumpOver: 0 {})
2131.353 Info ClientSynchronizer.cpp:255: Changing local latency from 15 to 11 (nextTickToSend: 691213, ticksClientWillJumpOver: 0 {})
2161.719 Info ClientSynchronizer.cpp:255: Changing local latency from 11 to 27 (nextTickToSend: 693007, ticksClientWillJumpOver: 0 {})
2171.720 Info ClientSynchronizer.cpp:255: Changing local latency from 27 to 9 (nextTickToSend: 693624, ticksClientWillJumpOver: 0 {})
2186.719 Info ClientSynchronizer.cpp:255: Changing local latency from 9 to 10 (nextTickToSend: 694505, ticksClientWillJumpOver: 0 {})
2246.720 Info ClientSynchronizer.cpp:255: Changing local latency from 10 to 16 (nextTickToSend: 698105, ticksClientWillJumpOver: 0 {})
2246.753 Info ClientSynchronizer.cpp:255: Changing local latency from 16 to 19 (nextTickToSend: 698113, ticksClientWillJumpOver: 0 {})
2262.103 Info ClientSynchronizer.cpp:255: Changing local latency from 19 to 35 (nextTickToSend: 699011, ticksClientWillJumpOver: 3 {699011, 699012, 699013})
2267.120 Info ClientSynchronizer.cpp:255: Changing local latency from 35 to 93 (nextTickToSend: 699328, ticksClientWillJumpOver: 3 {699328, 699329, 699330})
2272.086 Info ClientSynchronizer.cpp:255: Changing local latency from 93 to 163 (nextTickToSend: 699684, ticksClientWillJumpOver: 3 {699684, 699685, 699686})
2277.119 Info ClientSynchronizer.cpp:255: Changing local latency from 163 to 190 (nextTickToSend: 700056, ticksClientWillJumpOver: 5 {700056, 700057, 700058, 700059, 700060})
2282.102 Info ClientSynchronizer.cpp:255: Changing local latency from 190 to 254 (nextTickToSend: 700382, ticksClientWillJumpOver: 4 {700382, 700383, 700384, 700385})
2282.119 Info ClientSynchronizer.cpp:255: Changing local latency from 254 to 123 (nextTickToSend: 700447, ticksClientWillJumpOver: 5 {700447, 700448, 700449, 700450, 700451})
Speedtest indicates my connection shouldn't be an issue. The server's connection is similarly decent.

Image

It's worth noting also that I'm the client, and it has only happened for me so far. This is the third time since last night. I have tried connecting to other servers and NOT had this issue. It seems to be a problem specific to both client and server.

The game is otherwise enjoyable but this one issue is preventing us from playing entirely.