vdr-plugin-softhddevice-drm: RPI3, mmal, instabil

MarkusE · 18. April 2022

Also,

Im Prinzip funktioniert das Plugin auf dem RPI3, mit Raspberry Pi OS Bullseye.

Bei der Wiedergabe von VDR Aufzeichnungen ist es aber instabil, nach einigem Springen auf, auch auf Schnittmarken, regiert der VDR nicht mehr.

Im Syslog steht

Zitat

Apr 18 17:22:10 rpi3 vdr: [9448] [softhddev]SetPlayMode: 1

Apr 18 17:22:10 rpi3 vdr: [10051] dvbplayer thread started (pid=9448, tid=10051, prio=high)

Apr 18 17:22:10 rpi3 vdr: [10052] non blocking file reader thread started (pid=9448, tid=10052, prio=high)

Apr 18 17:22:14 rpi3 vdr: [9448] [softhddev]Clear:

Apr 18 17:22:14 rpi3 vdr: [9448] [softhddev]StillPicture: pes 0x20441f0 161548

Apr 18 17:22:18 rpi3 vdr: [9448] [softhddev]Clear:

Apr 18 17:22:18 rpi3 vdr: [9448] [softhddev]Clear:

Apr 18 17:22:18 rpi3 vdr: [9448] [softhddev]Play:

Apr 18 17:22:18 rpi3 vdr: [10060] subtitle converter thread started (pid=9448, tid=10060, prio=high)

Apr 18 17:22:22 rpi3 vdr: [9448] [softhddev]Clear:

Apr 18 17:22:22 rpi3 vdr: [9448] [softhddev]StillPicture: pes 0x20441f0 161548

Alles anzeigen

Auf dem Bildschirm wird

mmal error 7 "E10"

ausgegeben.

Markus

zillerbaer · 18. April 2022

Ich werde mir das mal ansehen. Die Unterstützung für MMAL ist aber obsolet und nur noch aus historischen Gründen im Plugin. Grossen Aufwand betreibe ich da nicht mehr. Wenn Du eine zukunftsfähige Plattform suchst, dann wähle bitte eine Plattform mit guter mainline Unterstützung aus.

zillerbaer · 20. April 2022

Ich erkenne Parallelen zu diesem Problem darin.

MarkusE · 21. April 2022

Der hier beschriebene Fehler tritt auch auf, wenn ich nur den Anfang eines Videos anschaue.

Der Fehler tritt vor allem beim Springen im Video (mit Grün/Gelb) auf.

Trotzdem kann es Paralellen geben, wegen der Art, wie VDR Daten anliefert

zillerbaer · 21. April 2022

Ich habe das alte Raspi 2b aus der Bastelbox geholt und getestet. Den Fehler 'mmal error 7 "E10"' konnte ich nicht provozieren. Wenn man nach den Springen abwartet bis Audio und Video synchronisiert sind, gibt es keine Probleme. Bleibt man auf der Taste, hängt es sich auf. Darauf habe ich in den Code geschaut und sah das nur jedes zweite Bild synchronisiert wird. Das sieht aus wie Zeitlupe. Soweit ich mich erinnere hatte das seine Ursache in MMAL. Das Problem rolle ich heute nicht wieder auf. Eher entferne ich den Support für die proprietäre Schnittstelle und unterstütze nur noch Mainline. Patches are welcome.

Gruss zille

MarkusE · 21. April 2022

Zitat von zillerbaer

Ich habe das alte Raspi 2b aus der Bastelbox geholt und getestet.

Danke!

Es treten noch mehr Probleme auf, ich kann z.B. SD Filme gar nicht richtig abspielen.

Vermutlich liegt es am Kernel, oder der ffmpeg Version.

Ich verwende das aktuelle RPI OS, mit kernel 5.15.32-v7+

~ Markus

zillerbaer · 21. April 2022

Ich habe den alten Stand von vor lange her benutzt. FFmpeg ist doch nur wrapper für mmal. Auch das Kernel sollte zweitrangig sein. MMAL ist eine proprietäre Bibliothek. Was sagt den "ffmpeg -hide_banner -decoders | grep mmal"?

jsffm · 21. April 2022

mpeg2-Lizenz?

MarkusE · 22. April 2022

ffmpeg -hide_banner -decoders | grep mmal:

Code

V..... h264_mmal            h264 (mmal) (codec h264)
V..... mpeg2_mmal           mpeg2 (mmal) (codec mpeg2video)
V..... mpeg4_mmal           mpeg4 (mmal) (codec mpeg4)
V..... vc1_mmal             vc1 (mmal) (codec vc1)

