VDR auf Goflex instabil

Manuel123 · May 24, 2013 at 1:25 PM

Hallo zusammen,

nach dem ich hier schon etwas länger mitlese, habe ich meinem Vater einen VDR (V2.0.1 von e-TOBI) auf einer Goflex (Debianized) mit Sundtek SkyTV Ultimate DVB S2 V5 installiert.
Leider läuft das System noch nicht ganz stabil und ich hoffe, dass ihr mir vielleicht ein paar Tipps geben könnt.

Das VDR Log ist "komplett voll" - obwohl der VDR nicht benutzt wird gibt es alle paar Minuten viele Einträge. (Nachdem ich das Log vor 4 Tagen vom syslog zur besseren Lesbarkeit in eine eigene Datei umgebogen habe, ist diese schon auf knapp 4 MB angewachsen.) Ich erspare es Euch deshalb in voller Länge und versuche, die hoffentlich wichtigen Abschnitte zu identifizieren. (Mitunter sind es häufige Watchdog Panics, aus dem nichts bzw. als aufeinander folgende Kette.)
Aufnahmen werden zertückelt und haben Fehler. Live-TV über XMBC-VNSI läuft jedoch.
a) Warum startet der VDR aus sich heraus einen Channel-Scan? Ich habe mithilfe von w_scan einen channels.conf erzeugt und diese auf die relevaten Kanäle zusammengeschrumpft. Diese channels.conf funktioniert soweit zunächst auch, aber aus irgendeinem Grund will der VDR wohl spontan mehr Kanäle haben.
b) Zwei Tage später nach einem Restart des VDR aufgrund eines Watchdog Panics reichen dem VDR die Kanäle dann immer noch nicht und er fügt erneut ein paar Kanäle hinzu.
Der VDR wird nicht genutzt und dennoch kommt der DVB-S Stick nicht zu Ruhe. Und der Sundtek mediasrv Deamon lastet die CPU kontinuierlich mit 10 % aus.
Obwohl auf den vdradmin-am Server nicht zugegriffen wird, lastet er den RAM laut top zu 25% aus!
Ist die Goflex vielleicht grundsätzlich mit der VDR Aufgabe überlastet, sie dient nebenbei als Samba und Minidlna Server?

Start-Log:

Display Spoiler

Code

