[0.3] vdr startet manchmal komplett ohne plugins

hsteinhaus · 9. November 2010

Hallo,

sehr selten tritt bei mir folgendes Phänomen auf: der VDR startet komplett ohne Plugins. Leider ist dieses Verhalten sehr selten und ich habe bislang keinen Weg gefunden, es zu reproduzieren.

Da bei mein VDR-Server durch eine FF-Karte bedient und gesteuert wird und der dann mindestens das remote- und das sddevice-Plugin fehlen, ist diese Situation eine recht unschöne Sackgasse, der VDR lässt sich nicht mal mehr ohne weiteres runterfahren.

Irgendwelche Ideen, wie man das in Zukunft vermeiden kann?

Im Log sieht das Ganze dann so aus:

Zitat

Nov 9 12:03:37 vdrsrv vdr: [871] VDR version 1.7.16 started
Nov 9 12:03:37 vdrsrv vdr: [871] switched to user 'vdr'
Nov 9 12:03:37 vdrsrv vdr: [871] codeset is 'UTF-8' - known
Nov 9 12:03:37 vdrsrv vdr: [871] found 27 locales in /usr/share/locale
Nov 9 12:03:37 vdrsrv vdr: [871] no locale for language code 'por'
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/setup.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/sources.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/diseqc.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/channels.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/timers.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/commands.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/reccmds.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/svdrphosts.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/remote.conf
Nov 9 12:03:37 vdrsrv vdr: [871] loading /var/lib/vdr/keymacros.conf
Nov 9 12:03:37 vdrsrv vdr: [871] reading EPG data from /var/cache/vdr/epg.data
Nov 9 12:03:37 vdrsrv vdr: [1046] video directory scanner thread started (pid=871, tid=1046)
Nov 9 12:03:37 vdrsrv vdr: [1047] video directory scanner thread started (pid=871, tid=1047)
Nov 9 12:03:38 vdrsrv vdr: [871] registered source parameters for 'A - ATSC'
Nov 9 12:03:38 vdrsrv vdr: [871] registered source parameters for 'C - DVB-C'
Nov 9 12:03:38 vdrsrv vdr: [871] registered source parameters for 'S - DVB-S'
Nov 9 12:03:38 vdrsrv vdr: [871] registered source parameters for 'T - DVB-T'
Nov 9 12:03:38 vdrsrv vdr: [871] probing /dev/dvb/adapter0/frontend0
Nov 9 12:03:38 vdrsrv vdr: [871] creating cDvbDevice
Nov 9 12:03:38 vdrsrv vdr: [871] new device number 1
Nov 9 12:03:38 vdrsrv vdr: [871] LNB-sharing: setting device 0 to use LNB 1
Nov 9 12:03:39 vdrsrv vdr: [871] frontend 0/0 provides DVB-S with QPSK ("ST STV0299 DVB-S")
Nov 9 12:03:39 vdrsrv vdr: [871] probing /dev/dvb/adapter1/frontend0
Nov 9 12:03:39 vdrsrv vdr: [871] creating cDvbDevice
Nov 9 12:03:39 vdrsrv vdr: [871] new device number 2
Nov 9 12:03:39 vdrsrv vdr: [1186] section handler thread started (pid=871, tid=1186)
Nov 9 12:03:39 vdrsrv vdr: [1185] tuner on frontend 0/0 thread started (pid=871, tid=1185)
Nov 9 12:03:39 vdrsrv vdr: [871] LNB-sharing: setting device 1 to use LNB 2
Nov 9 12:03:39 vdrsrv vdr: [1188] CI adapter on device 1 thread started (pid=871, tid=1188)
Nov 9 12:03:39 vdrsrv vdr: [871] frontend 1/0 provides DVB-S with QPSK ("Philips TDA8083 DVB-S")
Nov 9 12:03:39 vdrsrv vdr: [871] probing /dev/dvb/adapter2/frontend0
Nov 9 12:03:39 vdrsrv vdr: [871] creating cDvbDevice
Nov 9 12:03:39 vdrsrv vdr: [871] new device number 3
Nov 9 12:03:39 vdrsrv vdr: [1191] section handler thread started (pid=871, tid=1191)
Nov 9 12:03:39 vdrsrv vdr: [871] LNB-sharing: setting device 2 to use LNB 3
Nov 9 12:03:39 vdrsrv vdr: [1190] tuner on frontend 1/0 thread started (pid=871, tid=1190)
Nov 9 12:03:39 vdrsrv vdr: [871] frontend 2/0 provides DVB-S2 with QPSK ("STB0899 Multistandard")
Nov 9 12:03:39 vdrsrv vdr: [1227] tuner on frontend 2/0 thread started (pid=871, tid=1227)
Nov 9 12:03:39 vdrsrv vdr: [871] probing /dev/dvb/adapter3/frontend0
Nov 9 12:03:39 vdrsrv vdr: [871] creating cDvbDevice
Nov 9 12:03:39 vdrsrv vdr: [871] new device number 4
Nov 9 12:03:39 vdrsrv vdr: [871] new device number 4
Nov 9 12:03:39 vdrsrv vdr: [871] LNB-sharing: setting device 3 to use LNB 4
Nov 9 12:03:39 vdrsrv vdr: [1228] section handler thread started (pid=871, tid=1228)
Nov 9 12:03:39 vdrsrv vdr: [871] frontend 3/0 provides DVB-S2 with QPSK ("STB0899 Multistandard")
Nov 9 12:03:39 vdrsrv vdr: [1246] section handler thread started (pid=871, tid=1246)
Nov 9 12:03:39 vdrsrv vdr: [871] probing /dev/dvb/adapter4/frontend0
Nov 9 12:03:39 vdrsrv vdr: [871] creating cDvbDevice
Nov 9 12:03:39 vdrsrv vdr: [871] new device number 5
Nov 9 12:03:39 vdrsrv vdr: [871] LNB-sharing: setting device 4 to use LNB 5
Nov 9 12:03:39 vdrsrv vdr: [1245] tuner on frontend 3/0 thread started (pid=871, tid=1245)
Nov 9 12:03:39 vdrsrv vdr: [871] frontend 4/0 provides DVB-S2 with QPSK ("STB0899 Multistandard")
Nov 9 12:03:39 vdrsrv vdr: [871] found 5 DVB devices
Nov 9 12:03:39 vdrsrv vdr: [871] setting primary device to 1
Nov 9 12:03:39 vdrsrv vdr: [1249] section handler thread started (pid=871, tid=1249)
Nov 9 12:03:39 vdrsrv vdr: [871] device 1 has no MPEG decoder
Nov 9 12:03:39 vdrsrv vdr: [871] assuming manual start of VDR
Nov 9 12:03:39 vdrsrv vdr: [871] SVDRP listening on port 6419
Nov 9 12:03:39 vdrsrv vdr: [871] skin "EnigmaNG" not available - using "classic" instead
Nov 9 12:03:39 vdrsrv vdr: [871] loading /var/lib/vdr/themes/classic-default.theme
Nov 9 12:03:39 vdrsrv vdr: [871] skin "EnigmaNG" not available - using "classic" instead
Nov 9 12:03:39 vdrsrv vdr: [871] loading /var/lib/vdr/themes/classic-default.theme
Nov 9 12:03:39 vdrsrv vdr: [1248] tuner on frontend 4/0 thread started (pid=871, tid=1248)
Nov 9 12:03:39 vdrsrv vdr: [871] ERROR: /dev/lircd: No such file or directory
Nov 9 12:03:39 vdrsrv vdr: [871] ERROR: remote control LIRC not ready!
Nov 9 12:03:41 vdrsrv vdr: [1188] CAM 1: no module present
Nov 9 12:03:41 vdrsrv vdr: [1188] CAM 2: no module present
Nov 9 12:03:41 vdrsrv vdr: [871] switching to channel 15

