Completed Clients can't connect

[katrio]

Hello,
I have problem with TeaSpeak. Suddenly clients can't connect:

[12.34.56.78:54149/undefined | 0][HANDSCHAKE] Client wants handshake step 2 but we want a cookie request. Resetting client
[2018-11-21 15:26:17] [DEBUG] 2 | [12.34.56.78:54149/undefined | 0] Got non client 3.1 protocol with build timestamp 177256836
[2018-11-21 15:26:17] [DEBUG] 2 | [12.34.56.78:54149/undefined | 0] Cant decrypt packet with setupped key Ack. Error: memory verify failed!. Suceeded with default key!

How to fix it? Protocol key is the same all the time (converted AAL, version: 0)...

 

[katrio]

And I got spam like this:
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Resend 1 packets (Expected response within 66.3331ms)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (40)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (39)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (44)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (43)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (46)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (47)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (53)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (48)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (54)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (50)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (52)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (55)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (58)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (59)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (60)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (64)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (61)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (62)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (66)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (65)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (67)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (69)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (68)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (72)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (70)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Failed to handle acknowledge: Missing packet id (71)
[2018-11-21 15:28:35] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Resend 1 packets (Expected response within 69.7295ms)
[2018-11-21 15:28:36] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Update client variable via 'clientupdate'. Variable name: client_badges, Variable value: overwolf=0
[2018-11-21 15:28:38] [ERROR] 2 | Command handling of command channelsubscribeall needs 21ms
[2018-11-21 15:28:38] [ERROR] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Handling of packet Command needs more than 10ms (21ms)
[2018-11-21 15:28:38] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Resend 2 packets (Expected response within 66.4685ms)
[2018-11-21 15:28:44] [DEBUG] 2 | [Permission] Value test result for test type 1.
[2018-11-21 15:28:44] [DEBUG] 2 | [Permission] Permission: i_channel_description_view_power Required value: 0 Gained value: 50 Force required: 0 Channel: [28] Wiara Rodzina W▒asno▒▒ Result: 1
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Value test result for test type 1.
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Permission: b_channel_join_permanent Required value: 1 Gained value: 1 Force required: 1 Channel: [28] Wiara Rodzina W▒asno▒▒ Result:
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Value test result for test type 1.
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Permission: b_channel_ignore_join_power Required value: 1 Gained value: -2 Force required: 1 Channel: [28] Wiara Rodzina W▒asno▒▒ Result: 0
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Value test result for test type 1.
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Permission: i_channel_join_power Required value: 0 Gained value: 50 Force required: 0 Channel: [28] Wiara Rodzina W▒asno▒▒ Result: 1
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Value test result for test type 1.
[2018-11-21 15:28:46] [DEBUG] 2 | [Permission] Permission: b_channel_ignore_view_power Required value: 1 Gained value: -2 Force required: 1 Channel: none Result: 0
[2018-11-21 15:28:46] [DEBUG] 2 | [188.146.165.131:6817/Tadeusz Sznuk zza grobu | 3] Got a channel talk power of 25 Talk power set is 2

 

[katrio]

On command line there are some chars: 1;2c1;2c1;2c1;2c1;2c1;2c1;2c1;2c

 

[katrio]

[2018-11-21 16:44:07] [DEBUG] GEN | Dispatching io base 0x7f19558feb00
[2018-11-21 16:44:07] [DEBUG] GEN | Dispatching io base 0x7f19558fe800
[2018-11-21 16:44:07] [DEBUG] 1 | [WebList] Requesting from local address 0.0.0.0:9911:30567
[2018-11-21 16:44:07] [DEBUG] 2 | [WebList] Requesting from local address 0.0.0.0:9987:30568
[2018-11-21 16:44:12] [ERROR] 2 | [WebList] Status update failed. Error: timeout
[2018-11-21 16:44:12] [INFO] 2 | [WebList] Scheduling next update attempt in 5 seconds.
[2018-11-21 16:44:12] [ERROR] 1 | [WebList] Status update failed. Error: timeout
[2018-11-21 16:44:12] [INFO] 1 | [WebList] Scheduling next update attempt in 5 seconds.
[2018-11-21 16:44:12] [DEBUG] 1 | [WebList] Requesting from local address 0.0.0.0:9911:30567
[2018-11-21 16:44:12] [DEBUG] 2 | [WebList] Requesting from local address 0.0.0.0:9987:30568
[2018-11-21 16:44:14] [DEBUG] 2 | [178.36.238.157:60682/undefined] Creating VoiceClient instance at 0x7f194f93ec10
[2018-11-21 16:44:14] [DEBUG] 2 | Allocated new voice client connection at 0x7f194c51f900
[2018-11-21 16:44:14] [DEBUG] 2 | Remote address (v4) 51.254.161.157 destination address 51.254.161.157
[2018-11-21 16:44:14] [DEBUG] 2 | Having new voice client. Remote address: 178.36.238.157:60682
[2018-11-21 16:44:14] [DEBUG] 2 | [178.36.238.157:60682/undefined | 0] Got non client 3.1 protocol with build timestamp 181468630
[2018-11-21 16:44:14] [DEBUG] 2 | [178.36.238.157:60682/undefined | 0] Cant decrypt packet with setupped key Ack. Error: memory verify failed!. Suceeded with default key!
[2018-11-21 16:44:17] [ERROR] 2 | [WebList] Status update failed. Error: timeout
[2018-11-21 16:44:17] [INFO] 2 | [WebList] Scheduling next update attempt in 30 seconds.
[2018-11-21 16:44:17] [ERROR] 1 | [WebList] Status update failed. Error: timeout
[2018-11-21 16:44:17] [INFO] 1 | [WebList] Scheduling next update attempt in 30 seconds.

 

