Page 1 of 1

headless server - download map stops

Posted: Mon Jun 08, 2020 8:07 am
by EoRaptor
I have setup a headless linux server.
I can connect through either LAN or the public game-browser.
A second person connects without problems through either steam-list or the public game-browser without problems as well.

A 3rd person trying to connect through the public-game browser is disconnected when only partially through the download of the map.
Each time that is at 1.9MB of the download. The server simply stops and shows as not-responding to that person and he is dropped from the game.

The logging shows the ServerMultiplayerManager state as ConnectedWaitingForMap and later as ConnectedDownloadingMap.
MapDownloadingProgressUpdate from peer is processed, but after a short period, the peer is disconnected.

Any idea in what direction I have to look in order to solve this issue?

Regards,

Re: headless server - download map stops

Posted: Mon Jun 08, 2020 10:42 am
by Loewchen
Try reducing the download speed in the server settings.

Re: headless server - download map stops

Posted: Mon Jun 08, 2020 2:38 pm
by EoRaptor
Thanks for the reply!
Lowering that setting worked.

I must admit that i was skeptic on the answer till i tested that and seen that it worked.


I am curious to know why that works like this. Do you know the answer to the why?

Regards,

Re: headless server - download map stops

Posted: Mon Jun 08, 2020 2:50 pm
by Loewchen
The servers upload speed is limited and it gets congested uploading the map, so the packets sent to the connected player get dropped. Reducing the upload speed avoids the congestion.

Re: headless server - download map stops

Posted: Wed Jun 17, 2020 2:19 pm
by bigmond
Just some additional information since I am the player that was having trouble connecting.

I get to 1.8-1.9MB downloaded and the download stops and I get booted.

This happens on any server. I have tried modded and unmodded. On a brand new map I can connectr fine but once the map grows over a couple MB It stops cloase to 2MB and kicks me.

I have done a resinstall of factorio including clearing the appdata folder.

  • Connect to modded headless +server Using browse games - Connection stops around 2MB
  • Connect to vanilla headless server - Connection stops around 2MB
  • Connect to headless server using IP address on LAN (default settings, imported large map.) - Downloads close to 10MB/s and downloads successfully
  • Connect to headless server that is shown on LAN using browse LAN games - Downloads close to 10MB/s and downloads succefully
  • Connect to headless server using IP address over wan. - Stops at clost to 2MB then disconnects
  • Connect to headless server from public games list. - Stops at clost to 2MB then disconnects
  • Tried several randomly selsected unmodded servers from public games list, stops close to 2MB then disconnects.
  • Connect to player hosted game from public games list - Download stutters, Downloads at 1MB/s initially, stops and drops to 0, waits for several seconds and then repeats.
This is the log file when I get disconnected trying to connect