Ja, ich habe eine mpeg2-Lizenz.

zillerbaer · 22. April 2022

Zitat von MarkusE

Es treten noch mehr Probleme auf, ich kann z.B. SD Filme gar nicht richtig abspielen.

Wie äussert sich das genau? Gibt es Fehlermeldungen?

rfehr · 22. April 2022

Zitat von MarkusE

Also,

Im Prinzip funktioniert das Plugin auf dem RPI3, mit Raspberry Pi OS Bullseye.

Bei der Wiedergabe von VDR Aufzeichnungen ist es aber instabil, nach einigem Springen auf, auch auf Schnittmarken, regiert der VDR nicht mehr.

Im Syslog steht

Auf dem Bildschirm wird

mmal error 7 "E10"

ausgegeben.

Markus

Alles anzeigen

Warum denn nicht einfach das vdr-plugin.rpihddevice nehmen, das funktioniert doch gut auf rpi2/3.

Gruß,

Roland

MarkusE · 23. April 2022

Hi,

Fehler im syslog:

Code

Apr 23 06:13:39 rpi3 vdr: [16127] [softhddev]SetVideoFormat: 1
Apr 23 06:13:39 rpi3 vdr: [16127] [softhddev]SetVideoDisplayFormat: 1
Apr 23 06:13:39 rpi3 vdr: [16127] [softhddev]GetSpuDecoder:
Apr 23 06:13:49 rpi3 vdr: [16127] [softhddev]SetPlayMode: 1
Apr 23 06:13:49 rpi3 vdr: AlsaSetup: Channels 2 SampleRate 44100#012           HWChannels 2 HWSampleRate 44100 SampleFormat S16_LE#012           Supports pause: no mmap: yes#012           AlsaBufferTime 100ms AudioBufferTime 450ms Threshold 450ms
Apr 23 06:13:56 rpi3 vdr: [16127] [softhddev]SetVolumeDevice: 5
Apr 23 06:14:59 rpi3 vdr: [16127] [softhddev]SetPlayMode: 0
Apr 23 06:14:59 rpi3 vdr: [16127] [softhddev]SetVideoDisplayFormat: 1
Apr 23 06:14:59 rpi3 vdr: [16127] [softhddev]GetSpuDecoder:
Apr 23 06:15:14 rpi3 vdr: [16127] [softhddev]SetPlayMode: 1
Apr 23 06:15:15 rpi3 vdr: audio/alsa: set params error: Die Dateizugriffsnummer ist in schlechter Verfassung
Apr 23 06:15:15 rpi3 vdr: audio/alsa: snd_pcm_avail_update(): Eingabe-/Ausgabefehler
1459272 Zeilen mit diesem Fehler
> Ich habe dann "manuell" den VDR restarted, da keine Videos mehr angezeigt wurden
Apr 23 06:29:38 rpi3 vdr: [16127] [softhddev]SetPlayMode: 0
Apr 23 06:29:38 rpi3 vdr: audio: snd_pcm_drop(): Eingabe-/Ausgabefehler
Apr 23 06:29:38 rpi3 vdr: audio: snd_pcm_prepare(): Eingabe-/Ausgabefehler
Apr 23 06:29:38 rpi3 vdr: [16127] [softhddev]SetVideoDisplayFormat: 1
Apr 23 06:29:38 rpi3 vdr: [16127] [softhddev]GetSpuDecoder:
Apr 23 06:29:43 rpi3 vdr: [16127] confirm: Wirklich neu starten?
Apr 23 06:29:43 rpi3 vdr: [16127] warning: Wirklich neu starten?
Apr 23 06:29:46 rpi3 vdr: [16127] confirmed

Alles anzeigen

Auf dem UI wird angezeigt:

AudioGetClock: no hw delay (sehr viele Zeilen, direkt untereinander)

AlsaFlushBuffers: snd_pcm_drop(): Eingabe-/Ausgabefehler

AlsaFlushBuffers: snd_pcm_prepare(): Eingabe-/Ausgabefehler

Beim 2. Versuch:

Syslog: "Apr 23 06:39:52 rpi3 vdr: [16355] [softhddev]Freeze:"

UI:

CodecAudioDecode: avcodev_receive_frame error: Die Ressource ist zur Zeit nicht verfügbar

Vout MMAL error 7 "E10"