[katrio]

Any help? My server is down for about a day...

 

[edsandro]

Your server is in which version? And which operating system are you using?

 

[katrio]

Newest version, Debian 8. Problem start to appear on 1.2.27 version.

 

[WolverinDEV]

Well Is it only from the 1.2.27 or is something with the Tea*Speak license invalid.
I think there might be the issue.

 

[katrio]

@WolverinDEV
1.2.27 was working without problems for very long time.. License hasn't change... what should I do?

 

[WolverinDEV]

Thats incredible unusual, and as a test, when you downgrade your server any try to connect is the license still invalid?

 

[deviousangel]

@WolverinDEV
1.2.27 was working without problems for very long time.. License hasn't change... what should I do?

Hmmm try updating to 1.2.28-beta

 

[katrio]

Hmmm try updating to 1.2.28-beta

It is on 1.2.28, problem exsist from 1.2.27.

Thats incredible unusual, and as a test, when you downgrade your server any try to connect is the license still invalid?

I will try it today.

 

[katrio]

Same problem on 1.2.26... Help... my TeaSpeak is down for 2 days :/

 

[katrio]

[2018-11-22 17:31:54] [DEBUG] 2 | [Permission] Permission: i_channel_description_view_power Required value: -2 Gained value: 50 Force required: 0 Channel: jghjhgjghj Result: 1
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Resend 6 packets (Expected response within 55.1696ms)
[2018-11-22 17:31:54] [DEBUG] Requesting server 87.106.252.164
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Resend 6 packets (Expected response within 55.1696ms)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (165)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (167)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (166)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (168)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (169)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (166)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (167)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (170)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (165)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (170)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (168)
[2018-11-22 17:31:54] [DEBUG] 2 | [5.173.105.33:46428/mateuszek | 4] Failed to handle acknowledge: Missing packet id (169)
[2018-11-22 17:31:54] [DEBUG] GEN | Running close in a new thread
[2018-11-22 17:31:54] [DEBUG] GEN | Executing close done
[2018-11-22 17:31:57] [DEBUG] 2 | [83.25.247.107:65446/undefined | 0] Got handshake timeout. 83.25.247.107:65446. State: INIT_HIGH Time: 5
[2018-11-22 17:31:57] [DEBUG] 2 | [83.25.247.107:65446/undefined | 0] Closing voice client connection. (Flush: true)
[2018-11-22 17:31:57] [DEBUG] 2 | [83.25.247.107:65446/undefined | 0] Awaiting write prepare, write and acknowledge queue flushed
[2018-11-22 17:31:57] [DEBUG] 2 | [83.25.247.107:65446/undefined | 0] Write prepare queue progressed
[2018-11-22 17:31:57] [DEBUG] 2 | [83.25.247.107:65446/undefined | 0] Write and acknowledge queue are flushed
[2018-11-22 17:31:57] [INFO] 2 | Voice client 0/gs6TUPpyOoMcyBnQ0vfUPtMu6QM= (undefined) from 83.25.247.107:65446 left.

 

[WolverinDEV]

What does your Tea*Speak client says?

 

[katrio]

What does your Tea*Speak client says?

<17:30:59> Disconnected from server (server version is too old for command)

 

[WolverinDEV]

Did you may disabled the 3.1 support?

 

[katrio]

I don't know why it was switched to 0...
But it's 1 now and problem is the same:
protocol:
#Description:
# Enables the newer and safer protocol based on Tea*Speak's documented standard
#Notes:
# An invalid protocol chain could lead clients to calculate a wrong shared secret result
# This may cause a connection setup fail and the client will be unable to connect!
#The value must be a positive numeric value between 0 and 1
experimental_31: 1

 

[katrio]

Update: still some errors but clients can connect. WTF why it switch without any changes to 0

Update 2: still strange things in command line:
> 1;2c1;2c1;2c1;2c1;2c1;2c1;2c1;2c1;2c1;2c1;2c1;2c

 

[WolverinDEV]

Well the things in the com. And line doesn't matter, seems like you're interpreting ANSI codes a littlebit odfferent

And does it work now?