May 20 14:24:10 goflex vdr: [1849] VDR version 2.0.1 started
May 20 14:24:10 goflex vdr: [1849] switched to user 'vdr'
May 20 14:24:10 goflex vdr: [1849] codeset is 'UTF-8' - known
May 20 14:24:11 goflex vdr: [1849] found 28 locales in /usr/share/locale
May 20 14:24:11 goflex vdr: [1849] loading plugin: /usr/lib/vdr/plugins/libvdr-vnsiserver3.so.2.0.0
May 20 14:24:11 goflex vdr: [1849] loading plugin: /usr/lib/vdr/plugins/libvdr-wirbelscan.so.2.0.0
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/setup.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/sources.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/diseqc.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/scr.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/channels.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/timers.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/commands.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/reccmds.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/svdrphosts.conf
May 20 14:24:11 goflex vdr: [1849] loading /var/lib/vdr/keymacros.conf
May 20 14:24:11 goflex vdr: [1849] registered source parameters for 'A - ATSC'
May 20 14:24:11 goflex vdr: [1849] registered source parameters for 'C - DVB-C'
May 20 14:24:11 goflex vdr: [1849] registered source parameters for 'S - DVB-S'
May 20 14:24:11 goflex vdr: [1849] registered source parameters for 'T - DVB-T'
May 20 14:24:11 goflex vdr: [1852] epg data reader thread started (pid=1849, tid=1852, prio=high)
May 20 14:24:11 goflex vdr: [1852] reading EPG data from /var/cache/vdr/epg.data
May 20 14:24:11 goflex vdr: [1851] video directory scanner thread started (pid=1849, tid=1851, prio=high)
May 20 14:24:11 goflex vdr: [1850] video directory scanner thread started (pid=1849, tid=1850, prio=high)
May 20 14:24:11 goflex vdr: [1851] video directory scanner thread ended (pid=1849, tid=1851)
May 20 14:24:12 goflex vdr: [1850] video directory scanner thread ended (pid=1849, tid=1850)
May 20 14:24:12 goflex vdr: [1849] probing /dev/dvb/adapter0/frontend0
May 20 14:24:12 goflex vdr: [1849] creating cDvbDevice
May 20 14:24:12 goflex vdr: [1849] new device number 1
May 20 14:24:13 goflex vdr: [1849] DVB API version is 0x0504 (VDR was built with 0x0504)
May 20 14:24:13 goflex vdr: [1849] frontend 0/0 provides DVB-S,DVB-S2 with QPSK ("Sundtek DVB-S/S2 (III)")
May 20 14:24:13 goflex vdr: [1849] found 1 DVB device
May 20 14:24:13 goflex vdr: [1849] initializing plugin: vnsiserver3 (0.9.1): VDR-Network-Streaming-Interface (VNSI) Server
May 20 14:24:13 goflex vdr: [1854] section handler thread started (pid=1849, tid=1854, prio=low)
May 20 14:24:13 goflex vdr: [1853] tuner on frontend 0/0 thread started (pid=1849, tid=1853, prio=high)
May 20 14:24:13 goflex vdr: [1853] cTimeMs: using monotonic clock (resolution is 1 ns)
May 20 14:24:13 goflex vdr: [1849] initializing plugin: wirbelscan (0.0.7): DVB and pvrinput channel scan for VDR
May 20 14:24:13 goflex vdr: [1849] setting primary device to 1
May 20 14:24:13 goflex vdr: [1849] device 1 has no MPEG decoder
May 20 14:24:13 goflex vdr: [1849] assuming manual start of VDR
May 20 14:24:13 goflex vdr: [1849] SVDRP listening on port 6419
May 20 14:24:13 goflex vdr: [1849] setting current skin to "sttng"
May 20 14:24:13 goflex vdr: [1849] loading /var/lib/vdr/themes/sttng-default.theme
May 20 14:24:13 goflex vdr: [1849] starting plugin: vnsiserver3
May 20 14:24:13 goflex vdr: [1849] VNSI: VNSI Server started
May 20 14:24:13 goflex vdr: [1849] VNSI: Channel streaming timeout: 10 seconds
May 20 14:24:13 goflex vdr: [1849] starting plugin: wirbelscan
May 20 14:24:13 goflex vdr: [1849] ERROR (lirc.c,45): /var/run/lirc/lircd: Datei oder Verzeichnis nicht gefunden
May 20 14:24:13 goflex vdr: [1849] ERROR: remote control LIRC not ready!
May 20 14:24:13 goflex vdr: [1849] switching to channel 4
May 20 14:24:13 goflex vdr: [1849] setting watchdog timer to 1200 seconds
May 20 14:24:13 goflex vdr: [1855] VDR VNSI Server thread started (pid=1849, tid=1855, prio=high)
May 20 14:24:13 goflex vdr: [1849] OSD size changed to 720x480 @ 1
May 20 14:24:13 goflex vdr: [1849] ERROR: no OSD provider available - using dummy OSD!
May 20 14:24:15 goflex vdr: [1852] epg data reader thread ended (pid=1849, tid=1852)

Display More

Zu 2. Zerstückelte Aufnahme:

Display Spoiler

Code

