Completed Nach einiger Zeit kommt kein Ton mehr an!

[h1web]

Hi!

Nach einiger Zeit kommt bei den Freunden kein Ton mehr an, aber selbst sieht man im Client die blaue Leuchte- stimmt also alles. Nach einem Client Restart hoert der "Gegenueber" mich wieder. Passiert, wenn man lange am Server verbunden ist.

 

[iKaros]

Try reinstall all or install on other machine to test it. If you later have the same problem, post again.

 

[WolverinDEV]

Hmm ich habe da so ne Idee.
Steht denn was bestimmtes im log?
Das könnte mit den packet Generationen zutun haben, denn weil du über 65536 (2^32) mal 20ms redest.

 

[h1web]

[2018-07-31 12:29:25] [DEBUG]    49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-07-31 14:50:05] [DEBUG]    49 | [Permission] Value test result for test type 1.
[2018-07-31 14:50:05] [DEBUG]    49 | [Permission]    Permission: i_channel_subscribe_power Required value: 0 Gained value: 75 Force required: 0 Channel: Camaiore eSports Result: 1
[2018-07-31 14:50:05] [DEBUG]    49 | [Permission] Value test result for test type 1.
[2018-07-31 14:50:05] [DEBUG]    49 | [Permission]    Permission: i_channel_subscribe_power Required value: 0 Gained value: 75 Force required: 0 Channel: TEEEEEEEEEST Result: 1
[2018-07-31 14:50:05] [DEBUG]    49 | [Permission] Value test result for test type 1.
[2018-07-31 14:50:05] [DEBUG]    49 | [Permission]    Permission: i_channel_subscribe_power Required value: 0 Gained value: 75 Force required: 0 Channel: Saym1n Result: 1
[2018-07-31 14:50:05] [TRACE]    49 | [188.194.xxx:62452/ven000m][Command][Server -> Client] Sending command notifychannelsubscribed. Command low: false. Full command: notifychannelsubscribed es=1533041405 cid=135|es=1533041405 cid=137|es=1533041405 cid=138|es=1533041405 cid=139|es=1533041405 cid=243|es=1533041405 cid=141|es=1533041405 cid=2416|es=1533041405 cid=144|es=94397 cid=143|es=1533041405 cid=147|es=1533041405 cid=142|es=24301 cid=1275|es=46228 cid=145|es=0 cid=146|es=1533041405 cid=232|es=46231 cid=3617|es=1533041405 cid=7114|es=1533041405 cid=7118|es=1533041405 cid=7116|es=1533041405 cid=7115|es=1533041405 cid=7117|es=1533041405 cid=4047|es=1533041405 cid=1263|es=1533041405 cid=2442|es=1533041405 cid=4037|es=1533041405 cid=4044|es=1533041405 cid=4045|es=1533041405 cid=5616|es=1533041405 cid=5498|es=1533041405 cid=149|es=1533041405 cid=150|es=1533041405 cid=151|es=1533041405 cid=152|es=1533041405 cid=153|es=1533041405 cid=6634|es=1533041405 cid=154|es=1533041405 cid=5497|es=1533041405 cid=5605|es=1533041405 cid=2614|es=1533041405 cid=681|es=1533041405 cid=2914|es=1533041405 cid=3041|es=1533041405 cid=5540|es=1533041405 cid=5541|es=1533041405 cid=5542|es=1533041405 cid=5543|es=1533041405 cid=5544|es=1533041405 cid=5545|es=1533041405 cid=5546|es=1533041405 cid=5548|es=1533041405 cid=6540|es=1533041405 cid=7170
[2018-07-31 14:50:05] [TRACE]    49 | [188.194.xxx:62452/ven000m][Command][Server -> Client] Sending command error. Command low: false. Full command: error id=0 msg=ok return_code=1:2
[2018-07-31 14:50:05] [ERROR]    49 | Command handling of command channelsubscribeall needs 14ms
[2018-07-31 14:50:05] [ERROR]    49 | [188.194.xxx:62452/ven000m] Handling of packet Command needs more than 10ms (14ms)
[2018-07-31 14:50:08] [ERROR]    49 | Ticking of client 188.194.xxx:62452 (ven000m) needs more that 2500 microseconds! (6900 microseconds)
[2018-07-31 14:50:15] [ERROR]    49 | Ticking of client 79.239.xxx:60401 (Alexa Echo) needs more that 2500 microseconds! (2626 microseconds)
[2018-07-31 14:50:15] [TRACE]    49 | [188.194.xxx:62452/ven000m][Command][Client -> Server] Processing command:
[2018-07-31 14:50:15] [DEBUG]    49 | [Permission] Value test result for test type 1.
[2018-07-31 14:50:15] [DEBUG]    49 | [Permission]    Permission: b_client_allow_invalid_packet Required value: 1 Gained value: 1 Force required: 1 Channel: [cspacer] ★ Eingangshalle ★ Result: 1
[2018-07-31 14:50:15] [ERROR]    49 | Missing command ''
[2018-07-31 14:50:15] [TRACE]    49 | [188.194.xxx:62452/ven000m][Command] Command  with return code <undefined> fails and returns error code 0x0002. Properties: {}
[2018-07-31 14:50:15] [TRACE]    49 | [188.194.xxx:62452/ven000m][Command][Server -> Client] Sending command error. Command low: false. Full command: error id=2 msg=not\simplemented
[2018-07-31 14:50:15] [ERROR]    49 | Command handling of command  needs 32ms
[2018-07-31 14:50:15] [ERROR]    49 | [188.194.xxx:62452/ven000m] Handling of packet Command needs more than 10ms (32ms)
[2018-07-31 14:50:32] [ERROR]    49 | Found varianzes within the server tick! (Supposed: 500ms Hold: 510ms)
[2018-07-31 14:50:33] [ERROR]    49 | Found varianzes within the server tick! (Supposed: 500ms Hold: 489ms)
[2018-07-31 14:50:59] [TRACE]    49 | [79.239.xxx:60401/Alexa Echo][Command][Client -> Server] Processing command: connectioninfoautoupdate connection_server2client_packetloss_speech='0.0000' connection_server2client_packetloss_keepalive='0.0000' connection_server2client_packetloss_control='0.0000' connection_server2client_packetloss_total='0.0000'
[2018-07-31 14:51:00] [TRACE]    49 | [Property] Not saving property 'connection_server2client_packetloss_keepalive', changed for 3075 (New value: 0.0000)
[2018-07-31 14:51:00] [TRACE]    49 | [Property] Not saving property 'connection_server2client_packetloss_control', changed for 3075 (New value: 0.0000)
[2018-07-31 14:51:00] [TRACE]    49 | [Property] Not saving property 'connection_server2client_packetloss_speech', changed for 3075 (New value: 0.0000)
[2018-07-31 14:51:00] [TRACE]    49 | [Property] Not saving property 'connection_server2client_packetloss_total', changed for 3075 (New value: 0.0000)
[2018-07-31 14:51:00] [ERROR]    49 | Command handling of command connectioninfoautoupdate needs 427ms
[2018-07-31 14:51:00] [ERROR]    49 | [79.239.xxx:60401/Alexa Echo] Handling of packet Command needs more than 10ms (427ms)
[2018-07-31 14:51:01] [ERROR]    49 | Found varianzes within the server tick! (Supposed: 500ms Hold: 519ms)
[2018-07-31 14:51:01] [ERROR]    49 | Found varianzes within the server tick! (Supposed: 500ms Hold: 480ms)
[2018-07-31 14:51:11] [ERROR]    49 | Found varianzes within the server tick! (Supposed: 500ms Hold: 595ms)