CodecAudioDecode: avcodev_receive_frame error: Die Ressource ist zur Zeit nicht verfügbar

AudioGetClock: no hw delay

AlsaPlayer: ring buffer empty

AlsaSetup: set params error: Die Dateizugriffsnummer ist in schlechter Verfassung

AlsaFlushBuffers: snd_pcm_drop(): Eingabe-/Ausgabefehler

AlsaFlushBuffers: snd_pcm_prepare(): Eingabe-/Ausgabefehler

Es werden dann bis zum Restart des VDR keine Videos mehr abgespielt.

MarkusE · 23. April 2022

Beim Beenden des VDR:

Code

Apr 23 07:19:45 rpi3 vdr: [16355] confirm: Wirklich neu starten?
Apr 23 07:19:45 rpi3 vdr: [16355] warning: Wirklich neu starten?
Apr 23 07:19:47 rpi3 vdr: [16355] confirmed
Apr 23 07:19:47 rpi3 vdr: [16366] SVDRP client handler thread ended (pid=16355, tid=16366)
Apr 23 07:19:47 rpi3 vdr: [16355] SVDRP rpi3 > 192.168.178.33:6419 client destroyed for 'sp'
Apr 23 07:19:48 rpi3 vdr: [16365] SVDRP server handler thread ended (pid=16355, tid=16365)
Apr 23 07:19:48 rpi3 vdr: [16355] SVDRP rpi3 < sp connection closed
Apr 23 07:19:48 rpi3 vdr: [16355] SVDRP rpi3 < sp server destroyed
Apr 23 07:19:48 rpi3 vdr: [16355] saving /var/cache/vdr/cam.data
Apr 23 07:19:48 rpi3 vdr: [16355] stopping plugin: softhddevice-drm
Apr 23 07:19:48 rpi3 vdr: [16364] KBD remote control thread ended (pid=16355, tid=16364)
Apr 23 07:19:48 rpi3 vdr: [16355] saved setup to /var/lib/vdr/setup.conf
Apr 23 07:19:48 rpi3 vdr: [16355] deleting plugin: softhddevice-drm

Alles anzeigen

Und dann kommt nichts mehr. VDR braucht einen harten Kill.

Anmerkung: Diesen Fehler hatte ich erst einmal.

~ Markus

zillerbaer · 23. April 2022

Zitat von MarkusE

audio/alsa: set params error: Die Dateizugriffsnummer ist in schlechter Verfassung

Die Alsa Schnittstelle lässt sich nicht konfigurieren. Leider sagt die Alsa Fehlermeldung nicht welcher Parameter falsch ist. Die SampleRate ist mit 44100 bissel komisch. Vdr Aufnahmen haben eigentlich 48000. Kannst Du mit aplay Audio abspielen? Kannst Du mir ein Schnipsel zur Verfügung stellen?

MarkusE · 24. April 2022

Hi,

Hier habe ich etwas mit Mediaplayer abgespielt.

Auffällig ist, dass das erste Abspielen funktioniert. Beim Stop bleibt das Bild stehen.

Danach kann ich dann nichts mehr abspielen, auch keine VDR Aufzeichnungen.

Erst wieder nach einem Neustart des VDR.

Kann es sein, dass da etwas (alsa, ...) zurückgesetzt / neu initialisiert werden muss?

~ Markus

zillerbaer · 24. April 2022

Zitat von MarkusE

Kann es sein, dass da etwas (alsa, ...) zurückgesetzt / neu initialisiert werden muss?

Nein, softhdddevice-drm öffnet und schliesst alsa wie gefordert. Die Abarbeitung bleibt irgendwo hängen. Dazu wäre ein Log sehr hilfreich. Wenn es am Mediafile liegt wäre ein Schnipsel davon auch gut.

MarkusE · 24. April 2022

Hi,

Ich habe dir einen Link zum Beispielvideo geschickt.

Möglicherweise tritt der Fehler nicht sofort auf.

Dann das Video noch mal ansehen, mit den Farbtasten springen, beenden, ...

Wenn:

Bei der Anzeige des Videos kein Ton kommt
Die Anzeige des Video (Bild) nach einigen Sekunden stoppt

Ist der Fehler aufgetreten. Hier noch ein Auszug aus dem Log:

Code