May 20 20:28:05 goflex vdr: [2502] max. latency time 22 seconds
May 20 20:28:48 goflex vdr: [2508] changing pids of channel 1128 from 5131+5131=27:5132=deu@3,5133=mis@3;5136=deu@106:5135=deu:5134 to 5121+5121=27:5122=deu@3,5123=mis@3;5126=deu@106:5135=deu:5134
May 20 20:30:14 goflex vdr: [2508] channel 2 (Das Erste HD) event Mon 20.05.2013 20:15-21:45 (VPS: 20.05. 20:15) 'Tatort: Unvergessen' status 4
May 20 20:34:37 goflex vdr: [2520] i/o throttle activated, count = 1 (tid=2520)
May 20 20:34:43 goflex vdr: [2520] i/o throttle released, count = 0 (tid=2520)
May 20 20:38:03 goflex vdr: [2520] ERROR: skipped 140 bytes to sync on TS packet on device 1
May 20 20:38:03 goflex vdr: [2520] ERROR: skipped 44 bytes to sync on TS packet on device 1
May 20 20:38:04 goflex vdr: [2520] ERROR: skipped 31 bytes to sync on TS packet on device 1
May 20 20:38:04 goflex vdr: [2520] ERROR: skipped 156 bytes to sync on TS packet on device 1
May 20 20:38:04 goflex vdr: [2521] i/o throttle activated, count = 1 (tid=2521)
May 20 20:38:04 goflex vdr: [2521] buffer usage: 80% (tid=2520)
May 20 20:38:04 goflex vdr: [2521] buffer usage: 90% (tid=2520)
May 20 20:38:04 goflex vdr: [2520] ERROR: skipped 1 bytes to sync on TS packet on device 1
May 20 20:38:04 goflex vdr: [2521] buffer usage: 60% (tid=2520)
May 20 20:38:04 goflex vdr: [2521] i/o throttle released, count = 0 (tid=2521)
May 20 20:39:08 goflex vdr: [2520] i/o throttle activated, count = 1 (tid=2520)
May 20 20:39:08 goflex vdr: [2521] i/o throttle activated, count = 2 (tid=2521)
May 20 20:39:08 goflex vdr: [2520] i/o throttle released, count = 1 (tid=2520)
May 20 20:39:08 goflex vdr: [2521] buffer usage: 70% (tid=2520)
... Das geht hier so weiter ...
May 20 20:51:07 goflex vdr: [2521] buffer usage: 60% (tid=2520)
May 20 20:51:07 goflex vdr: [2521] i/o throttle released, count = 1 (tid=2521)
May 20 20:51:13 goflex vdr: [2520] ERROR: 54951 ring buffer overflows (10330788 bytes dropped)
May 20 20:51:19 goflex vdr: [2520] ERROR: 40068 ring buffer overflows (7532784 bytes dropped)
May 20 20:51:25 goflex vdr: [2520] ERROR: 37027 ring buffer overflows (6961076 bytes dropped)
May 20 20:51:25 goflex vdr: [2519] recording to '/media/smb-share/Videos/Aufnahmen/Tatort/2013-05-20.20.13.2-0.rec/00002.ts'
May 20 20:51:31 goflex vdr: [2520] ERROR: 39902 ring buffer overflows (7501576 bytes dropped)
May 20 20:51:37 goflex vdr: [2520] ERROR: 34471 ring buffer overflows (6480548 bytes dropped)
May 20 20:51:43 goflex vdr: [2520] ERROR: 48603 ring buffer overflows (9137364 bytes dropped)
... Das geht hier so weiter ...

Display More

Zu 3. Automatischer Channel Scan aus dem nichts:

Display Spoiler

Code

May 20 14:50:00 goflex vdr: [1854] changing pids of channel 1332 from 511+511=2:512=deu@3;515=deu@106:0:32 to 511+511=2:512=deu@3,513=eng@3;515=deu@106:0:32
May 20 14:50:00 goflex vdr: [1854] changing pids of channel 1337 from 2815+2815=2:2816=deu@3,2817=eng@3;2819=deu@106:0:32 to 2815+2815=2:2816=deu@3;2819=deu@106:0:32
May 20 14:50:03 goflex vdr: [1926] epg data writer thread started (pid=1849, tid=1926, prio=low)
May 20 14:50:09 goflex vdr: [1926] epg data writer thread ended (pid=1849, tid=1926)
May 20 14:50:58 goflex vdr: [1853] frontend 0/0 lost lock on channel 1338, tp 111875
May 20 14:50:59 goflex vdr: [1853] frontend 0/0 regained lock on channel 1338, tp 111875
May 20 14:51:08 goflex vdr: [1853] frontend 0/0 lost lock on channel 1343, tp 111914
May 20 14:51:08 goflex vdr: [1853] frontend 0/0 regained lock on channel 1343, tp 111914
May 20 14:52:07 goflex vdr: [1854] creating new channel 'SES 01,;BetaDigital' on S19.2E transponder 112148 with id 133-7-53-0
May 20 14:52:07 goflex vdr: [1854] creating new channel 'SES 02,;BetaDigital' on S19.2E transponder 112148 with id 133-7-70-0
May 20 14:52:07 goflex vdr: [1854] creating new channel 'RNF,;BetaDigital' on S19.2E transponder 112148 with id 133-7-768-0
... hier werden noch ganz viel mehr Channels erzeugt ...
May 20 14:52:07 goflex vdr: [1854] changing pids of channel 1375 from 0+0=0:0:0:0 to 0+0=0:304=deu@4:0:0
May 20 14:52:07 goflex vdr: [1854] changing pids of channel 1376 from 0+0=0:0:0:0 to 0+0=0:320=deu@4:0:0
May 20 14:52:07 goflex vdr: [1854] changing pids of channel 1367 from 0+0=0:0:0:0 to 2303+2303=2:2304=deu@3:0:0
... hier werden noch viel mehr pids verändert ...
es geht bis:
May 20 16:04:44 goflex vdr: [1854] changing pids of channel 1547 from 511+511=27:0;515=deu@106,516=eng@106:0:32 to 511+511=27:0;515=deu@106:0:32