Nov 9 12:03:41 vdrsrv vdr: [871] setting watchdog timer to 60 seconds
Nov 9 12:03:41 vdrsrv vdr: [871] OSD size changed to 720x480 @ 1
Nov 9 12:03:41 vdrsrv vdr: [871] ERROR: no OSD provider available - using dummy OSD!
Nov 9 12:03:41 vdrsrv vdr: [1047] video directory scanner thread ended (pid=871, tid=1047)
Nov 9 12:03:44 vdrsrv vdr: [1046] video directory scanner thread ended (pid=871, tid=1046)
Nov 9 12:03:44 vdrsrv vdr: [1186] changing pids of channel 272 from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0
Nov 9 12:03:47 vdrsrv vdr: [871] max. latency time 1 seconds
Nov 9 12:04:08 vdrsrv dvbmon: Saving number of devices (5) after boot
Nov 9 12:04:08 vdrsrv dvbmon: vdr started by udev allready
Nov 9 12:04:24 vdrsrv vdr: [1186] changing pids of channel 272 from 401+401=2:402=deu@3:0:0 to 501+501=2:502=deu@3:0:0
Nov 9 12:05:27 vdrsrv vdr: [1186] changing pids of channel 272 from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0
Nov 9 12:06:27 vdrsrv vdr: [1186] changing pids of channel 272 from 401+401=2:402=deu@3:0:0 to 501+501=2:502=deu@3:0:0
Nov 9 12:07:27 vdrsrv vdr: [1186] changing pids of channel 272 from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0
Nov 9 12:08:27 vdrsrv vdr: [1186] changing pids of channel 272 from 401+401=2:402=deu@3:0:0 to 501+501=2:502=deu@3:0:0
Nov 9 12:09:30 vdrsrv vdr: [1186] changing pids of channel 272 from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0
Nov 9 12:10:30 vdrsrv vdr: [1186] changing pids of channel 272 from 401+401=2:402=deu@3:0:0 to 501+501=2:502=deu@3:0:0