Apr 24 16:49:36 rpi3 vdr: [26558] [softhddev]SetPlayMode: 1
Apr 24 16:49:36 rpi3 vdr: AlsaSetup: Channels 2 SampleRate 44100#012           HWChannels 2 HWSampleRate 44100 SampleFormat S16_LE#012           Supports pause: no mmap: yes#012           AlsaBufferTime 100ms AudioBufferTime 450ms Threshold 450ms
Apr 24 16:49:43 rpi3 vdr: [26558] [softhddev]SetPlayMode: 0
Apr 24 16:49:43 rpi3 vdr: [26558] [softhddev]SetVideoDisplayFormat: 1
Apr 24 16:49:43 rpi3 vdr: [26558] [softhddev]GetSpuDecoder:
Apr 24 16:49:58 rpi3 vdr: [26558] [softhddev]SetPlayMode: 1
Apr 24 16:49:59 rpi3 vdr: [26559] video directory scanner thread ended (pid=26558, tid=26559)
Apr 24 16:50:00 rpi3 vdr: [26558] [softhddev]SetVolumeDevice: 25
Apr 24 16:50:00 rpi3 vdr: [26558] [softhddev]SetVolumeDevice: 20
Apr 24 16:50:01 rpi3 vdr: [26558] [softhddev]SetVolumeDevice: 15
Apr 24 16:50:19 rpi3 vdr: [26558] [softhddev]SetPlayMode: 0
Apr 24 16:50:19 rpi3 vdr: [26558] [softhddev]SetVideoDisplayFormat: 1
Apr 24 16:50:19 rpi3 vdr: [26558] [softhddev]GetSpuDecoder:
Apr 24 16:50:31 rpi3 vdr: [26558] [softhddev]SetPlayMode: 1
Apr 24 16:50:41 rpi3 vdr: [26558] [softhddev]SetPlayMode: 0
Apr 24 16:50:41 rpi3 vdr: [26558] [softhddev]SetVideoDisplayFormat: 1
Apr 24 16:50:41 rpi3 vdr: [26558] [softhddev]GetSpuDecoder:
Apr 24 16:50:54 rpi3 vdr: [26558] [softhddev]SetPlayMode: 1
Apr 24 16:50:54 rpi3 vdr: audio/alsa: set params error: Die Dateizugriffsnummer ist in schlechter Verfassung
Apr 24 16:50:54 rpi3 vdr: audio/alsa: snd_pcm_avail_update(): Eingabe-/Ausgabefehler
Apr 24 16:50:54 rpi3 vdr: audio/alsa: snd_pcm_avail_update(): Eingabe-/Ausgabefehler
(dieser letzte Fehler kommt mehrere 1000 mal ...)

Alles anzeigen

zillerbaer · 24. April 2022

Das Video läuft auf meinem Raspi2b und Mediaplayer ohne Probleme. Allerdings nutze ich ein Hifiberry zur Audioausgabe. Ich habe ins git eine neue Version geschickt. Jetzt wird nach einem solchen Fehler vdr abgebrochen. Zusätzlich gibt es noch ein Paar Informationen. Schalte bitte im Makefile die erste DEBUG Zeile ein und poste das Log.

Spielst Du das über VDR/DVBPlayer oder über den in softhddevice-drm integrierten Mediaplayer ab? Für vdr ist das Format sicherlich zu exotisch um einen ordentlichen Stream zu erzeugen.:

Code

Stream #-1:0(und): Video: h264 (avc1 / 0x31637661), none, 320x256, 261 kb/s, 25 fps, 25 tbr, 50 tbn (default)
Stream #-1:1(und): Audio: aac (mp4a / 0x6134706D), 44100 Hz, 1 channels, 72 kb/s (default)

Zitat von MarkusE

mmal error 7 "E10"

Nicht E10, ist ja kein Sprit! EIO = Error IO ist harmlos. Kommt immer wenn MMAL zu langsam für softhddevice-drm ist.

Edit: Gibt es H264 in 3:4? MMAL kann es aber Allwinner nicht.

MarkusE · 24. April 2022

Hi,

Ich habe jetzt das Video, das ich Dir geschickt habe, mehrfach problemlos abgespielt.

Danach habe ich ein VDR Video gestartet, und der Fehler trat sofort auf.

Die Meldungen, die am Bildschirm angezeigt waren, wurden sofort alle gelöscht, daher kann ich die nicht posten (ist eh keine soo gute Idee, da kommen dann Sachen raus wie E10 ).

Hier noch die syslog Meldungen:

Zitat

Apr 24 20:13:59 rpi3 vdr: [28392] warning: Kein Audio verfügbar!

Apr 24 20:14:21 rpi3 vdr: [28392] [softhddev]SetPlayMode: 1

