3.0.X hanging on serverstop or Ctrl+C, removeTimerEvent() errors abound

[Digging]

Hi all,

I'm running into a problem with stopping TS3 servers on one server. The same files on my desktop (Arch Linux) and another server work as expected, so there is something machine specific I'm trying to track down I think.

Below you can see what I'm doing (from a freshly untarred download of the server) , it happens whether I'm using 'login serveradmin testingpass' and 'serverstop sid=1' via the Query Console (telnet), using 'serverprocessstop' or just Control+C from the terminal :

: LD_LIBRARY_PATH=:. ./ts3server_linux_amd64 clear_database=1 create_default_virtualserver=1 serveradmin_password=testingpass
2012-06-27 14:15:10.597757|INFO    |ServerLibPriv |   | TeamSpeak 3 Server 3.0.6 (2012-06-21 04:43:35)
2012-06-27 14:15:10.598471|INFO    |DatabaseQuery |   | dbPlugin name:    SQLite3 plugin, Version 2, (c)TeamSpeak Systems GmbH
2012-06-27 14:15:10.598604|INFO    |DatabaseQuery |   | dbPlugin version: 3.7.3
2012-06-27 14:15:10.599110|INFO    |DatabaseQuery |   | checking database integrity (may take a while)
2012-06-27 14:15:10.627759|INFO    |SQL           |   | db_CreateTables() tables droped
2012-06-27 14:15:10.663813|INFO    |SQL           |   | db_CreateTables() tables created

------------------------------------------------------------------
                      I M P O R T A N T                           
------------------------------------------------------------------
               Server Query Admin Account created                 
         loginname= "serveradmin", password= "testingpass"
------------------------------------------------------------------

2012-06-27 14:15:11.012030|WARNING |Accounting    |   | Unable to find valid license key, falling back to limited functionality
2012-06-27 14:15:11.544166|INFO    |FileManager   |   | listening on 0.0.0.0:30033
2012-06-27 14:15:11.550392|INFO    |VirtualSvrMgr |   | executing monthly interval
2012-06-27 14:15:11.550666|INFO    |VirtualSvrMgr |   | reset virtualserver traffic statistics
2012-06-27 14:15:11.837798|INFO    |VirtualServer |  1| listening on 0.0.0.0:9987
2012-06-27 14:15:11.838491|INFO    |VirtualServer |  1| client 'server'(id:0) added privilege key for servergroup 'Server Admin'(id:6)
2012-06-27 14:15:11.838559|WARNING |VirtualServer |  1| --------------------------------------------------------
2012-06-27 14:15:11.838612|WARNING |VirtualServer |  1| ServerAdmin privilege key created, please use the line below
2012-06-27 14:15:11.838685|WARNING |VirtualServer |  1| token=De0QjhI+7uFckxrNsvZgKUTAhsqXl12wfnKIwRuy
2012-06-27 14:15:11.838745|WARNING |VirtualServer |  1| --------------------------------------------------------

------------------------------------------------------------------
                      I M P O R T A N T                           
------------------------------------------------------------------
      ServerAdmin privilege key created, please use it to gain 
      serveradmin rights for your virtualserver. please
      also check the doc/privilegekey_guide.txt for details.

       token=De0QjhI+7uFckxrNsvZgKUTAhsqXl12wfnKIwRuy
------------------------------------------------------------------

2012-06-27 14:15:11.858521|INFO    |CIDRManager   |   | updated query_ip_whitelist ips: 127.0.0.1, 
2012-06-27 14:15:11.859141|INFO    |Query         |   | listening on 0.0.0.0:10011
2012-06-27 14:15:12.840519|INFO    |ServerMain    |   | Received signal SIGINT, shutting down.
2012-06-27 14:15:12.891821|WARNING |Time          |   | removeTimerEvent() waiting since 0 timerName: PacketHandler: 1
2012-06-27 14:15:12.891946|WARNING |Time          |   | removeTimerEvent() waiting since 0 timerName: PacketHandler: 1
2012-06-27 14:15:12.892075|WARNING |Time          |   | removeTimerEvent() waiting since 1 timerName: PacketHandler: 1
2012-06-27 14:15:12.892183|WARNING |Time          |   | removeTimerEvent() waiting since 1 timerName: PacketHandler: 1

...snip...

2012-06-27 14:15:12.958438|WARNING |Time          |   | removeTimerEvent() waiting since 67 timerName: PacketHandler: 1
2012-06-27 14:15:12.958507|WARNING |Time          |   | removeTimerEvent() waiting since 67 timerName: PacketHandler: 1
2012-06-27 14:15:12.958564|WARNING |Time          |   | removeTimerEvent() waiting since 67 timerName: PacketHandler: 1
2012-06-27 14:15:12.958624|WARNING |Time          |   | removeTimerEvent() waiting since 67 timerName: PacketHandler: 1
2012-06-27 14:15:12.958682|WARNING |Time          |   | removeTimerEvent() waiting since 67 timerName: PacketHandler: 1

..snip..

2012-06-27 14:15:13.425236|WARNING |Time          |   | removeTimerEvent() waiting since 534 timerName: PacketHandler: 1
2012-06-27 14:15:13.425287|WARNING |Time          |   | removeTimerEvent() waiting since 534 timerName: PacketHandler: 1
2012-06-27 14:15:13.425337|WARNING |Time          |   | removeTimerEvent() waiting since 534 timerName: PacketHandler: 1

and so on. It spits out a *lot* of these warnings very quickly.

A strace of the process shows no errors and looks very similar to the strace from working servers, just that it spits out the removeTimerEvent errors rather than the OK msg.
The problem machine has no firewall running, unlimited ulimits and is a Centos 5.8 machine:
Linux test.server 2.6.18-308.8.2.el5 #1 SMP Tue Jun 12 09:58:12 EDT 2012 x86_64 x86_64 x86_64 GNU/Linux

I should also note, that while the error messages are spewing out, new connections to the query port still work, so it's not like the entire server is hung, just where I am typing 'serverstop sid=1' from initially, it doesn't accept new commands and never returns from the call. Running `serverlist` in a new query connection shows the status of sid 1 to be shutting down.

I have another server also running Centos 5.8 with the same kernel and it is working just fine.

Has anyone run into this sort of issue or know what I might look into ?

Thank you
Darien

[dante696]

I have tried to reproduce this on CentOS 6.2 and server 3.0.6.1.

But i can stop the server with putty and with the terminal and with "ts3server_ startscript.sh stop"
I also tried to start the server with the normal and the minimal startscricpt, but all was fine here.

Please check your server log for more errors.

[sometime]

Got the same result but with the "permreset" command with a php script.

After restarted the server (killing the process manualy) no problem.

Server 3.0.6.1
OS Linux Debian Squeeze