Display More

Zu 4. mediasrv.log

Display Spoiler

Code

2013-05-20 14:24:00 [1109] Enabling standby
2013-05-20 14:24:00 [1109] Device is now in standby
2013-05-20 14:24:11 [1109] Waking up device
2013-05-20 14:24:12 [1109] voltage is off
2013-05-20 14:24:13 [1109] voltage is high/0
2013-05-20 14:24:13 [1109] Frequency: 1611
2013-05-20 14:24:13 [1109] Symbolrate: 22000
2013-05-20 14:24:13 [1109] Enabling tuning delay
2013-05-20 14:24:16 [1109] voltage is high/0
2013-05-20 14:24:16 [1109] Frequency: 1236
2013-05-20 14:24:16 [1109] Symbolrate: 27500
2013-05-20 14:25:00 [1109] voltage is high/0
2013-05-20 14:25:00 [1109] Frequency: 1353
2013-05-20 14:25:00 [1109] Symbolrate: 27500
2013-05-20 14:40:04 [1109] voltage is high/0
... so geht es hier ganz lange weiter, nach paar Stunden gehts dann für fünf Minuten mal wieder in den Standby und dann fängt es vorne an ...

Display More

Wie soll ich das Problem eingrenzen?

Viele Grüße,
Manuel

seahawk1986 · May 24, 2013 at 1:59 PM

Quote from Manuel123

a) Warum startet der VDR aus sich heraus einen Channel-Scan?

Weil du ihm nichts anderes gesagt hast. http://vdr-wiki.de/wiki/index.php/Benutzerhandbuch#DVB

Quote from Manuel123

Der VDR wird nicht genutzt und dennoch kommt der DVB-S Stick nicht zu Ruhe.

Man kann das dynamite-Plugin nutzen um DVB-Geräte gezielt freizugeben, der VDR macht ansonsten einfach einen kontinuierlichen EPG-Scan.

Quote from Manuel123

Obwohl auf den vdradmin-am Server nicht zugegriffen wird, lastet er den RAM laut top zu 25% aus!

Selber schuld, wenn man das benutzt. Der Daemon kann nur über SVDRP mit dem VDR reden und muss viel bei einem großen EPG vermutlich einiges zwischenspeichern.

Quote from Manuel123

Ist die Goflex vielleicht grundsätzlich mit der VDR Aufgabe überlastet, sie dient nebenbei als Samba und Minidlna Server?

Kommt darauf an was sie so zu tun hat - Minidlna kann ja auch einiges an I/O verursachen, wenn sich der Datenbestand häufig ändert und inotify genutzt wird.

Manuel123 · May 24, 2013 at 2:38 PM

Danke schon mal für die Antworten.

