LinuxGSM icon indicating copy to clipboard operation
LinuxGSM copied to clipboard

[Bug]: Using systemd to run ts3server on boot, myTeamSpeak ID is invalid

Open borzaka opened this issue 7 months ago • 2 comments

User story

As a TeamSpeak 3 administrator, I want to start my ts3server without any error, or limitation in myTeamSpeak related services.

Game

Teamspeak 3

Linux distro

Debian 12

Command

command: start

Further information

When I connect to my TeamSpeak server:

<20:14:54> The TeamSpeak server could not validate your myTeamSpeak ID. Some myTeamSpeak related services are not available. <20:14:54> Reconnecting might solve the issue. <20:14:54> myTeamSpeak ID is invalid

Image

To fix, I have to manually restart the ts3server:

$ ./ts3server restart
[  OK  ] Stopping ts3server: Graceful: CTRL+c: 2 ... OK
[  OK  ] Starting ts3server: Applying Delete file /dev/shm/7gbhujb54g8z9hu43jre8 fix: TeamSpeak 3
[  OK  ] Starting ts3server: LinuxGSM

After that, connecting to the server, and the error message is gone.

My ts3server.service:

[Unit]
Description=LinuxGSM TeamSpeak 3 Server
After=network-online.target
Wants=network-online.target

[Service]
Type=forking
User=ts3server
WorkingDirectory=/home/ts3server
#Assume that the service is running after main process exits with code 0
RemainAfterExit=yes
ExecStart=/home/ts3server/ts3server start
ExecStop=/home/ts3server/ts3server stop
Restart=no

[Install]
WantedBy=multi-user.target

Relevant log output

# systemctl status ts3server
● ts3server.service - LinuxGSM TeamSpeak 3 Server
     Loaded: loaded (/etc/systemd/system/ts3server.service; enabled; preset: enabled)
     Active: active (exited) since Wed 2025-05-07 17:28:16 CEST; 2h 59min ago
   Main PID: 1655 (code=exited, status=0/SUCCESS)
        CPU: 1min 16.054s

May 07 17:28:13 PRECISION-5820 systemd[1]: Starting ts3server.service - LinuxGSM TeamSpeak 3 Server...
May 07 17:28:16 PRECISION-5820 ts3server[616]: [156B blob data]
May 07 17:28:16 PRECISION-5820 systemd[1]: Started ts3server.service - LinuxGSM TeamSpeak 3 Server.

/log/console/ts3server-console.log

2025-05-07 15:28:15.687430|INFO    |ServerLibPriv |   |TeamSpeak 3 Server 3.13.7 (2022-06-20 12:21:53)
2025-05-07 15:28:15.687486|INFO    |ServerLibPriv |   |SystemInformation: Linux 6.1.0-34-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.135-1 (2025-04-25) x86_64 Binary: 64bit
2025-05-07 15:28:15.687500|INFO    |ServerLibPriv |   |Using hardware aes
2025-05-07 15:28:15.690101|INFO    |DatabaseQuery |   |dbPlugin name:    SQLite3 plugin, Version 3, (c)TeamSpeak Systems GmbH
2025-05-07 15:28:15.690123|INFO    |DatabaseQuery |   |dbPlugin version: 3.11.1
2025-05-07 15:28:15.692662|INFO    |DatabaseQuery |   |checking database integrity (may take a while)
2025-05-07 15:28:15.706462|WARNING |Accounting    |   |Unable to open licensekey.dat, falling back to limited functionality
2025-05-07 15:28:15.708277|INFO    |Accounting    |   |Licensing Information
2025-05-07 15:28:15.708294|INFO    |Accounting    |   |licensed to       : Anonymous
2025-05-07 15:28:15.708303|INFO    |Accounting    |   |type              : No License
2025-05-07 15:28:15.708313|INFO    |Accounting    |   |starting date     : Tue Feb  1 00:00:00 2022
2025-05-07 15:28:15.708322|INFO    |Accounting    |   |ending date       : Thu Jul  1 00:00:00 2027
2025-05-07 15:28:15.708330|INFO    |Accounting    |   |max virtualservers: 1
2025-05-07 15:28:15.708337|INFO    |Accounting    |   |max slots         : 32
2025-05-07 15:28:16.107211|INFO    |              |   |Puzzle precompute time: 386
2025-05-07 15:28:16.107630|INFO    |FileManager   |   |listening on 0.0.0.0:30033, [::]:30033
2025-05-07 15:28:16.108086|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.108106|ERROR   |              |   |Could not open default UDP connection for weblist
2025-05-07 15:28:16.108220|INFO    |Query         |   |Using a query thread pool size of 2
2025-05-07 15:28:16.111244|INFO    |ServerLibPriv |   |failed to download revocation list - count: 1
2025-05-07 15:28:16.113380|INFO    |ServerLibPriv |   |failed to download revocation list - count: 1
2025-05-07 15:28:16.117284|INFO    |VirtualServerBase|1  |listening on 0.0.0.0:9987, [::]:9987
2025-05-07 15:28:16.117375|INFO    |Query         |   |listening for query on 0.0.0.0:10011, [::]:10011
2025-05-07 15:28:16.118829|INFO    |Query         |   |listening for ssh query on 0.0.0.0:10022, [::]:10022
2025-05-07 15:28:16.118860|INFO    |Query         |   |listening for http query on 0.0.0.0:10080, [::]:10080
2025-05-07 15:28:16.118987|INFO    |CIDRManager   |   |updated query_ip_allowlist ips: 127.0.0.1/32, ::1/128,
2025-05-07 15:28:16.207731|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.210957|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211023|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211093|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211157|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211222|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211288|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211358|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:28:16.211423|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
...
2025-05-07 15:28:16.214374|ERROR   |              |   |Could not open/bind UDP connection on 0.0.0.0 for weblist. Using default
2025-05-07 15:28:16.314586|ERROR   |              |   |TS3ANetwork::ResolveHostName failed error: -2 (Name or service not known) 11
2025-05-07 15:38:16.003923|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 15:38:18.003917|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 15:48:14.297865|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 15:48:16.027029|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 15:48:18.003917|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 15:58:14.297657|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 18:08:16.003919|ERROR   |              |   |TS3ANetwork::Send failed error: 111
...
2025-05-07 18:14:51.553059|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-07 18:14:51.553099|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-07 18:14:52.169044|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-07 18:14:52.169074|INFO    |VirtualServerBase|1  |failed to update myTeamSpeak ID
2025-05-07 18:18:16.003919|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 18:25:50.337136|INFO    |ServerMain    |   |Received signal SIGINT, shutting down.
2025-05-07 18:25:50.337371|INFO    |VirtualServerBase|1  |stopped

