[2.4.1] bad locking order bei skindesigner

PmK · 18. Juni 2019

Danke!

Ich hab mit Skindesigner da noch ein Locking-Problem. skindesigner ist aktuell aus dem git. Woran kann das liegen?

Code

Jun 18 08:14:26 raspberrypi vdr: [3289] skindesigner: templates and images cached
Jun 18 08:14:26 raspberrypi vdr: [3289] skindesigner: cached 75 icons - size internal mem 0,00MB, high level mem 1,45MB
Jun 18 08:14:26 raspberrypi vdr: [3289] skindesigner: cached 61 logos - size 3369,84MB internal mem
Jun 18 08:14:26 raspberrypi vdr: [3289] skindesigner: cached 8 skinparts - size internal mem 0,00MB, high level mem 15,44MB
Jun 18 08:14:26 raspberrypi vdr: [3289] skindesigner: templates loaded and caches created - needed 20740 ms
Jun 18 08:14:26 raspberrypi vdr: [3289] --- begin invalid lock sequence report
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  R  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  U  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  R  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  U  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  R  -  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  U  -  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  R  -  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  U  -  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  R  -  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  U  -  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  R  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  U  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  R  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  U  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  R  -  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  U  -  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  R  -  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3307  U  -  -  -  -  -  -  -  -  -  U
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  -  R  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289  R  *  -  -  -  -  -  -  -  -  L
Jun 18 08:14:26 raspberrypi vdr: [3289]  3289 invalid lock sequence: 1 Timers
Jun 18 08:14:26 raspberrypi vdr: [3289] full backtrace:
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/bin/vdr cStateLock::Lock(cStateKey&, bool, int) at thread.c:735
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/bin/vdr cTimers::GetTimersRead(cStateKey&, int) at timers.c:846
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/local/lib/vdr//libvdr-skindesigner.so.2.4.1 cGlobalTimers::SetLocalTimers() calling cTimers_Lock::cTimers_Lock(bool) at timers.h:217 at cGlobalTimers::SetLocalTimers() at globaltimers.c:82
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/local/lib/vdr//libvdr-skindesigner.so.2.4.1 cGlobalTimers::LoadTimers() at globaltimers.c:36
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/local/lib/vdr//libvdr-skindesigner.so.2.4.1 cViewChannel::SetChannel(cChannel const*, int) at viewdisplaychannel.c:211
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/bin/vdr cDisplayChannel::DisplayChannel() at menu.c:4688
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/bin/vdr cDisplayChannel::cDisplayChannel(int, bool) at menu.c:4649
Jun 18 08:14:27 raspberrypi vdr: [3289] /usr/bin/vdr main at vdr.c:1091
Jun 18 08:14:27 raspberrypi vdr: [3289] /lib/arm-linux-gnueabihf/libc.so.6 __libc_start_main at libc-start.c:325
Jun 18 08:14:27 raspberrypi vdr: [3289] --- end invalid lock sequence report
Jun 18 08:14:27 raspberrypi vdr: [3289] --- THERE WILL BE NO FURTHER REPORTS UNTIL VDR IS RESTARTED!
Jun 18 08:14:27 raspberrypi vdr: [3302] rpihddevice: loading /usr/share/fonts/truetype/dejavu/DejaVuSans.ttf ...
Jun 18 08:14:28 raspberrypi vdr: [3465] animator thread thread started (pid=3289, tid=3465, prio=high)
Jun 18 08:14:28 raspberrypi vdr: [3289] max. latency time 23 seconds

Alles anzeigen

seahawk1986 · 18. Juni 2019

Zitat von PmK

skindesigner ist aktuell aus dem git

Probier es mal mit dem Patch von kamel5: skindesigner - bad locking order for vdr-2.3.x

kamel5 · 21. Juni 2019

Hallo,

ich habe mal das Repo auf https://projects.vdr-developer.org aktualisiert.

Grüsse

kamel5

PmK · 7. Februar 2020

Ich hol den Thread nochmal hoch. Ich hab regelmässig immer noch bad locking order. Diesmal aber wie es scheint vom VDR selbst.

Verwendet wird der 2.4.1. Gibts dafür irgendwo nen Patch oder kennt das Problem jemand? Passiert eigentlich immer wenn man nach längerer Laufzeit den Kanal mal wechselt. Plugins hab ich streamdev, rpihddevice, epgsync, svdrpservice und skindesigner

Nach dem Absturz hab ich dann kein OSD mehr und muß den ganzen Raspberry neu starten das es wieder läuft..

Ich verwende den zapcockpit-patch vom skindesigner.. kann der evt. diesen Fehler provozieren?

Code

