NurKai3
commented
4 years ago We also have the problem that either all bots die out or only some of them fail. LG RadioFire
Closed officialLucas closed 3 years ago
NurKai3
commented
4 years ago We also have the problem that either all bots die out or only some of them fail. LG RadioFire
Splamy
commented
4 years ago You already postet pretty much the same problem a while ago without any answer (#705), so my same plea again: Info+ logs are fine to realize that something happened, but I will need Debug logs to diagnose what or why something happened.
I've also ran a little experiment yesterday to simulate the same situation. Running 25 bots twice, each 3h, one with local files and one with radio streams. Bot runs had no issues, timeouts, hiccups or whatsoever. If I can't find anything with better log I'd suggestet to meet on ts. I could try to create a special debug build for that
officialLucas
commented
4 years ago Guten Mittag,
das Ticket, welches ich vor einigen Monaten eröffnet habe hat sich plötzlich geklärt. Die TimeOuts waren einfach weg.
Gegen ein Teamspeak-Gespräch habe ich nichts dagegen. Stelle mir gerne einige Termine zur Verfügung, an welchen du Zeit hast.
Mit freundlichen Grüßen Lucas
Splamy
commented
4 years ago pretty much every day 10:00-00:00, except tomorrow, or when someting new comes up. Best would be for you if you just come on my server with your name and I'll move you when I'm free
officialLucas
commented
4 years ago Guten Abend,
meinst du 10 Uhr morgens? Wie ist deine TeamSpeak-IP? Bin mir nicht sicher ob ich auf dem richtigen bin.
Ich würde am Samstag vorbeikommen.
Mit freundlichen Grüßen Lucas
Splamy
commented
4 years ago Yes, you just were on the correct server. Saturday, seems fine
officialLucas
commented
4 years ago Guten Abend,
da ich derzeit nicht auf an meinem Computer bin, werde ich am Samstag kommen. Ich danke schonmal für die schnellen Antworten.
NurKai3
commented
4 years ago Hey, also ich habe mir mal einen Server geschnappt und auf diesen die 0.11.0 drauf gemacht. Dieser läuft nun seit 24 Stunden ohne Probleme und laggs. Der andere Server mit 0.10.0 ist derweil wieder getimeoutet.
Als kleine Information ^^
Grüße Kai
Splamy
commented
4 years ago Interessanter Hinweis. Ich hatte, soweit ich mich erinnern kann, zwischen master/dev keine signifikanten tslib Ändernungen von daher hätte ich nich erwartet dass das irgendwas bringen könnte, ich werds aber mal im Auge behalten.
officialLucas
commented
4 years ago Guten Mittag,
derzeit timen die Musikbots überhaupt nicht aus. Bitte das Ticket offen lassen, falls diese wieder austimen werde ich eine Antwort / den Debug-Log senden.
Mit freundlichen Grüßen Lucas
officialLucas
commented
4 years ago Guten Mittag,
da die Musikbots seit gestern Abend wieder regelmäßig austimen, würde ich gerne erneut ein kleines Gespräch auf dem TeamSpeak vereinbaren. Ich bin derzeit auf deinem TeamSpeak, falls du da bist.
Mit freundlichen Grüßen Lucas
NurKai3
commented
4 years ago Hallo, seit dem ich den Anbieter von Unesty zu OVH gewechselt bin, passiert nichts mehr.
Am Mo., 23. März 2020 um 13:30 Uhr schrieb officialLucas < notifications@github.com>:
Guten Mittag,
da die Musikbots seit gestern Abend wieder regelmäßig austimen, würde ich gerne erneut ein kleines Gespräch auf dem TeamSpeak vereinbaren. Ich bin derzeit auf deinem TeamSpeak, falls du da bist.
Mit freundlichen Grüßen Lucas
— You are receiving this because you commented. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-602561523, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EXCYI3LKLVYOT4XPK3RI5I6PANCNFSM4LFA3BEQ .
NurKai3
commented
4 years ago Heute Morgen um 6 Uhr ist einfach TS3AudioBot gestoppt. Die letzten paar Zeilen von Log her. Ts3AudioBot lieg mit 30 Bots auf einem OVH VPS 1 GB Server.
06:07:52.5516| WARN|3| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:07:52.5628| WARN|14| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:07:53.7677| WARN|26| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:08:29.4481|FATAL|| Critical program error!
06:08:59.0256| INFO|9| Trying to reconnect because of Timeout. Delaying reconnect for 300 seconds
06:09:58.7479| WARN|27| Previous resend tick didn't finish
06:10:12.2352| INFO|13| Trying to reconnect because of Timeout. Delaying reconnect for 1 seconds
06:10:13.4915| INFO|13| Bot "1-1581263140-3nOQ1g"(13) connected.
06:10:15.8896| WARN|27| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:16.2687| WARN|1| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:17.1954| WARN|28| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:30.6465| WARN|0| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:31.2960| WARN|18| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:31.7405| WARN|16| Unknown Teamspeak Error. (Connection closed) ()
06:10:31.7664| WARN|17| Unknown Teamspeak Error. (Connection closed) ()
06:10:31.8143| WARN|13| Unknown Teamspeak Error. (Connection closed) ()
06:10:31.8685| WARN|24| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:32.0118| WARN|26| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:32.0701| WARN|5| Unknown Teamspeak Error. (Connection closed) ()
06:10:32.2095| WARN|13| Unknown Teamspeak Error. (Connection closed) ()
06:10:32.2956| WARN|3| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:32.3836| WARN|14| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:32.5858| WARN|20| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:34.3591| WARN|27| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:34.7144| WARN|1| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:35.6452| WARN|28| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:37.5878| WARN|23| Unknown Teamspeak Error. (Missing teamspeak permissions.) (b_client_modify_own_description)
06:10:40.6605| INFO|5| Trying to reconnect because of Timeout. Delaying reconnect for 300 seconds
06:10:44.9292| INFO|13| Trying to reconnect because of Timeout. Delaying reconnect for 1 seconds
06:10:44.9345|FATAL|13| Critical program failure!
06:10:44.9504| INFO|13| TS3AudioBot shutting down.
06:10:44.9504| INFO|13| Bot (0) disconnecting.
06:10:44.9871| WARN|13| Unknown Teamspeak Error. (Connection closed) ()
06:10:45.0020| INFO|13| Bot (1) disconnecting.
06:10:45.0307| INFO|13| Bot (2) disconnecting.
06:10:45.0577| INFO|13| Bot (3) disconnecting.
06:10:45.0784| INFO|13| Bot (4) disconnecting.
06:10:45.0906| INFO|13| Bot (5) disconnecting.
06:10:45.1087| INFO|13| Bot (6) disconnecting.
06:10:45.1306| INFO|13| Bot (7) disconnecting.
06:10:45.1607| INFO|13| Bot (8) disconnecting.
06:10:45.1808| INFO|13| Bot (9) disconnecting.
06:10:45.1858| INFO|13| Bot (10) disconnecting.
06:10:45.2076| INFO|13| Bot (11) disconnecting.
06:10:45.2308| INFO|13| Bot (12) disconnecting.
06:10:45.2376| INFO|13| Bot (13) disconnecting.
06:10:45.2531| INFO|13| Bot (14) disconnecting.
06:10:45.2713| INFO|13| Bot (15) disconnecting.
06:10:45.2885| INFO|13| Bot (16) disconnecting.
06:10:45.2976| INFO|13| Bot (17) disconnecting.
06:10:45.3086| INFO|13| Bot (18) disconnecting.
06:10:45.3355| INFO|13| Bot (19) disconnecting.
06:10:45.3535| INFO|13| Bot (20) disconnecting.
06:10:45.3665| INFO|13| Bot (21) disconnecting.
06:10:45.3854| INFO|13| Bot (22) disconnecting.
06:10:45.3986| INFO|13| Bot (23) disconnecting.
06:10:45.4116| INFO|13| Bot (24) disconnecting.
06:10:45.4321| INFO|13| Bot (26) disconnecting.
06:10:45.4526| INFO|13| Bot (27) disconnecting.
06:10:45.4679| INFO|13| Bot (28) disconnecting.
06:10:45.4813| INFO|13| WebServer is closing
06:10:45.4884| INFO|13| WebServer has closed
06:08:29.4481|FATAL|| Critical program error! 06:10:44.9345|FATAL|13| Critical program failure!
Die log file bitte dazu
NurKai3
commented
4 years ago Ja... Gibt gerade minimal ein Problem, dass ich anscheinenden die Logfile nicht gesichtert habe und dazu die Log jetzt überschrieben wurde von dem neustart.
Am Sa., 28. März 2020 um 14:47 Uhr schrieb Splamy <notifications@github.com
:
06:08:29.4481|FATAL|| Critical program error! 06:10:44.9345|FATAL|13| Critical program failure!
Die log file bitte dazu
— You are receiving this because you commented. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-605449546, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EWS6OBJWQLWNESBXRLRJX5WHANCNFSM4LFA3BEQ .
Splamy
commented
4 years ago logfiles werden nicht automatisch vom bot überschrieben
NurKai3
commented
4 years ago Wo finde ich den alten Log vor dem neustart den? Da ich jetzt nur dem von dem Neustart habe.
Am Sa., 28. März 2020 um 15:09 Uhr schrieb Splamy <notifications@github.com
:
logfiles werden nicht überschrieben
— You are receiving this because you commented. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-605452249, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EUNZN2JUNXIL4MYGADRJYAKHANCNFSM4LFA3BEQ .
Splamy
commented
4 years ago wird alles in die eine datei reingeklebt
NurKai3
commented
4 years ago http://cdn.radiofire.de/ts3audiobot.log
Am Sa., 28. März 2020 um 15:14 Uhr schrieb Splamy <notifications@github.com
:
wird alles in die eine datei reingeklebt
— You are receiving this because you commented. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-605452816, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3ETRLTHSJEL4ZVJU7N3RJYA3XANCNFSM4LFA3BEQ .
Splamy
commented
4 years ago @NurKai3 :
2020-03-28 06:10:44.9345|FATAL|13|Core.ExceptionHandler Critical program failure! System.InvalidOperationException: Data was not set after trigger
das war der ursprüngliche error der für den crash verantortlich war. Ich hab auf develop n fix gepusht der das fixen sollte.
Ich hab auch n paar änderungen die hoffentlich das hier fixen sollte:
2020-03-28 06:08:29.4481|FATAL||LoggerImpl.Write Critical program error! System.ObjectDisposedException: Cannot access a disposed object.
NurKai3
commented
4 years ago Okay, danke für den Fix :) Beim nächsten Crash, werde ich die neue Version drauf ziehen.
Grüße
Am So., 29. März 2020 um 04:37 Uhr schrieb Splamy <notifications@github.com
:
@NurKai3 https://github.com/NurKai3 :
2020-03-28 06:10:44.9345|FATAL|13|Core.ExceptionHandler Critical program failure! System.InvalidOperationException: Data was not set after trigger
das war der ursprüngliche error der für den crash verantortlich war. Ich hab auf develop n fix gepusht der das fixen sollte.
Ich hab auch n paar änderungen die hoffentlich das hier fixen sollte:
2020-03-28 06:08:29.4481|FATAL||LoggerImpl.Write Critical program error! System.ObjectDisposedException: Cannot access a disposed object.
— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-605549464, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EV6ZWX2RWRSPVNLQUDRJ2X6RANCNFSM4LFA3BEQ .
Splamy
commented
4 years ago @officialLucas Also ich bin jetzt paar Abschnitte durch den log durchgegangen. Meiner Beobachtung nach passieren viele merkwürdige Sachen bei denen ich aber bei keiner explizit ursachen im TSAB ausmachen kann. Viele Pakete kommen sehr lang einfach nich and oder kommen viel zu oft an, das sieht einwenig nach network congestion oder schlechtem packet routing aus.
Meine aktuelle empfehlung is erstmal auf 0.11.0 (master) upzugraden, das scheint bei @NurKai3 gut funktioniert zu haben, und vielleicht testweise von nem anderen host aus laufen zu lassen. Oder auch mal zeitgleich sowas wie sinusbot auf dem selben server testen ob der ähnliches verhalten aufweist.
Btw auf develop haben wir testweise mittlerweile auch native builds, die standalone ohne dotnet core installation auf linux funktionieren, vielleicht zum testen ganz praktisch.
officialLucas
commented
4 years ago Hey,
könnten wir morgen nochmal kurz über TeamSpeak reden? Hast du morgen Zeit? Wenn ja würde ich mal vorbeikommen.
Mit freundlichen Grüßen Lucas
Splamy
commented
4 years ago jo komm einfach vorbei
Splamy
commented
4 years ago Interessante Feststellung gemacht, ich hab mal n bissl versucht das Szenario auf einem linux server mit ner schlechteren cpu nachgestellt und konnte das Problem paar mal reproduzieren. Das würde erklären warum ich das nie auf meinem Windows PC auftreten hatte. Irgend ne mischung aus low cpu und linux wirds sein. Ich werd da morgen mal einwenig reinschauen.
Splamy
commented
4 years ago Okay, ich hab einige Fälle auf meinem Server durchdebugged und ein paar Szenarien gefunden die definitiv zu blockweisen timouts führen kann. Der Threadpool von C# startet bei workspikes zu langsam neue thread und die aktuelle implementierung blockiert alle, wodurch pakete nicht mehr ankommen und soviele bots austimen bis wieder threads frei sind. Das erklät auch warum blockweise pakte wiederholt im log waren, heißt es sollte kein problem am server sein.
Ich bin nun dabei das gesammte scheduling system umzuschreiben, wodurch bots (nahezu) komplett unabhängig gescheduled werden, sowie nutze ich die neuen async schnittstellen welche angenehmere parallelprogrammierung ermöglicht. Beim staten zb sollten jetzt (sofern genug cpu leistung vorhanden) alle bots effektiv gleichzeitig staten und verbinden können statt wie aktuell nacheinander.
Ich erwarte allerdings auch dass es nach dem rework einigen neue fehler geben wird, von daher werd ich mutige tester brauchen die dann den develop branch testen. Ich werd dann in diesen issue ankündigen bzw ein neues announcement issue aufmachen wenn es soweit is.
NurKai3
commented
4 years ago Okay, ja, ich habe auch so 1-2 Contabo Server, mit denen man das testen könnte
Am Fr., 10. Apr. 2020 um 01:58 Uhr schrieb Splamy <notifications@github.com
:
Okay, ich hab einige Fälle auf meinem Server durchdebugged und ein paar Szenarien gefunden die definitiv zu blockweisen timouts führen kann. Der Threadpool von C# startet zu langsam neue thread und die aktuelle implementierung blockiert alle, wodurch pakete nicht mehr ankommen und soviele bots austimen bis wieder threads frei sind. Das erklät auch warum blockweise pakte wiederholt im log waren, heißt es sollte kein problem am server sein.
Ich bin nun dabei das gesammte scheduling system umzuschreiben, wodurch bots (nahezu) komplett unabhängig gescheduled werden, sowie nutze ich die neuen async schnittstellen welche angenehmere parallelprogrammierung ermöglicht. Beim staten zb sollten jetzt (sofern genug cpu leistung vorhanden) alle bots effektiv gleichzeitig staten und verbinden können statt wie aktuell nacheinander.
Ich erwarte allerdings auch dass es nach dem rework einigen neue fehler geben wird, von daher werd ich mutige tester brauchen die dann den develop branch testen. Ich werd dann in in diesen issue ankündigen bzw ein neues announcement issue aufmachen wenn es soweit is.
— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-611808391, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EWQFZLO6J62UGZVODLRLZOIZANCNFSM4LFA3BEQ .
officialLucas
commented
4 years ago Hey,
ich habe mir nun auf den gleichen Server einfach mal eine zweite Instanz von TS3AB aufgesetzt. Diese timed überhaupt nicht und läuft seit über 50h stabil. Aber die alten Bots von der alten Instanz timen weiterhin.
NurKai3
commented
4 years ago Hey Lucas! Mit der neueren Version von TS3AB oder welche Version?
Am Fr., 10. Apr. 2020 um 13:00 Uhr schrieb officialLucas < notifications@github.com>:
Hey,
ich habe mir nun auf den gleichen Server einfach mal eine zweite Instanz von TS3AB aufgesetzt. Diese timed überhaupt nicht und läuft seit über 50h stabil. Aber die alten Bots von der alten Instanz timen weiterhin.
— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-611984377, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EUVT4FTVMI6WRNURNTRL336TANCNFSM4LFA3BEQ .
officialLucas
commented
4 years ago ja, mit der neuen Version
NurKai3
commented
4 years ago Okay, danke
Am Fr., 10. Apr. 2020 um 19:14 Uhr schrieb officialLucas < notifications@github.com>:
ja, mit der neuen Version
— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-612127399, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3EUZLGAMLUN6WOLVQV3RL5HWNANCNFSM4LFA3BEQ .
Splamy
commented
4 years ago Wollt einfach mal bescheidgeben hier, dass auf dem develop branch der rework zum neuen scheduler soweit zum testen is. Feedback zu Problemen wäre gut. Bei meinem Testen konnte ich bisher noch keine offensichtlichen Hangups finden
NurKai3
commented
4 years ago Werde ich Mal in Anspruch nehmen und durchtesten. Dank dir
------ Originalnachricht ------ Von: "Splamy" notifications@github.com An: "Splamy/TS3AudioBot" TS3AudioBot@noreply.github.com Cc: "NurKai3" nurkaiyttv@gmail.com; "Mention" mention@noreply.github.com Gesendet: 14.05.2020 19:32:20 Betreff: Re: [Splamy/TS3AudioBot] TimeOuts (#748)
Wollt einfach mal bescheidgeben hier, dass auf dem develop branch der rework zum neuen scheduler soweit zum testen is. Feedback zu Problemen wäre gut. Bei meinem Testen konnte ich bisher noch keine offensichtlichen Hangups finden
— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/Splamy/TS3AudioBot/issues/748#issuecomment-628781498, or unsubscribe https://github.com/notifications/unsubscribe-auth/ANVL3ETINM7NMHPTRDIV7Z3RRQTKJANCNFSM4LFA3BEQ.
-- Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. https://www.avast.com/antivirus
Auf meinem Server laufen 45 Musikbots auf mehr als 30 verschiedenen TeamSpeak-Servern. Diese timen alle mehrmals am Tag gleichzeitig auf. Die Abstände in welchen die Musikbots austimen sind unregelmäßig.
Version: 0.10.1+2 Debian 9
Log
``` 2020-03-10 15:08:45.0238| INFO||Setup.LogHeader [============ TS3AudioBot started =============] 2020-03-10 15:08:45.1087| INFO||Setup.LogHeader [ Date/Time: Tuesday, March 10, 2020 3:08:45 PM 2020-03-10 15:08:45.1193| INFO||Setup.LogHeader [ Version: 0.10.1+2/master/eb56a2f8 2020-03-10 15:08:45.1208| INFO||Setup.LogHeader [ Platform: Debian GNU/Linux 9 (stretch) (64bit) 2020-03-10 15:08:45.1208| INFO||Setup.LogHeader [ Runtime: .NET Core (2.2.7) ServerGC:True GC:SustainedLowLatency 2020-03-10 15:08:45.1208| INFO||Setup.LogHeader [ Opus: libopus 1.2~alpha2 (x64) 2020-03-10 15:08:45.1208| INFO||Setup.LogHeader [==============================================] 2020-03-10 15:08:45.9017| INFO||Bot.InitializeBot Bot "default" connecting to "happyfm.eu:9987" 2020-03-10 15:08:46.8213| INFO||WebServer.StartWebServer Started Webserver on port 12399 2020-03-10 15:08:47.0092| INFO|0|Bot.OnBotConnected Bot "default"(0) connected. 2020-03-10 15:09:02.1871| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575304087 2020-03-10 15:09:02.3115| WARN||RightsManager.ReadFile Right "cmd.list.save" is not registered. 2020-03-10 15:09:02.3246| INFO||Bot.InitializeBot Bot "1-65-1575304087" connecting to "happyfm.eu" 2020-03-10 15:09:02.4089| INFO|1|Bot.OnBotConnected Bot "1-65-1575304087"(1) connected. 2020-03-10 15:09:03.6938| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/1/(/volume/25) 2020-03-10 15:09:03.7223| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/1/(/bot/commander/on) 2020-03-10 15:09:06.0167| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575304142 2020-03-10 15:09:06.0167| INFO||Bot.InitializeBot Bot "1-65-1575304142" connecting to "happyfm.eu" 2020-03-10 15:09:06.0991| INFO|2|Bot.OnBotConnected Bot "1-65-1575304142"(2) connected. 2020-03-10 15:09:07.0474| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/2/(/volume/25) 2020-03-10 15:09:07.0647| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/2/(/bot/commander/on) 2020-03-10 15:09:09.1024| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1580561914 2020-03-10 15:09:09.1024| INFO||Bot.InitializeBot Bot "1-65-1580561914" connecting to "happyfm.eu" 2020-03-10 15:09:09.2057| INFO|3|Bot.OnBotConnected Bot "1-65-1580561914"(3) connected. 2020-03-10 15:09:10.1553| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/3/(/volume/25) 2020-03-10 15:09:10.1719| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/3/(/bot/commander/on) 2020-03-10 15:09:11.6368| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1580562059 2020-03-10 15:09:11.6368| INFO||Bot.InitializeBot Bot "1-65-1580562059" connecting to "happyfm.eu" 2020-03-10 15:09:11.7141| INFO|4|Bot.OnBotConnected Bot "1-65-1580562059"(4) connected. 2020-03-10 15:09:12.6656| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/4/(/volume/25) 2020-03-10 15:09:12.6819| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/4/(/bot/commander/on) 2020-03-10 15:09:13.9165| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303993 2020-03-10 15:09:13.9165| INFO||Bot.InitializeBot Bot "1-65-1575303993" connecting to "happyfm.eu" 2020-03-10 15:09:14.0047| INFO|5|Bot.OnBotConnected Bot "1-65-1575303993"(5) connected. 2020-03-10 15:09:14.9825| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/5/(/volume/25) 2020-03-10 15:09:14.9988| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/5/(/bot/commander/on) 2020-03-10 15:09:15.5362| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303954 2020-03-10 15:09:15.5362| INFO||Bot.InitializeBot Bot "1-65-1575303954" connecting to "happyfm.eu" 2020-03-10 15:09:16.1689| INFO|6|Bot.OnBotConnected Bot "1-65-1575303954"(6) connected. 2020-03-10 15:09:16.5703| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/6/(/volume/25) 2020-03-10 15:09:16.5880| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/6/(/bot/commander/on) 2020-03-10 15:09:17.2927| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303896 2020-03-10 15:09:17.2927| INFO||Bot.InitializeBot Bot "1-65-1575303896" connecting to "happyfm.eu" 2020-03-10 15:09:17.3850| INFO|7|Bot.OnBotConnected Bot "1-65-1575303896"(7) connected. 2020-03-10 15:09:18.3224| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/7/(/volume/25) 2020-03-10 15:09:18.3390| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/7/(/bot/commander/on) 2020-03-10 15:09:19.1804| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303538 2020-03-10 15:09:19.1804| INFO||Bot.InitializeBot Bot "1-65-1575303538" connecting to "happyfm.eu" 2020-03-10 15:09:19.2607| INFO|8|Bot.OnBotConnected Bot "1-65-1575303538"(8) connected. 2020-03-10 15:09:20.2105| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/8/(/volume/25) 2020-03-10 15:09:20.2289| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/8/(/bot/commander/on) 2020-03-10 15:09:21.0713| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303466 2020-03-10 15:09:21.0719| INFO||Bot.InitializeBot Bot "1-65-1575303466" connecting to "happyfm.eu" 2020-03-10 15:09:21.1553| INFO|9|Bot.OnBotConnected Bot "1-65-1575303466"(9) connected. 2020-03-10 15:09:22.1050| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/9/(/volume/25) 2020-03-10 15:09:22.1223| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/9/(/bot/commander/on) 2020-03-10 15:09:23.2577| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303434 2020-03-10 15:09:23.2577| INFO||Bot.InitializeBot Bot "1-65-1575303434" connecting to "happyfm.eu" 2020-03-10 15:09:23.3452| INFO|10|Bot.OnBotConnected Bot "1-65-1575303434"(10) connected. 2020-03-10 15:09:24.2935| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/10/(/volume/25) 2020-03-10 15:09:24.3104| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/10/(/bot/commander/on) 2020-03-10 15:09:24.8603| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303402 2020-03-10 15:09:24.8603| INFO||Bot.InitializeBot Bot "1-65-1575303402" connecting to "happyfm.eu" 2020-03-10 15:09:24.9444| INFO|11|Bot.OnBotConnected Bot "1-65-1575303402"(11) connected. 2020-03-10 15:09:25.8908| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/11/(/volume/25) 2020-03-10 15:09:25.9079| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/11/(/bot/commander/on) 2020-03-10 15:09:26.6724| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1575303359 2020-03-10 15:09:26.6724| INFO||Bot.InitializeBot Bot "1-65-1575303359" connecting to "happyfm.eu" 2020-03-10 15:09:26.7627| INFO|12|Bot.OnBotConnected Bot "1-65-1575303359"(12) connected. 2020-03-10 15:09:27.7074| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/12/(/volume/25) 2020-03-10 15:09:27.7239| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/12/(/bot/commander/on) 2020-03-10 15:09:33.1423| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1582476382 2020-03-10 15:09:33.1423| INFO||Bot.InitializeBot Bot "1-65-1582476382" connecting to "not to know" 2020-03-10 15:09:33.2776| INFO|13|Bot.OnBotConnected Bot "1-65-1582476382"(13) connected. 2020-03-10 15:09:34.1882| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/13/(/volume/20) 2020-03-10 15:09:36.5304| WARN|13|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:09:36.7231| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1582365382 2020-03-10 15:09:36.7231| INFO||Bot.InitializeBot Bot "1-65-1582365382" connecting to "not to know" 2020-03-10 15:09:36.8450| INFO|14|Bot.OnBotConnected Bot "1-65-1582365382"(14) connected. 2020-03-10 15:09:36.9281| WARN|13|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:09:37.7549| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/14/(/volume/20) 2020-03-10 15:09:37.7721| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/14/(/bot/commander/on) 2020-03-10 15:09:38.7288| WARN|14|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:09:38.8094| WARN|14|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:09:57.1483| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1583164803 2020-03-10 15:09:57.1483| INFO||Bot.InitializeBot Bot "1-65-1583164803" connecting to "not to know" 2020-03-10 15:09:57.2716| INFO|15|Bot.OnBotConnected Bot "1-65-1583164803"(15) connected. 2020-03-10 15:09:58.2218| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/15/(/volume/25) 2020-03-10 15:09:58.2393| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/15/(/bot/commander/on) 2020-03-10 15:09:58.8915| WARN|14|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:00.4721| WARN|15|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:00.8810| WARN|15|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:01.9965| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1583684359 2020-03-10 15:10:01.9965| INFO||Bot.InitializeBot Bot "1-65-1583684359" connecting to "not to know" 2020-03-10 15:10:02.1100| INFO|16|Bot.OnBotConnected Bot "1-65-1583684359"(16) connected. 2020-03-10 15:10:03.0617| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/16/(/volume/20) 2020-03-10 15:10:03.0792| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/16/(/bot/commander/on) 2020-03-10 15:10:05.4766| WARN|16|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:05.8358| WARN|16|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:09.3767| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1583599074 2020-03-10 15:10:09.3767| INFO||Bot.InitializeBot Bot "1-65-1583599074" connecting to "Vumy.net" 2020-03-10 15:10:09.5083| INFO|17|Bot.OnBotConnected Bot "1-65-1583599074"(17) connected. 2020-03-10 15:10:10.4317| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/17/(/volume/25) 2020-03-10 15:10:11.9039| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1583599048 2020-03-10 15:10:11.9039| INFO||Bot.InitializeBot Bot "1-65-1583599048" connecting to "Vumy.net" 2020-03-10 15:10:12.0217| INFO|18|Bot.OnBotConnected Bot "1-65-1583599048"(18) connected. 2020-03-10 15:10:12.9160| WARN|17|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:12.9369| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/18/(/volume/20) 2020-03-10 15:10:13.3184| WARN|17|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:13.3344| WARN|17|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:14.0561| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/connect/template/1-65-1583599004 2020-03-10 15:10:14.0561| INFO||Bot.InitializeBot Bot "1-65-1583599004" connecting to "Vumy.net" 2020-03-10 15:10:14.1763| INFO|19|Bot.OnBotConnected Bot "1-65-1583599004"(19) connected. 2020-03-10 15:10:15.0907| INFO||WebApi.ProcessApiV1Call ::ffff:212.224.88.103 Requested: /api/bot/use/19/(/volume/25) 2020-03-10 15:10:15.3146| WARN|18|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:15.7005| WARN|18|Bot.LoggedUpdateBotStatus Missing teamspeak permissions. 2020-03-10 15:10:19.7451| WARN|19|Bot.TryCatchCommand Command Error (Could not load. (media: Unknown request error.)) TS3AudioBot.CommandSystem.CommandException: Could not load. (media: Unknown request error.) at TS3AudioBot.Helper.Util.UnwrapThrow(E`1 r) in C:\projects\ts3audiobot\TS3AudioBot\Helper\Util.cs:line 109 at TS3AudioBot.MainCommands.CommandPlay(PlayManager playManager, InvokerData invoker, String url) in C:\projects\ts3audiobot\TS3AudioBot\MainCommands.cs:line 1077 --- End of stack trace from previous location where exception was thrown --- at TS3AudioBot.CommandSystem.Commands.FunctionCommand.ExecuteFunction(Object[] parameters) in C:\projects\ts3audiobot\TS3AudioBot\CommandSystem\Commands\FunctionCommand.cs:line 71 at TS3AudioBot.CommandSystem.Commands.FunctionCommand.Execute(ExecutionInformation info, IReadOnlyList`1 arguments, IReadOnlyList`1 returnTypes) in C:\projects\ts3audiobot\TS3AudioBot\CommandSystem\Commands\FunctionCommand.cs:line 202 at TS3AudioBot.CommandSystem.Commands.OverloadedFunctionCommand.Execute(ExecutionInformation info, IReadOnlyList`1 arguments, IReadOnlyList`1 returnTypes) in C:\projects\ts3audiobot\TS3AudioBot\CommandSystem\Commands\OverloadedFunctionCommand.cs:line 88 at TS3AudioBot.Bot.<>c__DisplayClass40_0.