VDR stürzt alle paar Stunden ab (VDR 2.4.0, Ubuntu 18.04)

x_v · April 30, 2018 at 11:49 PM

Hallo,

ich hatte etwas Zeit und habe jetzt meinen VDR komplett neu eingerichtet:

Ubuntu 18.04 Server

VDR 2.4.0

Ziel ist dann Kodi als frontend zu verwenden, aber soweit bin ich noch nicht: In unregelmäßigen Abständen (alle 1-3 Stunden) stürzt der VDR ab, hier die syslog Einträge dazu:

Code

Apr 30 23:12:00 VDR_PC vdr: [3278] epg data writer thread started (pid=3940, tid=3278, prio=low)
Apr 30 23:12:01 VDR_PC vdr: [3278] epg data writer thread ended (pid=3940, tid=3278)
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43372 client connection accepted
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC > 192.168.1.87:43372 server created
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43372 lost connection to client
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43372 connection closed
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43372 server destroyed
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43374 client connection accepted
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC > 192.168.1.87:43374 server created
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43374 lost connection to client
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43374 connection closed
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43374 server destroyed
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC < 192.168.1.87:43376 client connection accepted
Apr 30 23:12:56 VDR_PC vdr: [3979] SVDRP VDR_PC > 192.168.1.87:43376 server created
Apr 30 23:13:41 VDR_PC vdr: [3940] PANIC: watchdog timer expired - exiting!
Apr 30 23:13:41 VDR_PC vdr: [3982] streamdev-livestreaming thread ended (pid=3940, tid=3982)
Apr 30 23:13:41 VDR_PC vdr: [3981] streamdev-server: closing HTTP connection to 192.168.1.87:57524
Apr 30 23:13:41 VDR_PC vdr: [3981] streamdev-writer thread ended (pid=3940, tid=3981)
Apr 30 23:13:41 VDR_PC vdr: [3940] buffer stats: 357012 (9%) used
Apr 30 23:13:41 VDR_PC vdr: [3984] device 1 TS buffer thread ended (pid=3940, tid=3984)
Apr 30 23:13:41 VDR_PC vdr: [3983] buffer stats: 91932 (1%) used
Apr 30 23:13:41 VDR_PC vdr: [3983] device 1 receiver thread ended (pid=3940, tid=3983)
Apr 30 23:13:41 VDR_PC vdr: [3968] fatal error, server exiting: Ungültiger Dateideskriptor
Apr 30 23:13:41 VDR_PC vdr: [3968] streamdev server thread ended (pid=3940, tid=3968)
Apr 30 23:13:45 VDR_PC vdr: [3940] ERROR: ListGarbageCollector destroyed without prior Purge()!
Apr 30 23:13:45 VDR_PC vdr[3940]: exception occured: pthread_mutex_lock failed: errno 22: Das Argument ist ungültig
Apr 30 23:13:45 VDR_PC systemd[1]: vdr.service: Main process exited, code=exited, status=1/FAILURE
Apr 30 23:13:45 VDR_PC systemd[1]: vdr.service: Failed with result 'exit-code'.
Apr 30 23:13:46 VDR_PC systemd[1]: vdr.service: Service hold-off time over, scheduling restart.
Apr 30 23:13:46 VDR_PC systemd[1]: vdr.service: Scheduled restart job, restart counter is at 1.
Apr 30 23:13:46 VDR_PC systemd[1]: Stopped Video Disk Recorder.
Apr 30 23:13:46 VDR_PC systemd[1]: Started Video Disk Recorder.

Display More

Wie man sieht gibt es ein timeout vom watchdog (90 Sekunden), ich habe aber im VLC beim schauen auf einem anderen Rechner über streamdev nur eine Unterbrechung von wenigen Sekunden. Als SVDRP client kommt EasyStream zum Einsatz. Irgendeine was die Ursache sein könnte? Der VDR wird folgendem Kommando von systemd aufgerufen:

/usr/local/bin/vdr -v /video -w 90 -c /home/vdruser/vdrconf -P "bösesplugin" -P "eepg" -P "epgsearch" -P "live" -P "streamdev-server"