Feb  7 06:13:52 raspberrypi vdr: [361] --- begin invalid lock sequence report
Feb  7 06:13:52 raspberrypi vdr: [361]   681  R  -  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   681  U  -  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  R  -  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  U  -  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  R  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  U  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  W  -  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  *  -  -  -  R  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  *  -  -  -  U  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  U  -  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  W  -  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  U  -  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   681  R  -  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   681  U  -  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  R  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  U  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  R  -  -  -  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  U  -  -  -  -  -  -  -  -  U
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  -  -  -  R  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361  -  R  -  -  *  -  -  -  -  -  L
Feb  7 06:13:52 raspberrypi vdr: [361]   361 invalid lock sequence: 2 Channels
Feb  7 06:13:52 raspberrypi vdr: [361] full backtrace:
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr cStateLock::Lock(cStateKey&, bool, int) at thread.c:735
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr cChannels::GetChannelsRead(cStateKey&, int) at channels.c:852
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr at ??:0
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr cDisplayChannel::DisplayInfo() at menu.c:4708
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr cDisplayChannelExtended::StateInit(int, cSkinDisplayChannelExtended*) at menu.c:5088
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr cDisplayChannelExtended::ProcessKey(eKeys) at menu.c:4987
Feb  7 06:13:53 raspberrypi vdr: [361] /usr/bin/vdr main at vdr.c:1486
Feb  7 06:13:53 raspberrypi vdr: [361] /lib/arm-linux-gnueabihf/libc.so.6 __libc_start_main at libc-start.c:325
Feb  7 06:13:53 raspberrypi vdr: [361] --- end invalid lock sequence report
Feb  7 06:13:53 raspberrypi vdr: [361] --- THERE WILL BE NO FURTHER REPORTS UNTIL VDR IS RESTARTED!
Feb  7 06:13:53 raspberrypi vdr: [711] animator thread thread started (pid=361, tid=711, prio=high)

Alles anzeigen

kamel5 · 7. Februar 2020

PmK ,

kannst Du bitte mal den angehängten zapcockpit-Patch testen.

Wenn es damit funktioniert, werde ich das git entsprechend aktualisieren.

Grüsse

kamel5

PmK · 7. Februar 2020

Mach ich natürlich sofort. Ich geb dir dann morgen oder übermorgen mal Bescheid ob das funktioniert. Nach dem ersten Start geht das immer eine Weile gut und erst wenn der vdr ne Weile lief kommt der Fehler bei mir.

1000 Dank schonmal für die schnelle Hilfe!

PmK · 8. Februar 2020

Also den Locking-Fehler hab ich nicht mehr.

Allerdings ist das Problem mit dem fehlenden OSD immer noch da.

Ich hab heute die ganze Zeit folgenden Fehler in den Logs gehabt:

Code

Feb  8 14:13:37 raspberrypi vdr: [418] rpihddevice: set HDMI audio output format to 2ch PCM, 48.0kHz
Feb  8 14:13:41 raspberrypi vdr: [418] rpihddevice: set HDMI audio output format to 2ch PCM, 48.0kHz
Feb  8 14:13:45 raspberrypi vdr: [418] rpihddevice: set HDMI audio output format to 2ch PCM, 48.0kHz
Feb  8 14:13:49 raspberrypi vdr: [418] rpihddevice: set HDMI audio output format to 2ch PCM, 48.0kHz

Dann hat meine Frau eingeschalten und beim ersten Umschalten kommt dann dieser Fehler:

Code

Feb  8 14:21:25 raspberrypi vdr: [808] i/o throttle activated, count = 1 (tid=808)
Feb  8 14:21:25 raspberrypi vdr: [808] buffer usage: 70% (tid=807)
Feb  8 14:21:25 raspberrypi vdr: [808] buffer usage: 80% (tid=807)
Feb  8 14:21:25 raspberrypi vdr: [808] buffer usage: 90% (tid=807)
Feb  8 14:21:25 raspberrypi vdr: [808] buffer usage: 100% (tid=807)
Feb  8 14:21:53 raspberrypi vdr: [363] PANIC: watchdog timer expired - exiting!
Feb  8 14:21:54 raspberrypi lircd[474]: lircd-0.9.4c[474]: Info: removed client
Feb  8 14:21:54 raspberrypi lircd-0.9.4c[474]: Info: removed client
Feb  8 14:21:54 raspberrypi systemd[1]: vdr.service: Main process exited, code=exited, status=1/FAILURE
Feb  8 14:21:54 raspberrypi systemd[1]: vdr.service: Unit entered failed state.
Feb  8 14:21:54 raspberrypi systemd[1]: vdr.service: Failed with result 'exit-code'.
Feb  8 14:21:54 raspberrypi systemd[1]: vdr.service: Service hold-off time over, scheduling restart.
Feb  8 14:21:54 raspberrypi systemd[1]: Stopped VDR.
Feb  8 14:21:54 raspberrypi systemd[1]: Starting VDR...

Alles anzeigen

Danach ist das OSD wieder weg und ich muß erst ganze System neu starten.

Streamdev-abort-Patch hab ich angewendet. Derartige Fehler wie den oben mit dem output-Format hab ich sonst nie im Log.

Kann da jemand helfen?

Grüsse, PmK

kamel5 · 8. Februar 2020

Zitat von PmK

Also den Locking-Fehler hab ich nicht mehr.

OK, dann werde ich den Patch so ins Git übernehmen.