[2018-07-31 14:50:25] [ERROR] Timer flush needs more than 82 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:25] [ERROR] Timer statistics tick needs more than 56 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:25] [ERROR] Timer statistics tick needs more than 78 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:26] [ERROR] Timer statistics tick needs more than 30 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:26] [ERROR] Timer statistics tick needs more than 30 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:28] [ERROR] Timer statistics tick needs more than 171 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:28] [ERROR] Timer statistics tick needs more than 19 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:33] [ERROR] Timer flush needs more than 96 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:55] [ERROR] Timer flush needs more than 40 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:51:00] [ERROR] Timer flush needs more than 315 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:51:10] [ERROR] Timer flush needs more than 192 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:51:11] [ERROR] Timer flush needs more than 8026 microseconds. Max allowed was 5000 microseconds.
[2018-07-31 14:50:23] [DEBUG]   GEN | Invalid packet id pushback. Current index: 212 packet index: 210 (Data: getconnectioninfo clid=1 return_code=1:3e)
[2018-07-31 14:50:24] [DEBUG]   GEN | Invalid packet id pushback. Current index: 215 packet index: 214 (Data: G¿
[2018-07-31 14:50:25] [ERROR] Timer statistics tick needs more than 308 milliseconds. Max allowed was 5 milliseconds.
[2018-07-31 14:50:25] [DEBUG]   GEN | Invalid packet id pushback. Current index: 215 packet index: 214 (Data: G¿

 

[WolverinDEV]

Hmm sure that there a client can't speak anymore? Doesn't seems to me to be like tgat

 

[h1web]

Yep, im waiting for the Client log by my dude. Any suggestions in the other ERROR logs to prevent them?

 

[h1web]

Could it be when we restore a 3.0.18 backup to the TeaSpeak that people wont be able to talk after a while, or do i have to change some config.yml values?

 

[WolverinDEV]

No the talk stuff isn't related to snapshots

 

[h1web]

Two music bots runs now on 2 TeaSpeak Servers.

1 from restore
1 fresh created

I'll report back when one stops playing.

 

[h1web]

Another bug:

Channel passwords not working when restoring from 3.0.18 backup to current TeaSpeak Servers
If channel permissions set to 9999999 on the old server, then restoring to TeaSpeak, serveradmins cant join this channels
<15:23:38> Unzureichende Client Rechte (gescheitert an i_channel_join_power)
<15:23:45> Keine Rechte, die Beschreibung des Channels "Tajna odaja" anzuzeigen

 

[h1web]

If you use a Serveradmin Privilege key, you get Serveradmin.
Then you can set yourself Server Admin again -> You are twice in Server Admin Group

<15:36:36> "-Nikoladiv" wurde von "Nikoladiv beTa" zur Server Gruppe "Server Admin" zugewiesen.
<15:36:40> "-Nikoladiv" wurde von "-Nikoladiv" zur Server Gruppe "Server Admin" zugewiesen.

You cant remove the Privilege Key - Server Admin Group

 

[h1web]

So my result after starting two musicbots at the same time with permanent music playing:

- on the fresh created teaspeak server, music still plays
- on the restored 3.0.18 server, bot is not playing anymore > but in the webcontrol everything is ok and "playing". Sound is just not coming through somehow

Tested it several times!

TeaSpeak Servers, that are created by YaTQA snapshot of older versions -> people cant talk after some connected time.
TeaSpeak Servers, new created by YaTQA are working without problems.

 

[WolverinDEV]

So my result after starting two musicbots at the same time with permanent music playing:

- on the fresh created teaspeak server, music still plays
- on the restored 3.0.18 server, bot is not playing anymore > but in the webcontrol everything is ok and "playing". Sound is just not coming through somehow

Tested it several times!

TeaSpeak Servers, that are created by YaTQA snapshot of older versions -> people cant talk after some connected time.
TeaSpeak Servers, new created by YaTQA are working without problems.

Hmm okey thats funny.
Do you have both servers running on the same instance? I'll test this definitely later. Seems quite funny

 

[h1web]

Hmm okey thats funny.
Do you have both servers running on the same instance? I'll test this definitely later. Seems quite funny

Yes same instance.

Also more errors incoming while people getting dropped by the Server

(invalid parameter size)
(undefined error)

 

[WolverinDEV]

Hey,
i need packet (verbose log) which cause these disconnects.
A client side log is recommended as well!

 

[h1web]

since more than 10000 chars,
https://pastebin.com/raw/CCZ2CTV3

Client (3.2.0 Beta) Log:

01.08.2018 18:34:19    SCHandler    Warning    Error from ChanClients: invalid parameter size   
01.08.2018 18:34:19    SCHandler    Warning    Error while parsing packet: undefined error   
01.08.2018 18:34:19    ClientUI    Info    Connect status: Disconnected   
01.08.2018 18:34:19    ClientUI    Info    Disconnected from server, no autoreconnect: undefined error

and from another Persons Client (3.1.10):

01-Aug-18 18:34:17SCHandlerWarningError from ChanClients: invalid parameter size
01-Aug-18 18:34:17SCHandlerWarningError while parsing packet: undefined error
01-Aug-18 18:34:17ClientUIInfoConnect status: Disconnected
01-Aug-18 18:34:17ClientUIInfoDisconnected from server, no autoreconnect: undefined error

 

[h1web]

It looks like this is the ERROR for my Music Bot of the restored Server instance, which cant play music anymore

[2018-08-01 20:33:09] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-01 20:33:09] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-01 20:33:09] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-01 20:33:09] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!