Es ist noch kein frontend installiert (geplant ist Kodi mit vsni).

Vielen Dank

mini73 · May 1, 2018 at 9:31 AM

Lass einfach mal die ersten beiden Plugins weg. Wenn es dann immer noch abstürzt, die nächsten beiden. Falls es danach immer noch passiert, müsste man in dem Moment, wo er hängt, mal einen backtrace erstellen, um zu sehen, wo es genau passiert.

Lars

Hitman47 · May 1, 2018 at 9:56 AM

Bei mir war es das Eepg-Plugin, das nach nach wenigen Minuten VDR nicht mehr bedienbar machte.

Ich verwende vdr4arch und die dort verwendete Version ist mit 2.3.1-compatibility aus commit: 7f7ec60c3654f318fc4ec918a29b8b95c8c4a25e.

Leider gibt das Log an der Stelle nichts her, sondern schweigt sich aus.

x_v · May 2, 2018 at 10:34 PM

Danke für die schnelle Hilfe: Es scheint in der tat das EEPG Plug-In gewesen zu sein. Ohne das ist er >12 Stunden problemlos gelaufen. Ich hab heute morgen jetzt wieder mit EEPG gestartet, jedoch EEPG dahingehend modifiziert, dass nur noch Fehler protokolliert werden und das syslog nicht durch jedes event vollgespammt wird (das war rund 60 MB und gefühlte 95% der Einträge waren EEPG). Ich vermute das könnte eine Fehlerursache gewesen sein, seit heute morgen auch durchgehend ohne Absturz.

x_v · May 2, 2018 at 11:58 PM

Leider zu früh gefreut: Das Problem scheint nur aufzutreten, wenn ein Client verbunden ist. Er stürzt wiederholt ab, einmal gab es erfreulicherweise etwas mehr im Log:

Code

May  2 23:41:01 VDR_PC vdr: [10073] --- begin invalid lock sequence report
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  U  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  U  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  W  -  -  -  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  *  -  -  W  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  *  -  -  U  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  U  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10060  R  -  -  -  -  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10060  U  -  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10060  R  -  -  -  -  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10060  U  -  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10060  -  R  -  -  -  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10060  -  U  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10060  W  -  -  -  -  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10060  *  -  -  -  R  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10060  *  -  -  -  U  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10060  U  -  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10060  W  -  -  -  -  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10060  U  -  -  -  -  -  -  -  -  -  U
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  -  -  -  W  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10073  -  R  -  -  *  -  -  -  -  -  L
May  2 23:41:01 VDR_PC vdr: [10073] 10073 invalid lock sequence: 2 Channels
May  2 23:41:01 VDR_PC vdr: [10073] full backtrace:
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/bin/vdr cStateLock::Lock(cStateKey&, bool, int) calling ?? at ??:0
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/bin/vdr cChannels::GetChannelsRead(cStateKey&, int) calling ?? at ??:0
May  2 23:41:01 VDR_PC vdr[10060]: invalid lock sequence at Mi. 02.05. 23:41
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/lib/vdr/libvdr-eepg.so.2.4.0 util::GetChannelByID(tChannelID const&, bool) at util.c:37
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/lib/vdr/libvdr-eepg.so.2.4.0 SI::cEIT2::cEIT2(cSchedules*, int, unsigned char, unsigned char const*, util::EFormat, bool, bool) at eit2.c:483 (discriminator 3)
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/lib/vdr/libvdr-eepg.so.2.4.0 cFilterEEPG::ProccessContinuous(unsigned short, unsigned char, int, unsigned char const*) at eepg.c:2930 (discriminator 1)
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/lib/vdr/libvdr-eepg.so.2.4.0 cFilterEEPG::Process(unsigned short, unsigned char, unsigned char const*, int) at eepg.c:3297
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/bin/vdr cSectionHandler::Action() calling ?? at ??:0
May  2 23:41:01 VDR_PC vdr: [10073] /usr/local/bin/vdr cThread::StartThread(cThread*) calling ?? at ??:0
May  2 23:41:01 VDR_PC vdr: [10073] /lib/x86_64-linux-gnu/libpthread.so.0 at ??:?
May  2 23:41:01 VDR_PC vdr: [10073] /lib/x86_64-linux-gnu/libc.so.6 clone at ??:?
May  2 23:41:01 VDR_PC vdr: [10073] --- end invalid lock sequence report
May  2 23:41:01 VDR_PC vdr: [10073] --- THERE WILL BE NO FURTHER REPORTS UNTIL VDR IS RESTARTED!