/log/server/ts3server_2025-05-07__18_44_44.866282_1.log

2025-05-07 18:44:45.183112|INFO    |VirtualServerBase|1  |listening on 0.0.0.0:9987, [::]:9987
2025-05-07 18:44:50.614573|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-07 18:44:50.614609|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-07 18:44:51.087669|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-07 18:44:51.087692|INFO    |VirtualServerBase|1  |failed to update myTeamSpeak ID
2025-05-07 18:45:11.645216|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-07 18:45:11.645243|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-07 18:45:12.165855|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-07 18:45:12.165879|INFO    |VirtualServerBase|1  |failed to update myTeamSpeak ID

After successful restart:

2025-05-07 18:25:54.930535|INFO    |DatabaseQuery |   |dbPlugin name:    SQLite3 plugin, Version 3, (c)TeamSpeak Systems GmbH
2025-05-07 18:25:54.930553|INFO    |DatabaseQuery |   |dbPlugin version: 3.11.1
2025-05-07 18:25:54.930654|INFO    |DatabaseQuery |   |checking database integrity (may take a while)
2025-05-07 18:25:54.938711|WARNING |Accounting    |   |Unable to open licensekey.dat, falling back to limited functionality
2025-05-07 18:25:54.938907|INFO    |Accounting    |   |Licensing Information
2025-05-07 18:25:54.938925|INFO    |Accounting    |   |licensed to       : Anonymous
2025-05-07 18:25:54.938935|INFO    |Accounting    |   |type              : No License
2025-05-07 18:25:54.938945|INFO    |Accounting    |   |starting date     : Tue Feb  1 00:00:00 2022
2025-05-07 18:25:54.938954|INFO    |Accounting    |   |ending date       : Thu Jul  1 00:00:00 2027
2025-05-07 18:25:54.938961|INFO    |Accounting    |   |max virtualservers: 1
2025-05-07 18:25:54.938969|INFO    |Accounting    |   |max slots         : 32
2025-05-07 18:25:55.327308|INFO    |              |   |Puzzle precompute time: 377
2025-05-07 18:25:55.327459|INFO    |FileManager   |   |listening on 0.0.0.0:30033, [::]:30033
2025-05-07 18:25:55.349656|INFO    |Query         |   |Using a query thread pool size of 2
2025-05-07 18:25:55.357425|INFO    |VirtualServerBase|1  |listening on 0.0.0.0:9987, [::]:9987
2025-05-07 18:25:55.357511|INFO    |Query         |   |listening for query on 0.0.0.0:10011, [::]:10011
2025-05-07 18:25:55.357598|INFO    |Query         |   |listening for ssh query on 0.0.0.0:10022, [::]:10022
2025-05-07 18:25:55.357629|INFO    |Query         |   |listening for http query on 0.0.0.0:10080, [::]:10080
2025-05-07 18:25:55.357664|INFO    |CIDRManager   |   |updated query_ip_allowlist ips: 127.0.0.1/32, ::1/128,
2025-05-07 18:25:55.482686|INFO    |              |   |myTeamSpeak identifier revocation list was downloaded successfully - all related features are activated
2025-05-07 18:30:57.003922|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-07 18:30:59.003916|ERROR   |              |   |TS3ANetwork::Send failed error: 111

