• Hey Guest, we're evolving the future of TeaSpeak.
    You're invited to join the discussion here!

Completed NO PING RESPONSE

Status
Not open for further replies.
Vafin

Vafin

TeaFanatic
Also, I often notice the ping is periodically increased by large values.
1539878452098.png
 
Last edited:
Kevinos

Kevinos

Fanatic member
Premium User
Just have the same, first time for me after update to the last version => <18:28:59> Vous avez abandonné (No ping response)
 
WolverinDEV

WolverinDEV

TeaSpeak Team
Staff member
Administrator
Do you may have the server log?
There should be a detailed entry about that.
(Last ping send, last ping received etc.)
Code: 
debugMessage(this->getServerId(), "{} Got a ping timeout. (Last successful ping: {}ms ago. Last request {}ms. Last response {}ms)",
                                 CLIENT_STR_LOG_PREFIX,
                                 duration_cast<milliseconds>(this->lastPingRequest - this->lastPingResponse).count(),
                                 duration_cast<milliseconds>(time - this->lastPingResponse).count(),
                                 duration_cast<milliseconds>(time - this->lastPingRequest).count());
                    this->disconnect(ViewReasonId::VREASON_TIMEOUT, "No ping response", nullptr, true);
 
Kevinos

Kevinos

Fanatic member
Premium User
WolverinDEV said:
Do you may have the server log?
There should be a detailed entry about that.
(Last ping send, last ping received etc.)
Code: 
debugMessage(this->getServerId(), "{} Got a ping timeout. (Last successful ping: {}ms ago. Last request {}ms. Last response {}ms)",
                                 CLIENT_STR_LOG_PREFIX,
                                 duration_cast<milliseconds>(this->lastPingRequest - this->lastPingResponse).count(),
                                 duration_cast<milliseconds>(time - this->lastPingResponse).count(),
                                 duration_cast<milliseconds>(time - this->lastPingRequest).count());
                    this->disconnect(ViewReasonId::VREASON_TIMEOUT, "No ping response", nullptr, true);
Click to expand...
I just have normal log, not any error
 
Vafin

Vafin

TeaFanatic
WolverinDEV said:
Do you may have the server log?
There should be a detailed entry about that.
(Last ping send, last ping received etc.)
Code: 
debugMessage(this->getServerId(), "{} Got a ping timeout. (Last successful ping: {}ms ago. Last request {}ms. Last response {}ms)",
                                 CLIENT_STR_LOG_PREFIX,
                                 duration_cast<milliseconds>(this->lastPingRequest - this->lastPingResponse).count(),
                                 duration_cast<milliseconds>(time - this->lastPingResponse).count(),
                                 duration_cast<milliseconds>(time - this->lastPingRequest).count());
                    this->disconnect(ViewReasonId::VREASON_TIMEOUT, "No ping response", nullptr, true);
Click to expand...
 

Attachments

WolverinDEV

WolverinDEV

TeaSpeak Team
Staff member
Administrator
I habt checkout the log yet because I'm on with my mobile device, but you have to enable devug logging to receive this message :)
 
zhimpa

zhimpa

Member
I've also has many people getting no ping response. This is what the log says for everyone.