Display More

EEPG scheint irgendwie involviert zu sein. Nur vom Gefühl scheint das Problem zu sein, dass EasyStream regelmäßig die Kanäle abfragt und sich dabei mit EEPG stört. Ich kann dieses invalid lock sequence leider nicht interpretieren, kann es sein, dass auch wenn es nicht im log auftaucht, es auch in anderen Fällen für die Abstürze verantwortlich ist? Leider sind multithreading Probleme schwer zu reproduzieren und damit extrem schwer zu debuggen. Ideen wie ich weiter vorgehen soll? Ich werde jetzt erst nochmal den VDR mit aktivem Client laufen ohne EEPG laufen lassen und schauen ob das Problem weg ist.

Nachtrag:

EEPG ist nicht der (einzige?) Übeltäter, auch ohne EEPG gibts Abstürze wenn ein client verbunden ist. Nehme jetzt alles außer stremdev raus und schaue ob er bis morgen früh durchläuft

mini73 · May 3, 2018 at 10:05 PM

invalid lock sequence wird nur beim aller ersten mal gemeldet, danach nicht wieder, auch wenn es auftritt. Das muss im Plugin behoben werden, sonst wird es nie laufen.

Lars

x_v · May 4, 2018 at 1:13 PM

Habe die Plugins einzeln hinzugefügt: streamdev-server, epgsearch und live verursachen keine Probleme.

EEPG dazu und der VDR stürzt gelegentlich ab, leider ohne besonders aussagekräftige Fehlermeldung:

Code

vdr[17429]: exception occured: pthread_mutex_lock failed: errno 22: Das Argument ist ungültig

Da EEPG selber keine pthreads verwendet, provoziert es den Fehler wohl woanders, irgendeine Idee?

mrjoe · May 18, 2024 at 2:18 PM

Ich greife den Thread mal auf, auch wenn ich kein Ubuntu einsetze - Auslöser aber scheinbar beides Mal das eepg-Plugin zu sein. Ich nutze seit kurzem eepg um EPG Daten auf IPTV Kanäle zu kopieren. Funktioniert auch soweit perfekt. Nur in unregelmässigen Abständen (gefühlt sobald viele neue EPG Daten anliegen) löst der vdr Watchdog aus und startet neu. Ich bekomme ebenfalls den Lock-Fehler - interessanterweise direkt nach dem Start von VDR sobald erster Aufruf von HandleEitEvent ausgelöst wird. Ohne eepg-Plugin erhalte ich keine Fehler. (vdr 2.6.7, eepg Plugin frisch von git)

Frage: nutzt jemand von euch eepg auch zum Kopieren der EPG Daten auf andere Kanäle mit >10 Einträgen in der eepg.equiv und hat keine Stabilitätsprobleme?

Alternative wäre das Skript von jsffm (funktioniert prinzipiell auch, vielen Dank dafür). Frage hierzu: was passiert, wenn sich Einträge verschieben? Z.B. Filmstart geplant auf 20:15, aber aufgrund von Sondersendung Start des gleichen Films erst um 20:30. Habe ich dann beim Nutzen des Skripts (am Ende beim Nutzen vom svdrp-Kommando PUTE) das Problem, das ich zwei Einträge habe oder sortiert der VDR den veralteten Eintrag aus, da Event Id gleich bleibt? Vermute eigentlich letzteres.

VDR stürzt alle paar Stunden ab (VDR 2.4.0, Ubuntu 18.04)

Participate now!

Tags