Zu dem Anderen kann ich leider nicht viel sagen. Du könntest aber mal noch eingrenzen, ob es auch mit dem Standard-Skin auftritt.

Grüsse

kamel5

PmK · 9. Februar 2020

Meinst du den Standard-Skin von VDR oder vom Skindesigner? Welcher wäre das dann?

Grüsse, Jan

kamel5 · 9. Februar 2020

Ich meinte hier den Standard-Skin vom VDR, um Probleme mit dem Skindesigner auszuschließen.

Um das Ganze einzugrenzen, wäre es sicher auch sinnvoll, den VDR mal ohne zusätzliche Patches und nur mit den nötigsten Plugins zu betreiben.

Und dann die Sachen einzeln wieder hinzunehmen, bis der Fehler auftritt.

kamel5

PmK · 10. Februar 2020

Ok. ich werd das mal so ausprobieren ob der vanilla vdr läuft. Heute früh wars wieder soweit.. selber Fehler i/o throttle activated und dann watchdog expired. Das ganze auch beim ersten Umschalten nachdem er die ganze Nacht lief und auch ein Bild gezeigt hat.

PmK · 12. Februar 2020

Ok.. Also noch mehreren Tagen testens hab ich folgendes Fazit. Ohne vdr-patches, ohne skindesigner aber mit epgsync, svdrpservice, rpihddevice und streamdev hab ich immer noch die i/o throttle Fehler beim Umschalten. Das gute ist jetzt, das ich nicht mehr neu starten muß. Anscheinend scheint beim Absturz mit Skindesigner irgendetwas nicht wirklich bereinigt zu werden weshalb dann das OSD nicht mehr geht. Das passiert mit dem LCARS-Skin mal nicht mehr.

Der Fehler tritt ziemlich selten auf aber eigentlich IMMER nach dem Umschalten. Es kommt dann auch kein Bild mehr sondern der buffer läuft voll und dann wars das..

Grüsse, PmK

Code

Feb 11 17:29:50 raspberrypi vdr: [329] switching to channel 3 S19.2E-1-1017-61301 (ProSieben HD)
Feb 11 17:29:51 raspberrypi vdr: [2007] i/o throttle activated, count = 1 (tid=2007)
Feb 11 17:29:51 raspberrypi vdr: [2007] buffer usage: 70% (tid=2006)
Feb 11 17:29:51 raspberrypi vdr: [2007] buffer usage: 80% (tid=2006)
Feb 11 17:29:52 raspberrypi vdr: [2007] buffer usage: 90% (tid=2006)
Feb 11 17:29:52 raspberrypi vdr: [2007] buffer usage: 100% (tid=2006)
Feb 11 17:30:19 raspberrypi vdr: [329] PANIC: watchdog timer expired - exiting!
Feb 11 17:30:19 raspberrypi lircd[473]: lircd-0.9.4c[473]: Info: removed client
Feb 11 17:30:19 raspberrypi lircd-0.9.4c[473]: Info: removed client
Feb 11 17:30:20 raspberrypi systemd[1]: vdr.service: Main process exited, code=exited, status=1/FAILURE
Feb 11 17:30:20 raspberrypi systemd[1]: vdr.service: Unit entered failed state.
Feb 11 17:30:20 raspberrypi systemd[1]: vdr.service: Failed with result 'exit-code'.
Feb 11 17:30:20 raspberrypi systemd[1]: vdr.service: Service hold-off time over, scheduling restart.
Feb 11 17:30:20 raspberrypi systemd[1]: Stopped VDR.
Feb 11 17:30:20 raspberrypi systemd[1]: Starting VDR...

Alles anzeigen

kamel5 · 12. Februar 2020

Ich kann Dir bei dem PANIC-Problem nicht wirklich weiterhelfen. Falls Du aber den Skindesigner immer noch einsetzen willst, könntest Du nochmal mit Skindesigner und beim rpihddevice mit abgeschalteter Hardwarebeschleunigung testen. Das macht möglicherweise auch noch einen Unterschied hinsichtlich des Neustarts.

kamel5

PmK · 13. Februar 2020

Hallo kamel, also ich hab das auch schon durchprobiert und das macht keinen Unterschied ob mit oder ohne GPU-Accel. Irgendwie scheint Skindesigner beim Absturz aus welchem Grund auch immer auf meinem Raspbian aber ein Problem mit dem OSD-Layer zu haben so das dieser nach dem Neustart nicht mehr angezeigt wird. Laut Logs sieht man auch immer das der animator-thread gestartet und beendet wird. Das verhalten hab ich mit dem LCARS nicht. Der Fehler mit dem i/o throttle ist aber immer noch mit der selben Häufigkeit vorhanden wie vorher. Ich bin schon am überlegen ob ich nicht minisatip mal als Zuspieler probiere Übergangsweise. Hab eh TVheadend auf dem Server laufen und der regelt auch meine Aufzeichnungen.. ergo bräuchte ich VDR dann dort gar nicht mehr.

[2.4.1] bad locking order bei skindesigner

Jetzt mitmachen!

Teilen