[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Got a ping timeout. (Last successful ping: 20469ms ago)
[2018-10-22 21:50:43] [ERROR] 1 | Ticking of client 158.174.11.130:64219 (Jenin) needs more that 2500 microseconds! (2909 microseconds)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:44] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:44] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:44] [DEBUG] 1 | [Property] Changed property in db key: client_month_online_time value: 165120
[2018-10-22 21:50:44] [DEBUG] 1 | [Property] Changed property in db key: client_total_online_time value: 642720
[2018-10-22 21:50:44] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:45] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:45] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/undefined] Creating VoiceClient instance at 0x7f3103019810
[2018-10-22 21:50:46] [DEBUG] 1 | Allocated new voice client connection at 0x7f3115ebdb80
[2018-10-22 21:50:46] [DEBUG] 39200 | Remote address (v4) 192.168.1.100 destination address 192.168.1.100
[2018-10-22 21:50:46] [DEBUG] 1 | Having new voice client. Remote address: 158.174.11.130:64218
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/undefined] Got client 3.1 protocol with build timestamp 180180065
[2018-10-22 21:50:46] [ERROR] 1 | [158.174.11.130:64218/undefined] Handling of packet Init1 needs more than 10ms (10ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/undefined] Got client ek!
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 26 (New value: 67, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [DEBUG] 1 | Loaded client from database. Database id: 26 Unique id: +j+TGEoRX+VC+2jpt6mqRYKRQb0=
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/Jenin] Got client init. (HWID: 800d57d749a5f61e28b30f20c36850f4,a27fb25149311fe86c4c43ea55a4c279)
[2018-10-22 21:50:46] [DEBUG] 1 | [Permission] Value test result for test type 1.
[2018-10-22 21:50:46] [DEBUG] 1 | [Permission] Permission: b_client_ignore_bans Required value: 1 Gained value: -2 Force required: 1 Channel: none Result: 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND (`name` = :name OR `strType` = :type) AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND `uid` = :uid AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND `ip` = :ip AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND `hwid` = :hwid AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_lastconnected' for 26 (New value: 1540237846, SQL: UPDATE `clients` SET `lastConnect` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 26 (New value: 68, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [INFO] 1 | [158.174.11.130:64218/Jenin] Client may cached a old permission list (Server is newer than the client's last join)
[2018-10-22 21:50:46] [ERROR] 1 | Command handling of command clientinit needs 14ms
[2018-10-22 21:50:46] [ERROR] 1 | [158.174.11.130:64218/Jenin] Handling of packet Command needs more than 10ms (15ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changed property in db key: client_nickname value: Jenin1
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_nickname' for 26 (New value: Jenin1, SQL: UPDATE `clients` SET `lastName` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [DEBUG] 1 | Updating server property: virtualserver_last_client_connect. New value: 1540237846. Query: UPDATE `properties` SET `value` = :value WHERE `serverId` = :sid AND `type` = :type AND `id` = :id AND `key` = :key
[2018-10-22 21:50:46] [DEBUG] 1 | Updating server property: virtualserver_client_connections. New value: 5163. Query: UPDATE `properties` SET `value` = :value WHERE `serverId` = :sid AND `type` = :type AND `id` = :id AND `key` = :key
[2018-10-22 21:50:46] [DEBUG] GEN | Client Jenin1|158.174.11.130 comes from Sweden|SE
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/Jenin1] Got a channel talk power of 0 Talk power set is 0
[2018-10-22 21:50:46] [DEBUG] 1 | Client id: 19
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/Jenin1] Got a channel talk power of 0 Talk power set is 0
[2018-10-22 21:50:46] [DEBUG] 1 | [Permission] Value test result for test type 1.
 
WolverinDEV

WolverinDEV

TeaSpeak Team
Staff member
Administrator
zhimpa said:
I've also has many people getting no ping response. This is what the log says for everyone.