Die Frage bezüglich des automatischen Channel-Scan ist mir nun peinlich... Das werde ich dann mal schnell deaktivieren.
Warum der DVB-S Stick nicht zur Ruhe kommt ist mir immer noch nicht klar, denn die EPG Daten dürften ja standardmäßig nur alle 5 Stunden aktuallisiert werden, oder? (Die mediasrv.log geht wirklich kontinuierlich so weiter und die wenigen Standby-Zeiten sind immer nur maximal fünf Minuten lang.)
Das vdradmin-am den VDR über SVDRP ansteuert, hatte ich auch schon gelesen. Werde jetzt dann mal VDR Live installieren - in der Annahme, dass Xxv mehr Resourcen benötigt (?).
Eigentlich ist das System äußerst statisch.

seahawk1986 · May 24, 2013 at 2:57 PM

Quote from Manuel123

Warum der DVB-S Stick nicht zur Ruhe kommt ist mir immer noch nicht klar, denn die EPG Daten dürften ja standardmäßig nur alle 5 Stunden aktuallisiert werden, oder? (Die mediasrv.log geht wirklich kontinuierlich so weiter und die wenigen Standby-Zeiten sind immer nur maximal fünf Minuten lang.)

Weil der VDR von sich aus einen Tuner nie dazu kommen lässt sich schlafen zu legen (er wird nicht frei gegeben). Entweder ein Kanal ist für Live-TV oder eine Aufnahme getuned oder es findet ein EPG-Scan auf dem Tuner statt. Die 5 Stunden bis zu EPG-Scan heißen ja nur, dass falls 5 Stunden lang keine Benutzeraktivität registriert wurde auch der Tuner, der normalerweise fürs Live-TV genutzt wird zum EPG-Scan herangezogen wird. Die einzige Möglichkit daran zu drehen (außer den VDR zu beenden) ist wie schon geschrieben das dynamite-Plugin, das allerdings einen Patch für den VDR und Freude am Basteln mit udev erfordert: https://github.com/flensrocker/vdr-plugin-dynamite

Sundtek · May 24, 2013 at 3:12 PM

Du kannst auf der Goflex auch noch folgende Konfiguration anlegen:
/etc/sundtek.conf
use_hwpidfilter=on
ir_disabled=1

Auf die Aussage des Speicherverbrauchs wuerde ich mich nur bedingt verlassen. Wir haben schon Systeme gesehen wo der Speicherverbrauch "angeblich" 100-300% betrug. Eventuell ist dies nur eine fehlerhafte Darstellung von top. Es kann sich hier eigentlich nur um virtuellen Speicher handeln. Der Treiber sollte nicht ueber 5-20 MB RAM benoetigen (je nachdem welche Konfiguration der Stick hat dvb-c/t, analogtv, fm radio benoetigt am meisten)

Manuel123 · May 28, 2013 at 3:16 PM

Habe nun mal folgendes gemacht:

VDR nun erstmal von 2.0.1 auf 1.7.28 runter
VDRadmin-am durch VDR Live ersetzt
/etc/sundtek.conf angepasst
VNSI Plugin entfernt
Das Aufnahmen Verzeichnis aus MiniDLNA Inotify Überwachung herausgenommen

Die Aufnahmen scheinen jetzt in Ordnung zu sein (muss das selbst nochmal etwas weiter testen) und VDR Live fühlt sich (im Vergleich zu VDRadmin-am) flüssig an.
Vor allem der Letzte Punkt mit der Inotify Überwachung, war natürlich zumindest gedanklich ("äußerst statisches System") ein dämlicher Fehler. Ob es daran lag werde ich demnächst nochmal testen.
Außerdem bin ich auf den Thread Golfex Ram gestoßen, in dem von einer SSD als SWAP Partition die Rede ist, wenn dies auch live Ansehen der Aufnahme ermöglichen sollte, muss ich darüber mal ernsthaft nachdenken.

Das Dynamite Plugin muss ich mir wohl ebenfalls auch mal zu gemüte führen, obwohl es mir davor doch noch ziemlich graust, aber angeblich (und logischerweise) lässt sich dadurch wohl die Lebenszeit des DVB-Sticks verlängern. Sundtek gibt es dazu eine konkretere Aussage?

Viele Grüße und Danke für die Antworten, schon nach einer Frage gefällt mir die VDR Community!
Manuel

VDR auf Goflex instabil

Participate now!

Tags