[2018-11-23 06:16:24] [DEBUG] Log successfully started!
[2018-11-23 06:16:24] [INFO] GEN | Starting TeaSpeak-Server v1.2.28-beta [Build: 1542928790]
[2018-11-23 06:16:24] [INFO] GEN | Starting music providers
[2018-11-23 06:16:24] [DEBUG] [Music] Provider load order:
[2018-11-23 06:16:24] [DEBUG] [Music] [0] providers/000ProviderFFMpeg.so
[2018-11-23 06:16:24] [DEBUG] [Music] [1] providers/001ProviderYT.so
[2018-11-23 06:16:24] [DEBUG] [Music] [FFMPEG] Using config file located at providers/config_ffmpeg.ini
[2018-11-23 06:16:24] [DEBUG] [Music] [FFMPEG] Missing configuration file. Using default values
[2018-11-23 06:16:24] [DEBUG] [Music] [FFMPEG] Executing command "ffmpeg -version"
[2018-11-23 06:16:24] [ERROR] [Music] [FFMPEG] Could not find ffmpeg (Error: "sh: ffmpeg: command not found")
[2018-11-23 06:16:24] [ERROR] [Music] [FFMPEG] How to download/install ffmpeg: "sudo apt-get install ffmpeg"
[2018-11-23 06:16:24] [ERROR] [Music] Could not create music provider for providers/000ProviderFFMpeg.so
[2018-11-23 06:16:24] [ERROR] [Music] Could not load music provider providers/001ProviderYT.so. Error: 000ProviderFFMpeg.so: cannot open shared object file: No such file or directory
[2018-11-23 06:16:27] [DEBUG] GEN | Created a new async thread pool!
[2018-11-23 06:16:27] [DEBUG] GEN | Loaded client from database. Database id: 1 Unique id: serveradmin
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 1 - Guest Server Query
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 2 - Admin Server Query
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 3 - Server Admin
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 4 - Normal
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 5 - Guest
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 6 - Channel Admin
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 7 - Operator
[2018-11-23 06:16:28] [DEBUG] GEN | Push back group -> 8 - Guest
[2018-11-23 06:16:28] [DEBUG] GLOBL | Instance admin group id 2
[2018-11-23 06:16:28] [DEBUG] GLOBL | Server guest group id 1
[2018-11-23 06:16:28] [DEBUG] GLOBL | Server music group id 1
[2018-11-23 06:16:28] [INFO] GEN | Got 4 saved channels
[2018-11-23 06:16:28] [INFO] FILE | Starting server on 0.0.0.0:30033
[2018-11-23 06:16:28] [INFO] QUERY | Starting server on 0.0.0.0:10011
[2018-11-23 06:16:28] [DEBUG] QUERY | Event base executed (0x7fa71be41900)
[2018-11-23 06:16:28] [INFO] GEN | Generating server puzzles...
[2018-11-23 06:16:28] [INFO] GEN | Puzzles generated! Time required: 320ms
[2018-11-23 06:16:28] [INFO] GLOBL | Loading startup cache (This may take a while)
[2018-11-23 06:16:28] [INFO] GLOBL | Required 31ms to preload the startup cache. Cache needs 0mb
[2018-11-23 06:16:28] [DEBUG] 1 | Importing server keypair
[2018-11-23 06:16:28] [INFO] 1 | Got 4 saved channels
[2018-11-23 06:16:28] [DEBUG] 1 | Push back group -> 9 - Server Admin
[2018-11-23 06:16:28] [DEBUG] 1 | Push back group -> 10 - Normal
[2018-11-23 06:16:28] [DEBUG] 1 | Push back group -> 11 - Guest
[2018-11-23 06:16:28] [DEBUG] 1 | Push back group -> 12 - Channel Admin
[2018-11-23 06:16:28] [DEBUG] 1 | Push back group -> 13 - Operator
[2018-11-23 06:16:28] [DEBUG] 1 | Push back group -> 14 - Guest
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_nickname' for 1 (New value: serveradmin, SQL: UPDATE `clients` SET `lastName` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [DEBUG] GEN | Query permission manager
[2018-11-23 06:16:28] [DEBUG] 1 | Loaded client from database. Database id: 1 Unique id: serveradmin
[2018-11-23 06:16:28] [DEBUG] 1 | - 1 | 0 ([cspacer01]┏╋━━━━━━◥◣◆◢◤━━━━━━╋┓)
[2018-11-23 06:16:28] [DEBUG] 1 | - 2 | 1 ([cspacer02] TeaSpeak Server)
[2018-11-23 06:16:28] [DEBUG] 1 | - 3 | 2 ([cspacer03]┗╋━━━━━━◥◣◆◢◤━━━━━━╋┛)
[2018-11-23 06:16:28] [DEBUG] 1 | - 4 | 3 ([cspacer04]Default Channel)
[2018-11-23 06:16:28] [INFO] 1 | Starting server
[2018-11-23 06:16:28] [INFO] 1 | Started server on 0.0.0.0:9987 | :::9987.
[2018-11-23 06:16:28] [INFO] 1 | Starting web server on 0.0.0.0:9987
[2018-11-23 06:16:28] [INFO] GLOBL | Loaded 1 servers within 24ms. Server/sec: 1.000000
[2018-11-23 06:16:28] [DEBUG] GEN | Dispatching io base 0x7fa71cbf4e00
[2018-11-23 06:16:28] [DEBUG] GEN | Dispatching io base 0x7fa71cbf5400
[2018-11-23 06:16:28] [DEBUG] 1 | [WebList] Requesting from local address 0.0.0.0:9987:30567
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/undefined] Creating VoiceClient instance at 0x7fa715c0e010
[2018-11-23 06:16:28] [DEBUG] 1 | Allocated new voice client connection at 0x7fa715c26180
[2018-11-23 06:16:28] [DEBUG] 1 | Remote address (v4) 213.108.243.86 destination address 213.108.243.86
[2018-11-23 06:16:28] [DEBUG] 1 | Having new voice client. Remote address: 176.65.240.18:62498
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/undefined | 0] Got non client 3.1 protocol with build timestamp 171539215
[2018-11-23 06:16:28] [ERROR] 1 | [176.65.240.18:62498/undefined | 0] Handling of packet Init1 needs more than 10ms (15ms)
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/undefined | 0] Cant decrypt packet with setupped key Ack. Error: memory verify failed!. Suceeded with default key!
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 2 (New value: 10, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [DEBUG] GEN | Query permission manager
[2018-11-23 06:16:28] [DEBUG] 1 | Loaded client from database. Database id: 2 Unique id: oWSpgEfERKg6+eveltojAV8pX4o=
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 0] Got client init. (HWID: 4e88cabf5ce6dfd659f42ff2bc0689dd,20d039a3e211c29387adb9ba95e6a738)
[2018-11-23 06:16:28] [DEBUG] 1 | [Permission] Value test result for test type 1.
[2018-11-23 06:16:28] [DEBUG] 1 | [Permission] Permission: b_client_ignore_bans Required value: 1 Gained value: 1 Force required: 1 Channel: none Result: 1
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_lastconnected' for 2 (New value: 1542971788, SQL: UPDATE `clients` SET `lastConnect` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 2 (New value: 11, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [INFO] 1 | [176.65.240.18:62498/TeamSpeakUser | 0] Client may cached a old permission list (Server is newer than the client's last join)
[2018-11-23 06:16:28] [ERROR] 1 | Command handling of command clientinit needs 28ms
[2018-11-23 06:16:28] [ERROR] 1 | [176.65.240.18:62498/TeamSpeakUser | 0] Handling of packet Command needs more than 10ms (29ms)
[2018-11-23 06:16:28] [DEBUG] 1 | Updating server property: virtualserver_client_connections. New value: 11. Query: UPDATE `properties` SET `value` = :value WHERE `serverId` = :sid AND `type` = :type AND `id` = :id AND `key` = :key
[2018-11-23 06:16:28] [DEBUG] 1 | Updating server property: virtualserver_last_client_connect. New value: 1542971788. Query: UPDATE `properties` SET `value` = :value WHERE `serverId` = :sid AND `type` = :type AND `id` = :id AND `key` = :key
[2018-11-23 06:16:28] [DEBUG] GEN | Client TeamSpeakUser|176.65.240.18 comes from Iran, Islamic Republic of|IR
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1] Got a channel talk power of 75 Talk power set is 0
[2018-11-23 06:16:28] [DEBUG] 1 | Client id: 1
[2018-11-23 06:16:28] [DEBUG] GEN | Parsed channel path '[cspacer04]Default\sChannel'. Entries:
[2018-11-23 06:16:28] [DEBUG] GEN | - '[cspacer04]Default Channel' (found)
[2018-11-23 06:16:28] [DEBUG] 1 | [Permission] Value test result for test type 1.
[2018-11-23 06:16:28] [DEBUG] 1 | [Permission] Permission: i_channel_join_power Required value: -2 Gained value: 75 Force required: 0 Channel: [cspacer04]Default Channel Result: 1
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1] Got a channel talk power of 75 Talk power set is 0
[2018-11-23 06:16:28] [DEBUG] 1 | [Permission] Value test result for test type 1.
[2018-11-23 06:16:28] [DEBUG] 1 | [Permission] Permission: b_channel_ignore_view_power Required value: 1 Gained value: -2 Force required: 1 Channel: none Result: 0
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1][CHANNELS] Insert channel 1 ([cspacer01]┏╋━━━━━━◥◣◆◢◤━━━━━━╋┓ => order 0) after 0 ()
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1][CHANNELS] Insert channel 2 ([cspacer02] TeaSpeak Server => order 1) after 1 ([cspacer01]┏╋━━━━━━◥◣◆◢◤━━━━━━╋┓)
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1][CHANNELS] Insert channel 3 ([cspacer03]┗╋━━━━━━◥◣◆◢◤━━━━━━╋┛ => order 2) after 2 ([cspacer02] TeaSpeak Server)
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1][CHANNELS] Insert channel 4 ([cspacer04]Default Channel => order 3) after 3 ([cspacer03]┗╋━━━━━━◥◣◆◢◤━━━━━━╋┛)
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: client_nickname value: TeamSpeakUser
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_nickname' for 2 (New value: TeamSpeakUser, SQL: UPDATE `clients` SET `lastName` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: client_version value: 3.1.10 [Build: 1528537615]
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: client_platform value: Windows
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: hwid value: 4e88cabf5ce6dfd659f42ff2bc0689dd,20d039a3e211c29387adb9ba95e6a738
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_lastconnected' for 2 (New value: 1542971788, SQL: UPDATE `clients` SET `lastConnect` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 2 (New value: 11, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: client_description value: I don't have a description
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: client_total_online_time value: 720
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: client_month_online_time value: 720
[2018-11-23 06:16:28] [DEBUG] 1 | [Property] Changed property in db key: connection_client_ip value: 176.65.240.18
[2018-11-23 06:16:28] [INFO] 1 | Voice client 2/oWSpgEfERKg6+eveltojAV8pX4o= (TeamSpeakUser) from 176.65.240.18:62498 joined.
[2018-11-23 06:16:28] [DEBUG] 1 | [176.65.240.18:62498/TeamSpeakUser | 1] Update client variable via 'clientupdate'. Variable name: client_badges, Variable value: overwolf=0
[2018-11-23 06:16:29] [DEBUG] 1 | [WebList] Weblist request failed. Status code 5. Buffer: 0x01 0x00 0x01 0x02 0x05
[2018-11-23 06:16:29] [ERROR] 1 | [WebList] Status update failed. Error: Invalid exchange status. Status: 5
[2018-11-23 06:16:29] [DEBUG] 1 | [WebList] Requesting from local address 0.0.0.0:9987:30567
[2018-11-23 06:16:30] [DEBUG] 1 | [WebList] Weblist request failed. Status code 5. Buffer: 0x01 0x00 0x01 0x02 0x05
[2018-11-23 06:16:30] [ERROR] 1 | [WebList] Status update failed. Error: Invalid exchange status. Status: 5
[2018-11-23 06:16:30] [DEBUG] 1 | [WebList] Requesting from local address 0.0.0.0:9987:30567
[2018-11-23 06:16:31] [DEBUG] 1 | [WebList] Weblist request failed. Status code 5. Buffer: 0x01 0x00 0x01 0x02 0x05
[2018-11-23 06:16:31] [ERROR] 1 | [WebList] Status update failed. Error: Invalid exchange status. Status: 5
[2018-11-23 06:16:31] [DEBUG] 1 | [WebList] Requesting from local address 0.0.0.0:9987:30567
[2018-11-23 06:16:32] [DEBUG] 1 | [WebList] Weblist request failed. Status code 5. Buffer: 0x01 0x00 0x01 0x02 0x05
[2018-11-23 06:16:32] [ERROR] 1 | [WebList] Status update failed. Error: Invalid exchange status. Status: 5
[2018-11-23 06:16:32] [INFO] 1 | [WebList] Scheduling next update attempt in 10 minutes.
[2018-11-23 06:16:43] [DEBUG] Requesting server 87.106.252.164
[2018-11-23 06:16:44] [DEBUG] GEN | Running close in a new thread
[2018-11-23 06:16:44] [DEBUG] GEN | Executing close done
[2018-11-23 06:16:44] [INFO] GLOBL | Got stop signal (SIGINT). Stopping instance.
[2018-11-23 06:16:44] [INFO] GEN | Stopping all server instances!
[2018-11-23 06:16:44] [INFO] GEN | Stopping application
[2018-11-23 06:16:44] [DEBUG] GLOBL | Stopping all virtual servers
[2018-11-23 06:16:45] [DEBUG] GEN | Dispatching io base 0x7fa71cbf5400 finished!
[2018-11-23 06:16:45] [DEBUG] GEN | Dispatching io base 0x7fa71cbf4e00 finished!
[2018-11-23 06:16:45] [DEBUG] GLOBL | All virtual server stopped
[2018-11-23 06:16:45] [DEBUG] QUERY | Stopping query server
[2018-11-23 06:16:45] [DEBUG] QUERY | Event base terminated (0x7fa71be41900)
[2018-11-23 06:16:45] [DEBUG] QUERY | Query server stopped
[2018-11-23 06:16:45] [DEBUG] FILE | Stopping file server
[2018-11-23 06:16:45] [DEBUG] FILE | File-Server accept thread terminated
[2018-11-23 06:16:45] [DEBUG] FILE | File server stopped
[2018-11-23 06:16:45] [DEBUG] GEN | Event base dispatch of 0x7fa71be41f00 ended.
[2018-11-23 06:16:45] [DEBUG] GEN | Event base dispatch of 0x7fa71be42500 ended.
[2018-11-23 06:16:45] [DEBUG] GEN | Event base dispatch of 0x7fa71be42b00 ended.
[2018-11-23 06:16:45] [DEBUG] GEN | Event base dispatch of 0x7fa71be43100 ended.
[2018-11-23 06:16:45] [INFO] GEN | Application suspend successful!