[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Got a ping timeout. (Last successful ping: 20469ms ago)
[2018-10-22 21:50:43] [ERROR] 1 | Ticking of client 158.174.11.130:64219 (Jenin) needs more that 2500 microseconds! (2909 microseconds)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:43] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:44] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:44] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:44] [DEBUG] 1 | [Property] Changed property in db key: client_month_online_time value: 165120
[2018-10-22 21:50:44] [DEBUG] 1 | [Property] Changed property in db key: client_total_online_time value: 642720
[2018-10-22 21:50:44] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:45] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:45] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64219/Jenin] Resend 1 packets (Expected response within 28.1265ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/undefined] Creating VoiceClient instance at 0x7f3103019810
[2018-10-22 21:50:46] [DEBUG] 1 | Allocated new voice client connection at 0x7f3115ebdb80
[2018-10-22 21:50:46] [DEBUG] 39200 | Remote address (v4) 192.168.1.100 destination address 192.168.1.100
[2018-10-22 21:50:46] [DEBUG] 1 | Having new voice client. Remote address: 158.174.11.130:64218
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/undefined] Got client 3.1 protocol with build timestamp 180180065
[2018-10-22 21:50:46] [ERROR] 1 | [158.174.11.130:64218/undefined] Handling of packet Init1 needs more than 10ms (10ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/undefined] Got client ek!
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 26 (New value: 67, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [DEBUG] 1 | Loaded client from database. Database id: 26 Unique id: +j+TGEoRX+VC+2jpt6mqRYKRQb0=
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/Jenin] Got client init. (HWID: 800d57d749a5f61e28b30f20c36850f4,a27fb25149311fe86c4c43ea55a4c279)
[2018-10-22 21:50:46] [DEBUG] 1 | [Permission] Value test result for test type 1.
[2018-10-22 21:50:46] [DEBUG] 1 | [Permission] Permission: b_client_ignore_bans Required value: 1 Gained value: -2 Force required: 1 Channel: none Result: 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND (`name` = :name OR `strType` = :type) AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND `uid` = :uid AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND `ip` = :ip AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] GEN | Query: SELECT * FROM `bannedClients` WHERE (`serverId` = 0 OR `serverId` = :sid) AND `hwid` = :hwid AND (bannedClients.`until` > :time OR bannedClients.`until` = 0) -> 0
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_lastconnected' for 26 (New value: 1540237846, SQL: UPDATE `clients` SET `lastConnect` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_totalconnections' for 26 (New value: 68, SQL: UPDATE `clients` SET `connections` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [INFO] 1 | [158.174.11.130:64218/Jenin] Client may cached a old permission list (Server is newer than the client's last join)
[2018-10-22 21:50:46] [ERROR] 1 | Command handling of command clientinit needs 14ms
[2018-10-22 21:50:46] [ERROR] 1 | [158.174.11.130:64218/Jenin] Handling of packet Command needs more than 10ms (15ms)
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changed property in db key: client_nickname value: Jenin1
[2018-10-22 21:50:46] [DEBUG] 1 | [Property] Changing client property 'client_nickname' for 26 (New value: Jenin1, SQL: UPDATE `clients` SET `lastName` = :value WHERE `serverId` = :serverId AND `cldbid` = :cldbid)
[2018-10-22 21:50:46] [DEBUG] 1 | Updating server property: virtualserver_last_client_connect. New value: 1540237846. Query: UPDATE `properties` SET `value` = :value WHERE `serverId` = :sid AND `type` = :type AND `id` = :id AND `key` = :key
[2018-10-22 21:50:46] [DEBUG] 1 | Updating server property: virtualserver_client_connections. New value: 5163. Query: UPDATE `properties` SET `value` = :value WHERE `serverId` = :sid AND `type` = :type AND `id` = :id AND `key` = :key
[2018-10-22 21:50:46] [DEBUG] GEN | Client Jenin1|158.174.11.130 comes from Sweden|SE
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/Jenin1] Got a channel talk power of 0 Talk power set is 0
[2018-10-22 21:50:46] [DEBUG] 1 | Client id: 19
[2018-10-22 21:50:46] [DEBUG] 1 | [158.174.11.130:64218/Jenin1] Got a channel talk power of 0 Talk power set is 0
[2018-10-22 21:50:46] [DEBUG] 1 | [Permission] Value test result for test type 1.
Click to expand...
You're definitely not using the latest version my friend :)

@Vafin
Youre monitoring the server. I'd there a connection between hight CPU usage and ping timeouts?
 
zhimpa

zhimpa

Member
Sorry, I’ve thought I updated the server yesterday but I didn’t for some reason. Now its done, so hopefully everythings working! Thank you for all the great work! :)
 
WolverinDEV

WolverinDEV

TeaSpeak Team
Staff member
Administrator
Well never improved the mechanism itself because I think it's kind of a normal timeout, but i print now more info when disconnecting a client %)
 
Status
Not open for further replies.