The LOG is full of it.

 

[WolverinDEV]

since more than 10000 chars,
https://pastebin.com/raw/CCZ2CTV3

Client (3.2.0 Beta) Log:

01.08.2018 18:34:19    SCHandler    Warning    Error from ChanClients: invalid parameter size  
01.08.2018 18:34:19    SCHandler    Warning    Error while parsing packet: undefined error  
01.08.2018 18:34:19    ClientUI    Info    Connect status: Disconnected  
01.08.2018 18:34:19    ClientUI    Info    Disconnected from server, no autoreconnect: undefined error

and from another Persons Client (3.1.10):

01-Aug-18 18:34:17SCHandlerWarningError from ChanClients: invalid parameter size
01-Aug-18 18:34:17SCHandlerWarningError while parsing packet: undefined error
01-Aug-18 18:34:17ClientUIInfoConnect status: Disconnected
01-Aug-18 18:34:17ClientUIInfoDisconnected from server, no autoreconnect: undefined error

Hmm okey,
its sad that he hasnt the TS3 hook to see which packet causes this
I just can guess that this error is something related with channel stuff But thanks anyways.

And yes "memory verify failed" could be the reason. I may improved the debug message and than may its getting more clear

 

[WolverinDEV]

https://github.com/TeaSpeak/TeaSpeak/issues/245

 

[h1web]

Bug still happens after ~10 minutes on restored servers

[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!
[2018-08-03 16:20:54] [DEBUG] 49 | Cant decrypt packet of type Voice. Dropping packet. Error: memory verify failed!