vdr-dvbapi: [5222] DVBAPI-Error: Action: read failed unknown command

[Wickie1]

Guten Abend,

erstmal meine Config:

• Hardware: stinknormaler x86-PC
• TV-Backend: vdr (2.3.2) mit vdr-sc als CAM-Modul
• TV-Frontend: Kodi mit vnsi-Server.
• Oscam wird nur als Client benutzt und fragt einen anderen CS-Server ab.

Vorgeschichte und Motivation (wer's lesen will):

Spoiler

Beim (ersten) Start von vdr blockiert der vnsi-Server irgendwie das sc-Plugin. Das hat zur Folge, dass ich beim Aufruf der Pay-TV-Kanäle begrüßt werde mit der Fehlermeldung "Kanal nicht verfügbar". Ich muss dann den VDR neustarten, dann geht's problemlos. Starte ich dann im Laufe des Tages den HTPC neu, klappt's auf Anhieb. Das Problem tritt also immer auf, wenn der PC über viele Stunden (bis zum nächsten Tag) ausgeschaltet wurde.

Irgendwo bin ich mal darauf gestoßen, dass VNSI der Übeltäter ist und das SC-Plugin u.U. beim Laden blockiert. Um das Problem zu lösen/umgehen, müsste man sicherstellen, dass der VNSI-Server erst nach Laden und Initialisierung des SC-Plugins gestartet wird. Aber damit hatte ich bisher keinen Erfolg.

Aus diesem Grund wollte ich mal Oscam mit DVBAPI ausprobieren. Installiert ist vdr-dvbapi-2.2.3-GIT.
Das sc-Plugin hab ich logischerweise deaktiviert und dvbapi aktiviert in der VDR-Config.

Kodi fällt als Problemursache raus, denn ich krieg den Fehler auch, wenn ich xineliboutput als Ausgabeplugin verwende.

Fehlerbeschreibung:
Manchmal kann ich den ersten aufgerufenen PayTV-Kanal mit Oscam/dvbapi entschlüsseln. Spätestens ab dem 2. Umschalten krieg ich die Unmenge an den folgenden Fehlermeldungen. Die Oscam-Config funktioniert nahezu identisch (bis auf den Boxtype: dreambox <-> pc) problemlos auf meiner VU+ Solo.

Deswegen gehe ich davon aus, dass vdr-dvbapi und der Oscam-Server gewisse Verständigungsprobleme haben:

Logfile:

Spoiler

Jan 29 21:52:38 htpc vdr[3685]: [3685] DVBAPI: 0.0 set CAM decrypt (SID 61301 (0xEF75), caLm 4, HasCaDescriptors 0)
Jan 29 21:52:39 htpc vdr[3685]: [3685] DVBAPI: 0.0 set CAM decrypt (SID 61301 (0xEF75), caLm 5, HasCaDescriptors 1)
Jan 29 21:52:40 htpc vdr[3685]: [3685] DVBAPI: 0.0 set CAM decrypt (SID 61301 (0xEF75), caLm 4, HasCaDescriptors 1)
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 002b6f3c
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: ff000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000
Jan 29 21:52:40 htpc vdr[3685]: [3725] DVBAPI-Error: Action: read failed unknown command: 00000000

Die Fehlermeldung müllt dann das Logfile zu.

/etc/vdr/setup.conf (Auszug)

Spoiler

dvbapi.OSCamHost = 127.0.0.1
dvbapi.OSCamPort = 2000

/etc/oscam/oscam.conf

Spoiler

[global]
disablelog        = 1
logfile        = /var/log/oscam/oscam.log
unlockparental        = 1
nice            = -10
maxlogsize        = 100
waitforcards        = 0
preferlocalcards    = 2
dropdups        = 0
usrfile        = /tmp/user.log
cwlogdir        = /var/log/oscam
emmlogdir        = /var/log/oscam
WaitForCards    = 0
#lb_mode        = 0

[dvbapi]
enabled        = 1
au            = 1
user            = dvbapi_local
boxtype        = pc
pmt_mode        = 4
listen_port     = 2000


[webif]
httpallowed        = 172.0.0.1,192.168.109.10-192.168.109.100
httposcamlabel        = Oscam WebInterface

/etc/oscam/oscam.service

Spoiler

[reader]
Label            = SKY_DE
Protocol        = newcamd
Key            = 0102030405060708091011121314
Device            = cs.server.tld,port
user           = user
password       = password
CAID            = 098C
IDENT            = 000000
Fallback        = 0
Group            = 1
ReconnectTimeout    = 20
InactivityTimeOut    = 15

/etc/oscam/oscam.user

Spoiler

[account]
user           = dvbapi_local
au           = 1
group           = 1

/etc/oscam/oscam.dvbapi

Spoiler

P:098C
I:0

 

[Hunter113]

Ähnliches Problem und Fehlermeldung bei mir. Hast du eine Lösung gefunden.

 

[Wickie1]

Ja, hab ich. Ist aber nicht die Lösung, die ich eigentlich wollte.

Etwas weiter ausgeholt:

Spoiler

Das eigentliche Problem zwischen VNSI-Server und VDR-SC lag nicht am VNSI. Vielmehr hat das SC-Plugin oder der VDR selbst keine Prüfung drin, ob das Netzwerk schon verfügbar ist. Systemd funktioniert nach dem Prinzip, dass alle Service in größtenteils unbestimmter Reihenfolge pseudoparallel starten. Entsprechend war das Netzwerk eben noch nicht da. Und damit stellte sich das SC-Plugin quer und blockierte alle Kanäle. Ich hab dann beim Start des VDR eine Prüfung eingebaut, die eben genau gewährleistet, dass der VDR schon da ist. Im einzelnen sind das:

/etc/systemd/system/vdr.service.d/01_wait_for_devices.conf

[Unit]
Wants=dev-dvb-adapter0-demux0.device
After=dev-dvb-adapter0-demux0.device
Wants=dev-dvb-adapter0-dvr0.device
After=dev-dvb-adapter0-dvr0.device
Wants=dev-dvb-adapter0-frontend0.device
After=dev-dvb-adapter0-frontend0.device
Wants=dev-dvb-adapter1-demux0.device
After=dev-dvb-adapter1-demux0.device
Wants=dev-dvb-adapter1-dvr0.device
After=dev-dvb-adapter1-dvr0.device
Wants=dev-dvb-adapter1-frontend0.device
After=dev-dvb-adapter1-frontend0.device

(hab 'ne Dualtuner-Karte. Bei Single-Tuner muss alles mit adapter1 raus.)

/etc/systemd/system/vdr.service.d/02_wait_for_network.conf

[Unit]
Requires=systemd-networkd.socket
After=systemd-networkd.socket

[Service]
ExecStartPre=/usr/lib/systemd/systemd-networkd-wait-online

Damit meckert dann Kodi beim Start immer, dass der VDR noch nicht verfügbar ist. Allerdings hab ich dadurch das Problem vollständig beseitigen können.

Kurz und knapp, ich hab dvbapi und Oscam erstmal wieder runtergeschmissen und bin zurück auf vdr-sc. Es funktioniert tadellos und kann für meine Zwecke mehr als die wesentlich aufwendigere Kombination aus Oscam+dvbapi. Speziell kann vdr-sc auch Softcam.key, wofür ich den Oscam erst patchen müsste, auch wenn man mit der Softcam nur noch SRF hell bekommt.