Apr 24 20:14:21 rpi3 vdr: video: display thread started

Apr 24 20:14:22 rpi3 vdr: AlsaSetup: Channels 1 SampleRate 44100#012 HWChannels 1 HWSampleRate 44100 SampleFormat S16_LE#012

Supports pause: no mmap: yes#012 AlsaBufferTime 100ms AudioBufferTime 450ms Threshold 450ms

Apr 24 20:14:22 rpi3 vdr: audio: a/v start, no valid audio

Apr 24 20:14:22 rpi3 vdr: audio: start? in Rb 23ms to skip 0ms

Apr 24 20:14:22 rpi3 vdr: audio: start? in Rb 46ms to skip 0ms

....
Apr 24 20:16:56 rpi3 vdr: audio: start? in Rb 1811ms to skip 0ms

Apr 24 20:16:56 rpi3 vdr: audio: ----> 1834ms start

Apr 24 20:16:58 rpi3 vdr: [28392] [softhddev]SetPlayMode: 0

Apr 24 20:16:58 rpi3 vdr: audio: wait on start condition

Apr 24 20:16:58 rpi3 vdr: audio/alsa: flush state RUNNING

Apr 24 20:16:58 rpi3 vdr: [28392] [softhddev]SetVideoDisplayFormat: 1

Apr 24 20:16:58 rpi3 vdr: [28392] [softhddev]GetSpuDecoder:

Apr 24 20:17:01 rpi3 CRON[28576]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)

Apr 24 20:17:07 rpi3 vdr: [28392] [softhddev]SetPlayMode: 1

Apr 24 20:17:07 rpi3 vdr: audio: start? in Rb 23ms to skip 0ms

Apr 24 20:17:07 rpi3 vdr: audio: start? in Rb 46ms to skip 0ms

(sehr oft wiederholt)
....
Apr 24 20:17:07 rpi3 vdr: audio: start? in Rb 1787ms to skip 0ms

Apr 24 20:17:07 rpi3 vdr: audio: start? in Rb 1811ms to skip 0ms

Apr 24 20:17:07 rpi3 vdr: audio: ----> 1811ms start

Apr 24 20:17:09 rpi3 vdr: [28392] [softhddev]SetPlayMode: 0

Apr 24 20:17:09 rpi3 vdr: audio: wait on start condition

Apr 24 20:17:09 rpi3 vdr: audio/alsa: flush state RUNNING

Apr 24 20:17:09 rpi3 vdr: [28392] [softhddev]SetVideoDisplayFormat: 1

Apr 24 20:17:09 rpi3 vdr: [28392] [softhddev]GetSpuDecoder:

Apr 24 20:17:17 rpi3 vdr: [28392] replay /video/video/Ein_Fressen_für_die_Geier/2022-04-23.02.42.5-0.rec

Apr 24 20:17:17 rpi3 vdr: [28392] playing '/video/video/Ein_Fressen_für_die_Geier/2022-04-23.02.42.5-0.rec/00001.ts'

Apr 24 20:17:17 rpi3 vdr: [28392] loading /video/video/Ein_Fressen_für_die_Geier/2022-04-23.02.42.5-0.rec/marks

Apr 24 20:17:17 rpi3 vdr: [28392] [softhddev]SetPlayMode: 1

Apr 24 20:17:17 rpi3 vdr: [28590] dvbplayer thread started (pid=28392, tid=28590, prio=high)

Apr 24 20:17:17 rpi3 vdr: [28590] starting replay at first mark 7200 (0:04:48.00)

Apr 24 20:17:17 rpi3 vdr: [28591] non blocking file reader thread started (pid=28392, tid=28591, prio=high)

Apr 24 20:17:17 rpi3 vdr: audio/demux: new channel id

Apr 24 20:17:17 rpi3 vdr: audio/alsa: set params error: Die Dateizugriffsnummer ist in schlechter Verfassung

Apr 24 20:17:17 rpi3 systemd[1]: vdr.service: Main process exited, code=killed, status=6/ABR

Alles anzeigen

Die Meldung "Apr 24 20:13:59 rpi3 vdr: [28392] warning: Kein Audio verfügbar!" ist irreführend, ich hatte immer Ton.

~ Markus

zillerbaer · 24. April 2022

Starte noch mal auf der Konsole. Da sollte am Ende auch noch eine Meldung kommen.

vdr-plugin-softhddevice-drm: RPI3, mmal, instabil

Jetzt mitmachen!

Teilen