Steps to reproduce

  1. Use systemd to run the server on boot: https://docs.linuxgsm.com/configuration/running-on-boot
  2. Connect to your ts3server
  3. see error message

borzaka avatar May 07 '25 18:05 borzaka

I'd like to add that this is also happening on Ubuntu Server 24.04.2 LTS.

User story

I expect the TeamSpeak server to not throw an error in the TeamSpeak 3 client window of:

<20:06:12> The TeamSpeak server could not validate your myTeamSpeak ID. Some myTeamSpeak related services are not available.
<20:06:12> Reconnecting might solve the issue.
<20:06:13> myTeamSpeak ID is invalid

I also expect to not see an error about downloading a revocation list in the TeamSpeak server logs 2025-05-09 23:49:09.900830|INFO |ServerLibPriv | |failed to download revocation list - count: 1.

Game

TeamSpeak 3

Linux Distribution

Ubuntu Server 24.04.2 LTS

Please note, some information has been redacted from my screenshot for privacy purposes.

Image

Unlike the above, restarting the TeamSpeak server with /home/linuxgsm/LinuxGSM/TeamSpeak/ts3server restart does not resolve the issue and the error is still present in the TeamSpeak 3 client.

Image

TeamSpeak does restart without error according to the GUI.

Image

My teamspeakserver.service file:

[Unit]
Description=LinuxGSM TeamSpeak Server
After=network-online.target
Wants=network-online.target

[Service]
Type=forking
User=linuxgsm
WorkingDirectory=/home/linuxgsm/LinuxGSM/TeamSpeak
#Assume that the service is running after main process exits with code 0
RemainAfterExit=yes
ExecStart=/home/linuxgsm/LinuxGSM/TeamSpeak/ts3server start
ExecStop=/home/linuxgsm/LinuxGSM/TeamSpeak/ts3server stop
Restart=no

[Install]
WantedBy=multi-user.target

/home/linuxgsm/LinuxGSM/TeamSpeak/log/console/ts3server-console.log log output:

linuxgsm@sns-linuxgsm:~$ cat /home/linuxgsm/LinuxGSM/TeamSpeak/log/console/ts3server-console.log
2025-05-10 00:08:49.868245|INFO    |              |   |Puzzle precompute time: 554
2025-05-10 00:08:49.868892|INFO    |FileManager   |   |listening on 0.0.0.0:30033, [::]:30033
2025-05-10 00:08:49.886668|INFO    |Query         |   |Using a query thread pool size of 2
2025-05-10 00:08:49.900198|INFO    |VirtualServerBase|1  |listening on 0.0.0.0:9987, [::]:9987
2025-05-10 00:08:49.900462|INFO    |Query         |   |listening for query on 0.0.0.0:10011, [::]:10011
2025-05-10 00:08:49.900839|INFO    |Query         |   |listening for ssh query on 0.0.0.0:10022, [::]:10022
2025-05-10 00:08:49.900974|INFO    |Query         |   |listening for http query on 0.0.0.0:10080, [::]:10080
2025-05-10 00:08:49.901133|INFO    |CIDRManager   |   |updated query_ip_allowlist ips: 127.0.0.1/32, ::1/128,
2025-05-10 00:08:49.964315|INFO    |ServerLibPriv |   |failed to download revocation list - count: 1
2025-05-10 00:08:50.225919|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-10 00:08:50.226079|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-10 00:08:50.853069|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-10 00:08:50.853159|INFO    |VirtualServerBase|1  |failed to update myTeamSpeak ID

/home/linuxgsm/LinuxGSM/TeamSpeak/log/console/ts3server-console-2025-05-09-20\:08\:41.log log output:

linuxgsm@sns-linuxgsm:~$ cat /home/linuxgsm/LinuxGSM/TeamSpeak/log/console/ts3server-console-2025-05-09-20\:08\:41.log
2025-05-09 23:49:08.818661|INFO    |ServerLibPriv |   |TeamSpeak 3 Server 3.13.7 (2022-06-20 12:21:53)
2025-05-09 23:49:08.818816|INFO    |ServerLibPriv |   |SystemInformation: Linux 6.8.0-59-generic #61-Ubuntu SMP PREEMPT_DYNAMIC Fri Apr 11 23:16:11 UTC 2025 x86_64 Binary: 64bit
2025-05-09 23:49:08.818857|INFO    |ServerLibPriv |   |Using hardware aes
2025-05-09 23:49:08.882840|INFO    |DatabaseQuery |   |dbPlugin name:    SQLite3 plugin, Version 3, (c)TeamSpeak Systems GmbH
2025-05-09 23:49:08.882937|INFO    |DatabaseQuery |   |dbPlugin version: 3.11.1
2025-05-09 23:49:08.905658|INFO    |DatabaseQuery |   |checking database integrity (may take a while)
2025-05-09 23:49:09.024661|WARNING |Accounting    |   |Unable to open licensekey.dat, falling back to limited functionality
2025-05-09 23:49:09.057029|INFO    |Accounting    |   |Licensing Information
2025-05-09 23:49:09.057096|INFO    |Accounting    |   |licensed to       : Anonymous
2025-05-09 23:49:09.057147|INFO    |Accounting    |   |type              : No License
2025-05-09 23:49:09.057177|INFO    |Accounting    |   |starting date     : Tue Feb  1 00:00:00 2022
2025-05-09 23:49:09.057203|INFO    |Accounting    |   |ending date       : Thu Jul  1 00:00:00 2027
2025-05-09 23:49:09.057225|INFO    |Accounting    |   |max virtualservers: 1
2025-05-09 23:49:09.057242|INFO    |Accounting    |   |max slots         : 32
2025-05-09 23:49:09.716004|INFO    |              |   |Puzzle precompute time: 561
2025-05-09 23:49:09.728784|INFO    |FileManager   |   |listening on 0.0.0.0:30033, [::]:30033
2025-05-09 23:49:09.818379|INFO    |Query         |   |Using a query thread pool size of 2
2025-05-09 23:49:09.900830|INFO    |ServerLibPriv |   |failed to download revocation list - count: 1
2025-05-09 23:49:09.930493|INFO    |VirtualServerBase|1  |listening on 0.0.0.0:9987, [::]:9987
2025-05-09 23:49:09.930759|INFO    |Query         |   |listening for query on 0.0.0.0:10011, [::]:10011
2025-05-09 23:49:09.964221|INFO    |Query         |   |listening for ssh query on 0.0.0.0:10022, [::]:10022
2025-05-09 23:49:09.964357|INFO    |Query         |   |listening for http query on 0.0.0.0:10080, [::]:10080
2025-05-09 23:49:09.971596|INFO    |CIDRManager   |   |updated query_ip_allowlist ips: 127.0.0.1/32, ::1/128,
2025-05-09 23:49:10.714179|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-09 23:49:10.714269|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-09 23:59:12.001095|ERROR   |              |   |TS3ANetwork::Send failed error: 111
2025-05-10 00:00:28.932377|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-10 00:00:28.932512|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-10 00:06:12.227865|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-10 00:06:12.228063|INFO    |VirtualServerBase|1  |client is connecting with a invalid or revoked myTeamSpeak ID
2025-05-10 00:06:12.836245|INFO    |VirtualServerBase|1  |rejecting myteamspeakid: revoke list out of date
2025-05-10 00:06:12.836385|INFO    |VirtualServerBase|1  |failed to update myTeamSpeak ID
^C2025-05-10 00:08:43.817106|INFO    |ServerMain    |   |Received signal SIGINT, shutting down.
2025-05-10 00:08:43.817886|INFO    |VirtualServerBase|1  |stopped

Steps to reproduce

  1. Use systemd to run the server on boot: https://docs.linuxgsm.com/configuration/running-on-boot
  2. Connect to your ts3server
  3. See error message

It is interesting that a restart of the TeamSpeak server resolves the error on Debian but does not resolve it on Ubuntu.

Please let me know if any additional information is needed and what I can do to help test!

Wolveraptor avatar May 10 '25 00:05 Wolveraptor

I wanted to chime in with an update, the failure to download the revocation list was actually on TeamSpeak's side. I am not sure if this impacts the behavior you noted on your Debian instance, @borzaka however I for my Ubuntu instance, since TeamSpeak fixed the problem with their infrastructure, I have not experienced any issues with downloading the revocation list. I am also able to stop, start, and restart both the TeamSpeak server and the virtual machine it resides on with no problems with the Systemd service file and no failures to download the revocation list.

TeamSpeak community forum post where I chimed in with other users reporting this can be found here.

Wolveraptor avatar May 13 '25 01:05 Wolveraptor