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

x_v · 30. April 2018

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.

Alles anzeigen

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 · 1. Mai 2018

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 · 1. Mai 2018

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 · 2. Mai 2018

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 · 2. Mai 2018

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!

Alles anzeigen

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 · 3. Mai 2018

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 · 4. Mai 2018

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?

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

Jetzt mitmachen!

Teilen

Tags