Code: Select all

 
    0.001 2020-06-17 14:23:28; Factorio 0.18.32 (build 52799, win64, steam)
   0.001 Operating system: Windows 10 (version 2004) 
   0.002 Program arguments: "C:\Program Files (x86)\Steam\steamapps\common\Factorio\bin\x64\Factorio.exe" "--wait-to-close" "14696" "--mp-connect" "xxx.xxx.xxx.16:34197" 
   0.002 Read data path: C:/Program Files (x86)/Steam/steamapps/common/Factorio/data
   0.002 Write data path: C:/Users/BigMonD/AppData/Roaming/Factorio [94606/226357MB]
   0.002 Binaries path: C:/Program Files (x86)/Steam/steamapps/common/Factorio/bin
   0.017 System info: [CPU: AMD FX(tm)-8350 Eight-Core Processor, 8 cores, RAM: 6278/16121 MB, page: 8073/35449 MB, virtual: 4327/134217727 MB, extended virtual: 0 MB]
   0.018 Display options: [FullScreen: 1] [VSync: 1] [UIScale: automatic (100.0%)] [Native DPI: 1] [Screen: 255] [Special: lmw] [Lang: en]
   0.030 Available displays: 2
   0.031  [1]: \\.\DISPLAY2 - NVIDIA GeForce GTX 1060 3GB {0x05, [0,0], 1920x1080, 32bit, 60Hz}
   0.031  [0]: \\.\DISPLAY1 - NVIDIA GeForce GTX 1060 3GB {0x01, [-1920,0], 1920x1080, 32bit, 60Hz}
   0.166 [Direct3D11] Display: 0, Output: 0, DisplayAdapter: 0, RenderingAdapter: 0; d3dcompiler_47.dll
   0.168 Verbose GraphicsInterfaceDX11.cpp:263: DXGI 1.5 detected, Allow tearing is supported
   0.171  Adapter [0]: NVIDIA GeForce GTX 1060 3GB {10de-1c02} (26.21.14.4614)
   0.174  Adapter [1]: Microsoft Basic Render Driver {1414-8c} (10.0.19041.84)
   0.387 Initialised Direct3D[0]: NVIDIA GeForce GTX 1060 3GB; id: 10de-1c02; driver: nvldumdx.dll 26.21.14.4614
   0.387   D3D Feature Level: 11.1, DXGI 1.5+, SwapChain: 3,flip-discard,-,-,-,none
   0.387   [Local Video Memory] Budget: 2539MB, CurrentUsage: 2MB, Reservation: 0/1344MB
   0.388   [Non-Local Vid.Mem.] Budget: 7401MB, CurrentUsage: 0MB, Reservation: 0/3828MB
   0.388   Tiled resources: Tier 2
   0.388   Unified Memory Architecture: No
   0.388   BGR 565 Supported: Yes
   0.388   MaximumFrameLatency: 3, GPUThreadPriority: 0
   0.388 Graphics settings preset: very-high
   0.388   Dedicated video memory size 2988 MB
   0.453 Verbose PipelineStateObject.cpp:85: Time to load shaders: 0.062873 seconds.
   0.459 Desktop composition is active.
   0.459 Graphics options: [Graphics quality: high] [Video memory usage: all] [Light scale: 25%] [DXT: high-quality] [Color: 32bit]
   0.459                   [Max threads (load/render): 32/8] [Max texture size: 0] [Tex.Stream.: 0] [Rotation quality: normal] [Other: STDCWT] [B:0,C:0,S:100]
   0.583 DSound: Starting _dsound_update thread
   0.583 DSound: Enter _dsound_update; tid=15872
   0.830 Info ModManager.cpp:253: Found duplicate mod DeathMarkers, using higher version (0.3.1 > 0.3.0).
   0.885 Info ModManager.cpp:253: Found duplicate mod space-exploration, using higher version (0.3.81 > 0.3.77).
   0.970 Verbose PipelineStateObject.cpp:77: Time to load shaders in background: 0.517573 seconds.
   0.982 Info ModManager.cpp:253: Found duplicate mod space-exploration-postprocess, using higher version (0.3.19 > 0.3.18).
   1.027 Info ModManager.cpp:253: Found duplicate mod train-pubsub, using higher version (0.6.19 > 0.6.18).
   1.035 Loading mod core 0.0.0 (data.lua)
   1.223 Loading mod base 0.18.32 (data.lua)
   1.776 Loading mod base 0.18.32 (data-updates.lua)
   2.048 Checksum for core: 533635165
   2.048 Checksum of base: 2402425971
   2.390 Prototype list checksum: 156825909
   2.393 Verbose ModManager.cpp:554: Time to load mods: 1.68821
   2.487 Loading sounds...
   2.698 Info PlayerData.cpp:70: Local player-data.json unavailable
   2.698 Info PlayerData.cpp:73: Cloud player-data.json available, timestamp 1592399501
   2.975 Initial atlas bitmap size is 16384
   3.036 Created atlas bitmap 16384x16368 [none]
   3.042 Created atlas bitmap 16384x11428 [none]
   3.043 Created atlas bitmap 8192x3736 [decal]
   3.046 Created atlas bitmap 16384x5248 [low-object, linear-minification]
   3.047 Created atlas bitmap 8192x3760 [mipmap, linear-minification, linear-magnification, linear-mip-level]
   3.048 Created atlas bitmap 16384x5776 [terrain, mipmap, linear-minification, linear-mip-level]
   3.049 Created atlas bitmap 4096x2848 [terrain-effect-map, mipmap, linear-minification, linear-mip-level]
   3.049 Created atlas bitmap 4096x1600 [smoke, mipmap, linear-minification, linear-magnification]
   3.050 Created atlas bitmap 4096x3104 [mipmap]
   3.051 Created atlas bitmap 4096x2144 [icon, not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level]
   3.051 Created atlas bitmap 2048x192 [icon-background, not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level, ]
   3.051 Created atlas bitmap 4096x2652 [alpha-mask]
   3.056 Created atlas bitmap 16384x14748 [shadow, linear-magnification, alpha-mask]
   3.057 Created atlas bitmap 8192x2352 [shadow, mipmap, linear-magnification, alpha-mask]
   3.064 Verbose TextureProcessor.cpp:1140: GPU accelerated compression test: Time 7.408 ms; YCoCg_DXT PSNR: 35.83, BC3 PSNR: 33.82
   3.064 Texture processor created (2048). GPU accelerated compression Supported: yes, Enabled: yes/yes. Test passed. YCoCgDXT PSNR: 35.83, BC3 PSNR: 33.82
   4.337 Parallel Sprite Loader initialized (threads: 7)
  17.098 Sprites loaded
  17.156 Generated mipmaps (4) for atlas [3] of size 16384x5248   
  17.178 Generated mipmaps (3) for atlas [4] of size 8192x3760   
  17.223 Generated mipmaps (3) for atlas [5] of size 16384x5776   
  17.234 Generated mipmaps (3) for atlas [6] of size 4096x2848   
  17.245 Generated mipmaps (3) for atlas [7] of size 4096x1600   
  17.296 Generated mipmaps (3) for atlas [8] of size 4096x3104   
  17.308 Generated mipmaps (5) for atlas [9] of size 4096x2144   
  17.320 Generated mipmaps (3) for atlas [13] of size 8192x2352   
  17.332 Custom mipmaps uploaded.
  17.332 Verbose AtlasSystem.cpp:909: Atlas memory size: 1125.37MB; 0.00MB (virtual)
  17.332 Verbose AtlasSystem.cpp:910: Size of sprites outside of atlas: 0.13MB
  17.442 Factorio initialised
  17.445 Joining game IP ADDR:({xxx.xxx.xxx.16:34197})
  17.445 Info UDPSocket.cpp:33: Opening socket
  17.447 Verbose RouterBase.cpp:60: Started router thread.
  17.447 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(Ready) to(Connecting)
  17.450 Verbose SteamContext.cpp:553: SteamUserStats initialized.
  17.668 Connection refused
  17.669 Info ClientMultiplayerManager.cpp:202: Quitting multiplayer connection.
  17.669 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(Connecting) to(Disconnected)
  17.670 Info HttpSharedState.cpp:54: Downloading https://auth.factorio.com/generate-user-server-key-2?api_version=4
  18.162 Info AuthServerConnector.cpp:97: Received key from auth server.
  18.163 Verbose RouterBase.cpp:82: Finishing router thread.
  18.164 Info UDPSocket.cpp:218: Closing socket
  18.164 Info UDPSocket.cpp:248: Socket closed
  18.164 Joining game IP ADDR:({xxx.xxx.xxx.16:34197})
  18.164 Info UDPSocket.cpp:33: Opening socket
  18.165 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(Ready) to(Connecting)
  18.165 Verbose RouterBase.cpp:60: Started router thread.
  18.437 Info ClientSynchronizer.cpp:35: Initialized Synchronizer local peer(1) latency(32).
  18.437 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(Connecting) to(ConnectedWaitingForMap)
  18.437 Info ClientRouter.cpp:221: ConnectionAccepted
  18.736 Info ClientMultiplayerManager.cpp:750: Received mapReadyForDownload
  18.736 Downloading file C:\Users\BigMonD\AppData\Roaming\Factorio\temp\mp-download.zip (4692138 B, 9329 blocks)
  18.738 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(ConnectedWaitingForMap) to(ConnectedDownloadingMap)
  46.969 Warning ClientSynchronizer.cpp:89: nextHeartbeatSequenceNumber(562865385) server wants a heartbeat(562864580) that we don't have.
  47.535 Warning ClientSynchronizer.cpp:89: nextHeartbeatSequenceNumber(562865402) server wants a heartbeat(562864580) that we don't have.
  47.670 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(ConnectedDownloadingMap) to(WaitingForUserToSaveOrQuitAfterServerLeft)
  47.819 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911211) outside latency window [2910411, 2911211)
  47.819 Info UnparsedNetworkMessage.cpp:102: Message data: 27 00 eb 6b 2c 00
  47.836 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911212) outside latency window [2910411, 2911211)
  47.836 Info UnparsedNetworkMessage.cpp:102: Message data: 27 00 ec 6b 2c 00
  47.886 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911213) outside latency window [2910411, 2911211)
  47.886 Info UnparsedNetworkMessage.cpp:102: Message data: 07 10 ed 6b 2c 00 01 0b f9 07 00
  47.918 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911214) outside latency window [2910411, 2911211)
  47.918 Info UnparsedNetworkMessage.cpp:102: Message data: 07 00 ee 6b 2c 00
  47.936 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911215) outside latency window [2910411, 2911211)
  47.936 Info UnparsedNetworkMessage.cpp:102: Message data: 07 00 ef 6b 2c 00
  47.986 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911216) outside latency window [2910411, 2911211)
  47.986 Info UnparsedNetworkMessage.cpp:102: Message data: 27 10 f0 6b 2c 00 01 0b fa 07 00
  48.018 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911217) outside latency window [2910411, 2911211)
  48.018 Info UnparsedNetworkMessage.cpp:102: Message data: 07 00 f1 6b 2c 00
  48.052 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911218) outside latency window [2910411, 2911211)
  48.052 Info UnparsedNetworkMessage.cpp:102: Message data: 27 00 f2 6b 2c 00
  48.086 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911219) outside latency window [2910411, 2911211)
  48.086 Info UnparsedNetworkMessage.cpp:102: Message data: 27 10 f3 6b 2c 00 01 0b fb 07 00
  48.118 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911220) outside latency window [2910411, 2911211)
  48.119 Info UnparsedNetworkMessage.cpp:102: Message data: 27 01 f4 6b 2c 00 01 c4 a1 8c 21
  48.153 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911221) outside latency window [2910411, 2911211)
  48.153 Info UnparsedNetworkMessage.cpp:102: Message data: 07 00 f5 6b 2c 00
  48.186 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911222) outside latency window [2910411, 2911211)
  48.186 Info UnparsedNetworkMessage.cpp:102: Message data: 07 10 f6 6b 2c 00 01 0b fc 07 00
  48.218 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911223) outside latency window [2910411, 2911211)
  48.218 Info UnparsedNetworkMessage.cpp:102: Message data: 27 00 f7 6b 2c 00
  48.252 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911224) outside latency window [2910411, 2911211)
  48.252 Info UnparsedNetworkMessage.cpp:102: Message data: 07 00 f8 6b 2c 00
  48.285 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911225) outside latency window [2910411, 2911211)
  48.285 Info UnparsedNetworkMessage.cpp:102: Message data: 07 10 f9 6b 2c 00 01 0b fd 07 00
  48.319 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911226) outside latency window [2910411, 2911211)
  48.319 Info UnparsedNetworkMessage.cpp:102: Message data: 07 00 fa 6b 2c 00
  48.351 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911227) outside latency window [2910411, 2911211)
  48.351 Info UnparsedNetworkMessage.cpp:102: Message data: 07 10 fb 6b 2c 00 01 0b fe 07 00
  48.385 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from IP ADDR:({xxx.xxx.xxx.16:34197}): heartbeat sequence number (2911228) outside latency window [2910411, 2911211)
  48.386 Info UnparsedNetworkMessage.cpp:102: Message data: 27 10 fc 6b 2c 00 01 01 01 07 00
  50.138 Info ClientMultiplayerManager.cpp:202: Quitting multiplayer connection.
  50.138 Info ClientMultiplayerManager.cpp:581: UpdateTick(4294967295) changing state from(WaitingForUserToSaveOrQuitAfterServerLeft) to(Disconnected)
  50.139 Verbose RouterBase.cpp:82: Finishing router thread.
  50.140 Info UDPSocket.cpp:218: Closing socket
  50.140 Info UDPSocket.cpp:248: Socket closed
  52.268 Quitting: user-quit.
  52.407 DSound: Stopping voice
  52.407 DSound: Joining thread
  52.410 DSound: Exit _dsound_update; tid=15872
  52.410 DSound: Waiting for voice to stop ... signaled
  52.411 DSound: Joined thread
  52.411 DSound: Destroying thread
  52.411 DSound: Thread destroyed
  52.411 DSound: Releasing buffer
  52.411 DSound: Voice stopped
  52.411 DSound: Deallocating voice
  52.411 DSound: Deallocated voice
  52.540 Steam API shutdown.
  52.545 Goodbye