Alles anzeigen

Grüße,
Holger

hsteinhaus · 13. November 2010

Gemeldet als Bug #234.

Grüße,
Holger

steffen_b · 13. November 2010

liegt /usr/ auf einer seperaten Partition ? Geht dann alles wenn du restart vdr machst ?

hsteinhaus · 13. November 2010

Hallo,

nein, /usr liegt ganz normal auf der Root-Partition (ganz normaler Festplatten-Boot). "restart vdr" werde ich beim nächsten Treffer mal ausprobieren. Das Problem ist leider sehr schlecht reproduzierbar.

Grüße,
Holger

hsteinhaus · 15. November 2010

Habe den VDR gerade mal wieder ohne Plugins erwischt.

Interessant ist u.a. die Kommandozeile, die gestartet wurde:

Code

root@vdrsrv:~# ps -aux | grep vdr
Warning: bad ps syntax, perhaps a bogus '-'? See http://procps.sf.net/faq.html
vdr        918  1.5  2.3 165904 23780 ?        S<sl 15:47   0:06 /usr/bin/vdr -v /srv/vdr/video.00 -c /var/lib/vdr -L /usr/lib/vdr/plugins -r /usr/lib/vdr/vdr-recordingaction -s /usr/lib/vdr/vdr-shutdown.wrapper -E /var/cache/vdr/epg.data -u vdr -g /tmp --port 6419 -w 60 
root      1346  0.0  0.0   3332   808 pts/0    S+   15:54   0:00 grep --color=auto vdr

Nach einem "restart vdr" sieht es hingegen so aus:

Code

root@vdrsrv:~# restart vdr
vdr start/running, process 1363
root@vdrsrv:~# !ps
ps -aux | grep vdr
Warning: bad ps syntax, perhaps a bogus '-'? See http://procps.sf.net/faq.html
vdr       1363 55.0  2.7 274996 28652 ?        D<sl 15:55   0:00 /usr/bin/vdr -v /srv/vdr/video.00 -c /var/lib/vdr -L /usr/lib/vdr/plugins -r /usr/lib/vdr/vdr-recordingaction -s /usr/lib/vdr/vdr-shutdown.wrapper -E /var/cache/vdr/epg.data -u vdr -g /tmp --port 6419 -w 60 -Pskinenigmang --logodir=/usr/share/vdr-enigmang-icons --epgimages=/var/cache/vdr/epgimages -Pepgsearch -Premote -i /dev/input/ir -Pconflictcheckonly -Pwirbelscan -Pstreamdev-client -Pepgsearchonly -Pdvbsddevice -Pquickepgsearch -Pstreamdev-server -Plive --port=8008 --ip=0.0.0.0 --epgimages=/var/cache/vdr/epgimages -Pfemon -Pchannellists -Pmenuorg
root      1433  0.0  0.0   3336   880 pts/0    S+   15:55   0:00 grep --color=auto vdr

Danach funktioniert erwartungsgemäß alles einwandfrei. Nur wieso gibt es offenscihtlich zwei verschiedene Arten, den VDR zu starten?

Grüße,
Holger

steffen_b · 15. November 2010

