lloesche / valheim-server-docker

Valheim dedicated gameserver with automatic update, World backup, BepInEx and ValheimPlus mod support
https://hub.docker.com/r/lloesche/valheim-server
Apache License 2.0
1.96k stars 276 forks source link

Server stop working at fixed hour everyday #682

Open Nicbobbo6 opened 6 months ago

Nicbobbo6 commented 6 months ago

Hello, I've been using this container since some times without problem, but recently i faced a bug. Every day at 5:00, my server stop responding and i have to restart the container. Please find my docker compose and the logs of the container :

May 26 04:45:15 supervisord: valheim-updater OK May 26 04:45:15 supervisord: valheim-updater Waiting for client config...OK May 26 04:45:15 supervisord: valheim-updater Waiting for user info... May 26 04:45:21 supervisord: valheim-updater OK May 26 04:45:21 supervisord: valheim-updater Update state (0x3) reconfiguring, progress: 0.00 (0 / 0) May 26 04:45:21 supervisord: valheim-updater Update state (0x5) verifying install, progress: 16.18 (379578661 / 2346408748) May 26 04:45:21 supervisord: valheim-updater Update state (0x5) verifying install, progress: 47.83 (1122265701 / 2346408748) May 26 04:45:21 supervisord: valheim-updater Update state (0x5) verifying install, progress: 91.02 (2135610149 / 2346408748) May 26 04:45:21 supervisord: valheim-updater Success! App '896660' fully installed. May 26 04:45:21 supervisord: valheim-updater .d..t...... ./ May 26 04:45:21 supervisord: valheim-updater INFO - Valheim Server is already the latest version May 26 04:46:12 supervisord: valheim-server 05/26/2024 04:46:12: Connections 0 ZDOS:652484 sent:0 recv:0 May 26 04:56:12 supervisord: valheim-server 05/26/2024 04:56:12: Connections 0 ZDOS:652484 sent:0 recv:0 May 26 05:00:01 CRON[24563]: pam_unix(cron:session): session opened for user valheim(uid=1003) by (uid=0) May 26 05:00:01 CRON[24562]: pam_unix(cron:session): session opened for user valheim(uid=1003) by (uid=0) May 26 05:00:01 CRON[24564]: pam_unix(cron:session): session opened for user valheim(uid=1003) by (uid=0) May 26 05:00:01 CRON[24565]: (valheim) CMD ([ -f "/var/run/valheim/valheim-updater.pid" ] && kill -HUP $(cat /var/run/valheim/valheim-updater.pid)) May 26 05:00:01 CRON[24566]: (valheim) CMD (/usr/local/bin/valheim-is-idle && /usr/local/bin/supervisorctl restart valheim-server) May 26 05:00:01 CRON[24567]: (valheim) CMD ([ -f "/var/run/valheim/valheim-backup.pid" ] && kill -HUP $(cat /var/run/valheim/valheim-backup.pid)) May 26 05:00:01 CRON[24564]: pam_unix(cron:session): session closed for user valheim May 26 05:00:01 CRON[24563]: pam_unix(cron:session): session closed for user valheim 2024-05-26 05:00:01,840 INFO waiting for valheim-server to stop May 26 05:00:01 supervisord: valheim-server DEBUG - [102] - Received signal to shut down valheim-server May 26 05:00:01 supervisord: valheim-server INFO - Shutting down Valheim server with PID 106 May 26 05:00:01 supervisord: valheim-server DEBUG - [102] - Waiting for Valheim Server with PID 106 to shut down May 26 05:00:01 supervisord: valheim-server 05/26/2024 05:00:01: Game - OnApplicationQuit May 26 05:00:01 supervisord: valheim-server Am I Host? True May 26 05:00:01 supervisord: valheim-server 05/26/2024 05:00:01: Available space to current user: 1592758517760. Saving is blocked if below: 63509574 bytes. Warnings are given if below: 127019148 May 26 05:00:01 supervisord: valheim-server 05/26/2024 05:00:01: Shutting down May 26 05:00:01 supervisord: valheim-server 05/26/2024 05:00:01: ZNet Shutdown May 26 05:00:02 supervisord: valheim-server 05/26/2024 05:00:02: PrepareSave: clone done in 116ms May 26 05:00:02 supervisord: valheim-server 05/26/2024 05:00:02: PrepareSave: ZDOExtraData.PrepareSave done in 125 ms May 26 05:00:02 supervisord: valheim-server 05/26/2024 05:00:02: World save writing starting May 26 05:00:02 supervisord: valheim-server 05/26/2024 05:00:02: World save writing started 2024-05-26 05:00:03,840 INFO waiting for valheim-server to stop May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Saved 652484 ZDOs May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: World save writing finishing May 26 05:00:04 supervisord: valheim-updater DEBUG - [42] - Received signal to check for update May 26 05:00:04 supervisord: valheim-updater DEBUG - [42] - Kernel: Linux b1b4b426ec7e 6.1.0-0.deb11.13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.55-1~bpo11+1 (2023-10-08) x86_64 GNU/Linux May 26 05:00:04 supervisord: valheim-updater DEBUG - [42] - Found CPU with 4207.789 MHz May 26 05:00:04 supervisord: valheim-updater DEBUG - [42] - Memory total/free/available: 16242944/977660/6134892 May 26 05:00:04 supervisord: valheim-updater DEBUG - [42] - Storage configuration: May 26 05:00:04 supervisord: valheim-updater Filesystem Size Used Avail Use% Mounted on May 26 05:00:04 supervisord: valheim-updater overlay 109G 27G 76G 26% / May 26 05:00:04 supervisord: valheim-updater /dev/md0 11T 9.4T 1.5T 87% /data May 26 05:00:04 supervisord: valheim-updater overlay / overlay rw,relatime,lowerdir=/var/lib/docker/overlay2/l/S2VIO4MSDAY64ZCYMZ3LPS4XV5:/var/lib/docker/overlay2/l/4KZJG6B6YGWCVKOSOZO2V4EMKL:/var/lib/docker/overlay2/l/7KWIOSBH7NIFDMNPUFKCK7EJ5M:/var/lib/docker/overlay2/l/CQ3GNU2MF3YKTQDIRY2SQXO6AE:/var/lib/docker/overlay2/l/XKAPKSI37OZI22SRXXF4EZ7XNQ:/var/lib/docker/overlay2/l/ZDF7UZKTPURLIRO3SHLNIMO6PQ:/var/lib/docker/overlay2/l/MXYIO6XQAHKOWIWMIHA7C3VAOU:/var/lib/docker/overlay2/l/6IFIDVUYDHOGWXUAVZAVPKYXD6,upperdir=/var/lib/docker/overlay2/0a6eb3b4023e5379881db68dfc0dac655da68d47f46f07ee955c2b903cf78922/diff,workdir=/var/lib/docker/overlay2/0a6eb3b4023e5379881db68dfc0dac655da68d47f46f07ee955c2b903cf78922/work 0 0 May 26 05:00:04 supervisord: valheim-updater /dev/md0 /config ext4 rw,relatime,stripe=384,jqfmt=vfsv0,usrjquota=aquota.user,grpjquota=aquota.group 0 0 May 26 05:00:04 supervisord: valheim-updater /dev/md0 /data ext4 rw,relatime,stripe=384,jqfmt=vfsv0,usrjquota=aquota.user,grpjquota=aquota.group 0 0 May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: World save writing finished May 26 05:00:04 supervisord: valheim-updater DEBUG - [42] - No players connected to Valheim server May 26 05:00:04 supervisord: valheim-updater INFO - Downloading/updating/validating Valheim server from Steam May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: World saved ( 2297.58ms ) May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Considering autobackup. World time: 63829.58, short time: 7200, long time: 43200, backup count: 4 May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: No autobackup needed yet... May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Unloading unused assets May 26 05:00:04 supervisord: valheim-server Unloading 0 Unused Serialized files (Serialized files now loaded: 1135) May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Sending disconnect msg May 26 05:00:04 supervisord: valheim-server ZPlayFabMatchmaking::UnregisterServer - unregistering server now. State: Uninitialized May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Disposing socket May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Stopping listening socket May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Last socket, unregistering callback May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: ZSteamSocket UnregisterGlobalCallbacks, existing sockets:0 May 26 05:00:04 supervisord: valheim-server 05/26/2024 05:00:04: Stopping build thread 2024-05-26 05:00:05,840 INFO waiting for valheim-server to stop May 26 05:00:06 supervisord: valheim-updater Redirecting stderr to '/home/valheim/Steam/logs/stderr.txt' May 26 05:00:06 supervisord: valheim-updater Logging directory: '/home/valheim/Steam/logs' May 26 05:00:06 supervisord: valheim-updater [ 0%] Checking for available updates... May 26 05:00:06 supervisord: valheim-updater [----] Verifying installation... May 26 05:00:06 supervisord: valheim-updater UpdateUI: skip show logoSteam Console Client (c) Valve Corporation - version 1716584438 May 26 05:00:06 supervisord: valheim-updater -- type 'quit' to exit -- May 26 05:00:06 supervisord: valheim-updater Loading Steam API... May 26 05:00:06 supervisord: valheim-updater OK May 26 05:00:06 supervisord: valheim-updater May 26 05:00:06 supervisord: valheim-updater Connecting anonymously to Steam Public... May 26 05:00:07 supervisord: valheim-server Unloading 0 unused Assets to reduce memory usage. Loaded Objects now: 195844. May 26 05:00:07 supervisord: valheim-server Total: 221.370233 ms (FindLiveObjects: 11.328163 ms CreateObjectMapping: 13.666005 ms MarkObjects: 196.109468 ms DeleteObjects: 0.265930 ms) May 26 05:00:07 supervisord: valheim-server Setting up 4 worker threads for Enlighten. 2024-05-26 05:00:07,840 INFO waiting for valheim-server to stop May 26 05:00:07 supervisord: valheim-server DEBUG - [102] - Waiting for Valheim Server with PID 106 to shut down May 26 05:00:07 supervisord: valheim-server 05/26/2024 05:00:07: Steam manager on destroy May 26 05:00:08 supervisord: valheim-updater OK May 26 05:00:08 supervisord: valheim-updater Waiting for client config...OK May 26 05:00:08 supervisord: valheim-updater Waiting for user info... May 26 05:00:08 supervisord: valheim-server 05/26/2024 05:00:08: ZNet OnDestroy May 26 05:00:08 supervisord: valheim-server 05/26/2024 05:00:08: Net scene destroyed May 26 05:00:08 supervisord: valheim-server Memory Statistics: May 26 05:00:08 supervisord: valheim-server [ALLOC_TEMP_TLS] TLS Allocator May 26 05:00:08 supervisord: valheim-server StackAllocators : May 26 05:00:08 supervisord: valheim-server [ALLOC_TEMP_MAIN] May 26 05:00:08 supervisord: valheim-server Peak usage frame count: [0-1.0 KB]: 2235865 frames, [1.0 KB-2.0 KB]: 3 frames, [2.0 KB-4.0 KB]: 11 frames, [4.0 KB-8.0 KB]: 7 frames, [8.0 KB-16.0 KB]: 32 frames, [16.0 KB-32.0 KB]: 1914013 frames, [32.0 KB-64.0 KB]: 4 frames, [64.0 KB-128.0 KB]: 360 frames, [128.0 KB-256.0 KB]: 1 frames, [256.0 KB-0.5 MB]: 26 frames, [2.0 MB-4.0 MB]: 1 frames, [4.0 MB-8.0 MB]: 20 frames May 26 05:00:08 supervisord: valheim-server Initial Block Size 4.0 MB May 26 05:00:08 supervisord: valheim-server Current Block Size 7.6 MB May 26 05:00:08 supervisord: valheim-server Peak Allocated Bytes 7.6 MB May 26 05:00:08 supervisord: valheim-server Overflow Count 129 May 26 05:00:08 supervisord: valheim-server [ALLOC_TEMP_Background Job.worker 4] May 26 05:00:08 supervisord: valheim-server Initial Block Size 32.0 KB May 26 05:00:08 supervisord: valheim-server Current Block Size 32.0 KB May 26 05:00:08 supervisord: valheim-server Peak Allocated Bytes 168 B May 26 05:00:08 supervisord: valheim-server Overflow Count 0 May 26 05:00:08 supervisord: valheim-server [ALLOC_TEMP_Loading.PreloadManager]

docker_compose services: valheim: image: lloesche/valheim-server:latest restart: unless-stopped container_name: valheim-server ports:

thorkseng commented 6 months ago

Hello all,

Yeah, I have the same issue at 5am with the automatic restart of the server, it seems that only fails with the restart of the 5 am, because during previous hours without players it restart the servers to update it without issues: image

Regards, Tranko.

Tr4il commented 6 months ago

+1, exactly the same issue, server does not restart at 5am.

Dunny88 commented 5 months ago

I am having the same issue. Restart causes server to fail.

dnbrainwash commented 5 months ago

Same issue here, can we turn the restart off?

bubylou commented 5 months ago

Same issue here, can we turn the restart off?

You can. I set the UPDATE_CRON and RESTART_CRON to an empty string to stop all automatic restarts. This has been working for me so far.

Interestingly setting only UPDATE_CRON did not fix it so this issue appears to be with any restart not just for updates. I'm still trying to figure out why.

Dimand commented 4 months ago

I am also seeing the same issue. Server stops at 5am (UTC time).

MartenBE commented 1 month ago

I am still having the exact same problem ...