Die Plugins werden über das Vorhandensein in /usr/lib/vdr/plugins/ und die order.conf bestimmt. Das wird vom /usr/share/vdr/plugin-loader gemacht.

hsteinhaus · 25. November 2010

Der Bug hat wieder mal zugeschlagen (nach 23 fehlerfreien Starts). Eine Kleinigkeit ist mir aufgefallen - keine Ahnung, ob das was mit der Problemursache zu tun hat: Das Envrionment eines kaputten VDR-Prozesses ist geringfügig anders als das eines Intakten:

Code

kaputt:
TERM=linuxLC_ALL=de_DE.UTF-8PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/binPWD=/LANG=de_DE.UTF-8HOME=/var/lib/vdrSHLVL=0UPSTART_INSTANCE=UPSTART_EVENTS=dvb-devices-completeUPSTART_JOB=vdr


intakt nach "restart vdr":
TERM=linuxLC_ALL=de_DE.UTF-8PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/binPWD=/LANG=de_DE.UTF-8HOME=/var/lib/vdrSHLVL=0UPSTART_INSTANCE=UPSTART_JOB=vdr


intakt nach erfolgreichem reboot:
TERM=linuxLC_ALL=de_DE.UTF-8PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/binPWD=/LANG=de_DE.UTF-8HOME=/var/lib/vdrSHLVL=0UPSTART_INSTANCE=UPSTART_EVENTS=dvb-devices-completeUPSTART_JOB=vdr

Edit: Leider zeigt jedoch der letzte Versuch, das die Ursache "UPSTART_EVENTS=dvb-devices-complete" offenbar doch nichts mit dem Problem zu tun hat...

gda · 25. November 2010

kannst du das noch mal mit Zeilenumbrüchen liefern? Sollte ja nun inzwischen hinreichend oft erklärt worden sein, wie das geht.

Gerald

steffen_b · 25. November 2010

Bau mal bitte ein ls -la /usr/lib/vdr/plugins/ ... und ein vdr --version und leite die Ausgabe ins log oder eine Datei oder so - damit man beim nächsten mal die Ausgabe der Sachen hat wenns auftritt. Ich kanns mir nicht erklären - aber vielleicht sieht man so dann ja was ?

hsteinhaus · 25. November 2010

Hallo Steffen,

habs mal eingebaut - werde heute abend vielleicht auch mal einen Skript bauen, der den Rechner automatisch startet und runterfährt.

Grüße,
Holger

hsteinhaus · 25. November 2010

Durch das Loggen hat sich die Fehlerwahrscheinlichkeit offenbar massiv erhöht - jetzt knallts jeden dritten bis fünften Start.

Ich habe mal folgende Befehle in vdr.conf eingebaut:

Code

...
export HOME=/var/lib/vdr


        echo "=========================================================================" >> /var/log/debug.txt
        date >> /var/log/debug.txt
        echo "=========================================================================" >> /var/log/debug.txt
        ls -la /usr/lib/vdr/plugins/ >> /var/log/debug.txt
        vdr --version >> /var/log/debug.txt


exec /usr/bin/vdr -v $VIDEO_DIR -c $CFG_DIR -L $PLUGIN_DIR -r $REC_CMD -s $VDRSHUTDOWN -E $EPG_FILE -u $USER -g /tmp --port $SVDRP_PORT $OPTIONS "${PLUGINS[@]}" $REDIRECT &> /tmp/vdr.log
...

Alles anzeigen

Der Output sieht nach einem Fehlstart allerdings identisch zum Normalfall aus:

Code

=========================================================================
Do 25. Nov 16:16:28 CET 2010
=========================================================================
insgesamt 5816
drwxr-xr-x 2 root root    4096 2010-11-24 08:05 .
drwxr-xr-x 3 root root    4096 2010-11-24 08:05 ..
-rw-r--r-- 1 root root   42800 2010-11-02 23:46 libvdr-channellists.so.1.7.16
-rw-r--r-- 1 root root   17876 2010-11-02 23:52 libvdr-conflictcheckonly.so.1.7.16
-rw-r--r-- 1 root root   46876 2010-11-21 14:34 libvdr-dvbsddevice.so.1.7.16
-rw-r--r-- 1 root root   13740 2010-11-02 23:52 libvdr-epgsearchonly.so.1.7.16
-rw-r--r-- 1 root root  972204 2010-11-02 23:52 libvdr-epgsearch.so.1.7.16
-rw-r--r-- 1 root root  150040 2010-11-02 23:53 libvdr-extrecmenu.so.1.7.16
-rw-r--r-- 1 root root  204252 2010-11-08 16:53 libvdr-femon.so.1.7.16
-rw-r--r-- 1 root root  117032 2010-11-02 22:50 libvdr-iptv.so.1.7.16
-rw-r--r-- 1 root root 1538972 2010-11-12 00:30 libvdr-live.so.1.7.16
-rw-r--r-- 1 root root   83988 2010-11-02 22:56 libvdr-menuorg.so.1.7.16
-rw-r--r-- 1 root root   13740 2010-11-02 23:52 libvdr-quickepgsearch.so.1.7.16
-rw-r--r-- 1 root root   42736 2010-11-02 23:09 libvdr-remote.so.1.7.16
-rw-r--r-- 1 root root  283604 2010-11-02 23:19 libvdr-skinenigmang.so.1.7.16
-rw-r--r-- 1 root root   79844 2010-11-02 23:17 libvdr-streamdev-client.so.1.7.16
-rw-r--r-- 1 root root  412136 2010-11-04 13:18 libvdr-streamdev-server.so.1.7.16
-rw-r--r-- 1 root root  347308 2010-11-02 23:22 libvdr-text2skin.so.1.7.16
-rw-r--r-- 1 root root  199472 2010-11-02 23:25 libvdr-vnsiserver.so.1.7.16
-rw-r--r-- 1 root root  407304 2010-11-02 23:28 libvdr-wirbelscan.so.1.7.16
-rw-r--r-- 1 root root  543484 2010-11-23 22:30 libvdr-xineliboutput.so.1.7.16
-rw-r--r-- 1 root root  389844 2010-11-02 23:29 libvdr-xine.so.1.7.16
vdr (1.7.16/1.7.16) - The Video Disk Recorder
skinenigmang (0.1.1) - EnigmaNG skin
epgsearch (0.9.25.beta17) - search the EPG for repeats and more
remote (0.4.0) - Remote control
conflictcheckonly (0.0.1) - Direct access to epgsearch's conflict check menu
iptv (0.4.2) - Experience the IPTV
wirbelscan (0.0.5) - DVB and pvrinput channel scan for VDR
streamdev-client (0.5.0-CVS) - VTP Streaming Client
epgsearchonly (0.0.1) - Direct access to epgsearch's search menu
text2skin (1.3.1) - Loader for text-based skins
dvbsddevice (0.0.3) - SD Full Featured DVB device
quickepgsearch (0.0.1) - Quick search for broadcasts
streamdev-server (0.5.0-CVS) - VDR Streaming Server
vnsiserver (0.0.1) - VDR-Network-Streaming-Interface (VNSI) Server
xine (0.9.3) - Software based playback using xine
live (0.2.0) - Live Interactive VDR Environment
femon (1.7.8) - DVB Signal Information Monitor (OSD)
channellists (0.0.4) - Manage your channellists
menuorg (0.4.4) - Reorganizes the main menu
extrecmenu (1.3-beta1) - Extended recordings menu
xineliboutput (1.0.90-cvs) - X11/xine-lib output plugin

Alles anzeigen

steffen_b · 25. November 2010

Merkwürdig ....

Inhalt von "${PLUGINS[@]}" ist womöglich leer, wird in /usr/lib/vdr/plugin-loader gefüllt, evtl dan mal ein paat debugmessages reintun ...

hsteinhaus · 30. November 2010

Kurzer Zwischenbericht:

bis heute kein einziger Plugin-freier Start, weder mit Test-Skript (103 automatische WOL-Starts mit anschließendem SVDRP-Send "hitk power") noch im Nomalbetrieb.

Der Testskript hat jedoch einen anderen Fehler zu Tage gebracht - offenbar in den Ubuntu-Startskripten: trotz fstab-Eintrag mit bootwait-Option war in seltenen Fällen die /srv/video.00-Partition beim VDR-Start nicht gemounted. Das führte jedoch zu einem völlig anderen Fehlerbild als dem weiter oben im Thread beschriebenen und war somit nicht die Ursache für das Problem.

Grüße,
Holger

[0.3] vdr startet manchmal komplett ohne plugins

Jetzt mitmachen!

Teilen