[suspended][vtuner-ng] Probleme mit verschlüsselten Kanälen/Programmen via streamdev Plugin

ciax · 23. April 2024

Hallo,

seit Einsatz von vtuner von Joe_D für "sat>ip" funktioniert das Streamen via streamdev plugin nicht mehr. Ganz kurz kommt Bild+Ton/nur Ton, ein Zucken - ab und zu funktioniert ein Sender mal zwischendurch, geht um ORF, ServusTV, ..

Dunkel kann ich mich erinnern, dass es schon mal ohne Probleme funktioniert hat.

Im Einsatz ist yavdr/22.04 mit einem dkms, das seahawk (dankenswerterweise! ) mal eingebaut hat

Code

ii  vtuner-ng-dkms                                   0.0.1+git20240208-0yavdr0~jammy                  all          DKMS source for the vtuner-ng kernel module
ii  vtuner-ng-satip                                  0.0.1+git20240208-0yavdr0~jammy                  all          the satip binary to control the vtunerc kernel

Hier ein Logauszug beim Tunen - von einem unverschlüsselten Kanal auf einen verschlüsselten in streamdev:

Code

Apr 23 20:51:42 vdr vdr[1150]: [2054] ERROR: read from client (HTTP) 192.168.xx.149:65242 failed: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt
Apr 23 20:51:43 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,1936): /dev/dvb/adapter0/demux0: Zu viele offene Dateien
Apr 23 20:51:43 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,2191): Zu viele offene Dateien
Apr 23 20:51:43 vdr vdr[1150]: [1488] ERROR: can't set PID 1925 on device 1
Apr 23 20:51:43 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,2206): Ungültiger Dateideskriptor
Apr 23 20:51:45 vdr vdr[1150]: [12281] streamdev-server: streamer done - writer exiting

Auf ATV-HD geht's immer .. Puls4 auch .. ?

Zu ServusTV:

Code

Apr 23 20:55:45 vdr vdr[1150]: [2054] XXXAPI: 0.0 set CAM decrypt (SID 4913 (0x1331), caLm 4, HasCaDescriptors 1)
Apr 23 20:55:46 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,1936): /dev/dvb/adapter0/demux0: Zu viele offene Dateien
Apr 23 20:55:46 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,2191): Zu viele offene Dateien
Apr 23 20:55:46 vdr vdr[1150]: [1488] ERROR: can't set PID 3588 on device 1
Apr 23 20:55:46 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,2206): Ungültiger Dateideskriptor
Apr 23 20:55:48 vdr vdr[1150]: [12347] streamdev-server: streamer done - writer exiting

Hier ein Zugriff auf "Welt" - dieser Fehler kommt immer, aber es funktioniert, keine weiteren Einträge:

Code

Apr 23 21:01:11 vdr vdr[1150]: [2054] ERROR: read from client (HTTP) 192.168.35.149:65300 failed: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt

Dann zurück auf ORF2 und es funktioniert wieder, Bild in Streamdev/vdrzapper da (allerdings bei Werbung/unverschlüsselt?):

Code

Apr 23 21:02:42 vdr vdr[1150]: [2054] ERROR: read from client (HTTP) 192.168.35.149:65301 failed: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt
Apr 23 21:02:42 vdr vdr[1150]: [2054] XXXAPI: 0.0 set CAM decrypt (SID 4912 (0x1330), caLm 4, HasCaDescriptors 1)
Apr 23 21:02:44 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,1936): /dev/dvb/adapter0/demux0: Zu viele offene Dateien
Apr 23 21:02:44 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,2191): Zu viele offene Dateien
Apr 23 21:02:44 vdr vdr[1150]: [1488] ERROR: can't set PID 2925 on device 1
Apr 23 21:02:44 vdr vdr[1150]: [1488] ERROR (dvbdevice.c,2206): Ungültiger Dateideskriptor
Apr 23 21:02:45 vdr vdr[1150]: [1150] XXXAPI: 0.0 set CAM decrypt (SID 4912 (0x1330), caLm 4, HasCaDescriptors 1)
Apr 23 21:02:45 vdr vdr[1150]: [1488] ERROR: can't open filter handle on '/dev/dvb/adapter0/demux0'
Apr 23 21:02:45 vdr vdr[1150]: [1488] ERROR: can't open filter handle on '/dev/dvb/adapter0/demux0'
Apr 23 21:02:46 vdr vdr[1150]: [1488] ERROR: can't open filter handle on '/dev/dvb/adapter0/demux0'
Apr 23 21:03:40 vdr vdr[1150]: video:  4:05:05.490  +49  551   0/\ms  42+1+4 v-buf

Alles anzeigen

komisch .. ab und zu funktioniert's beim "heftigen" Herumzappen dann zwischendurch doch - meistens aber nicht

Vllt. hat ja jemand eine Idee ..

Grusz!

seahawk1986 · 24. April 2024

Zitat von ciax

Zu viele offene Dateien

Du könntest mal (als root) schauen, welcher Prozess da so viele Datei-Handles offen hat:

Code

find /proc -maxdepth 1 -type d -name '[0-9]*'      -exec bash -c "ls {}/fd/ | wc -l | tr '\n' ' '" \;      -printf "fds (PID = %P), command: "      -exec bash -c "tr '\0' ' ' < {}/cmdline" \;      -exec echo \; | sort -rn | head

Wenn das der VDR ist, dann gibt es vermutlich ein Problem beim Schließen der Datei-Handles im Fehlerfall - da könnte man mal mit strace draufsehen, was da genau passiert.

Falls es etwas anderes ist (z.B. sowas wie VS Code, das meint einen Haufen Dateien gleichzeitig beobachten zu müssen), könnte man auch das obere Limit für die file handles im System hochsetzen.

ciax · 24. April 2024

puuh, in welches Sphären du einen führst ..

die file-handles geben leider nicht viel her - bleibt immer ungefähr gleich (ohne streamdev-client verbunden, oder mit). Es ist natürlich schwierig, die "Veränderung" abzufangen. Ich schalte auf einen Sender und führe so schnell wie möglich im anderen Fenster dein Kommando aus. Mit strace könnte man's "live" mitschauen (?) - hatte das vor 15 Jahren (ein)mal probiert bei irgendwas ..

Ausgabe sieht so aus:

Code

ciax@vdr:~$ sudo find /proc -maxdepth 1 -type d -name '[0-9]*'      -exec bash -c "ls {}/fd/ | wc -l | tr '\n' ' '" \;      -printf "fds (PID = %P), command: "      -exec bash -c "tr '\0' ' ' < {}/cmdline" \;      -exec echo \; | sort -rn | head
286 fds (PID = 1177), command: /usr/sbin/netdata -D -P /var/run/netdata/netdata.pid
126 fds (PID = 1), command: /sbin/init splash
124 fds (PID = 1150), command: /usr/bin/vdr
92 fds (PID = 1793), command: /usr/libexec/netdata/plugins.d/ebpf.plugin 1
79 fds (PID = 2101), command: python3 /opt/bin/kivy-osd2web/vdr_status_display.py -a
66 fds (PID = 358), command: /lib/systemd/systemd-journald
56 fds (PID = 793), command: /usr/lib/xorg/Xorg -noreset vt7
50 fds (PID = 1212), command: /usr/sbin/smbd --foreground --no-process-group
47 fds (PID = 3155), command: /usr/sbin/smbd --foreground --no-process-group
46 fds (PID = 9739), command: journalctl -f

Alles anzeigen

Danke für deine Gedanken! Ist nicht so wichtig, komisch ist's aber ...

seahawk1986 · 24. April 2024

Du kannst dem VDR ja mal versuchsweise ein höheres Limit mitgeben (das standardmäßige softlimit ist 1024), und schauen, ob sich an den Logmeldungen des VDR etwas ändert:

Code: /etc/systemd/system/vdr.service.d/open-file-limit.conf

[Service]
LimitNOFILE=8192

Danach die Unit neu einlesen und den VDR neu starten:

Code

sudo systemctl daemon-reload
sudo systemctl restart vdr

ciax · 24. April 2024

Danke, ich hab's so eingebaut - gefühlt ist es besser geworden und die Kanäle werden öfter hell - ServusTV ist am schlechtesten. Das Log hat sich dahingehend verändert, dass nun oft diese Zeile erscheint und dann ein Bild da ist:

Code

Apr 24 23:33:54 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'

also so bei Wechsel auf einen verschlüsselten Kanal:

Code

Apr 24 23:33:52 vdr vdr[31502]: [31693] ERROR: read from client (HTTP) 192.168.35.149:51481 failed: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt
Apr 24 23:33:52 vdr vdr[31502]: [31883] ERROR: streamdev-server: couldn't send data: Ungültiger Dateideskriptor
Apr 24 23:33:52 vdr vdr[31502]: [31693] DVBXXX: 1.1 set CAM decrypt (SID 4911 (0x132F), caLm 4, HasCaDescriptors 1)
Apr 24 23:33:54 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'
Apr 24 23:33:54 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'
Apr 24 23:33:55 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'
Apr 24 23:33:56 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'
Apr 24 23:33:57 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'
Apr 24 23:33:59 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'
Apr 24 23:34:00 vdr vdr[31502]: [31509] ERROR: can't open filter handle on '/dev/dvb/adapter1/demux0'

kein Bild kommt, wenn die letzten Zeilen im Log wie folgend aussehen:

Code

[..]
Apr 24 23:34:05 vdr vdr[31502]: [31693] DVBXXX: 1.1 set CAM decrypt (SID 4913 (0x1331), caLm 4, HasCaDescriptors 1)
Apr 24 23:34:06 vdr vdr[31502]: [31509] ERROR (dvbdevice.c,1936): /dev/dvb/adapter1/demux0: Zu viele offene Dateien
Apr 24 23:34:06 vdr vdr[31502]: [31509] ERROR (dvbdevice.c,2191): Zu viele offene Dateien
Apr 24 23:34:06 vdr vdr[31502]: [31509] ERROR: can't set PID 18 on device 2
Apr 24 23:34:06 vdr vdr[31502]: [31509] ERROR (dvbdevice.c,2206): Ungültiger Dateideskriptor
Apr 24 23:34:08 vdr vdr[31502]: [31896] streamdev-server: streamer done - writer exiting

ciax · 24. April 2024

//edit: nein, hat leider doch nocht so viel gebracht - einmal geht's dann nicht mehr .. sorry

.. hab das Limit

Code

LimitNOFILE=8192

auf "32768" erhöht und nun geht' s noch besser (eventuell nur ein Gefühl am späten Abend) - immer klappt es aber nicht.

seahawk1986 · 25. April 2024

Zitat von ciax

//edit: nein, hat leider doch nocht so viel gebracht - einmal geht's dann nicht mehr .. sorry

Sieht man denn ein massives Anwachsen der file-Handles für den VDR-Prozess, beim Umschalten?

strace sollte das z.B. so loggen können, wenn man es als root ausführt:

Code

strace -t -e trace=open,openat,close,read,getdents,write,connect,accept -p $(pidof vdr)

ciax · 25. April 2024

.. Spaß macht's schon wieder, wenn man so ein bißchen "debuggen" darf - leider schon zu "alt".

strace hängt sich an den Prozess vdr dran, gibt beim Kanalwechsel (am streamdev-client) leider nichts Auffälliges aus:

Kanalwechsel von unverschüsselt auf unverschlüsselt:

Code

22:35:48 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:35:48 close(85)                      = 0
22:35:48 close(90)                      = 0
22:35:48 close(91)                      = 0
22:35:48 close(92)                      = 0
22:35:48 close(93)                      = 0
22:35:48 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:35:48 close(105)                     = 0
22:35:48 close(108)                     = 0
22:35:48 close(109)                     = 0
22:35:48 close(110)                     = 0
22:35:48 close(112)                     = 0
22:35:48 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:35:50 write(13, "\1\0\0\0\0\0\0\0", 8) = 8

Alles anzeigen

.. von unverschüsselt auf verschlüsselt

Code

22:36:09 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:36:09 close(117)                     = 0
22:36:09 close(125)                     = 0
22:36:09 close(126)                     = 0
22:36:09 close(127)                     = 0
22:36:09 close(128)                     = 0
22:36:09 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:36:09 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:36:09 close(131)                     = 0
22:36:09 close(132)                     = 0
22:36:09 close(133)                     = 0
22:36:09 close(134)                     = 0
22:36:09 close(135)                     = 0
22:36:09 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:36:09 write(13, "\1\0\0\0\0\0\0\0", 8) = 8
22:36:09 close(85)                      = 0
22:36:09 close(90)                      = 0
22:36:09 close(91)                      = 0
22:36:09 close(92)                      = 0
22:36:09 close(93)                      = 0
22:36:09 write(13, "\1\0\0\0\0\0\0\0", 8) = 8

Alles anzeigen

.. mit syslog/journal Eintrag

Code

Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR: can't open filter handle on '/dev/dvb/adapter0/demux0'
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR: can't open filter handle on '/dev/dvb/adapter0/demux0'
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR (dvbdevice.c,1936): /dev/dvb/adapter0/demux0: Zu viele offene Dateien
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR (dvbdevice.c,2191): Zu viele offene Dateien
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR: can't set PID 109 on device 1
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR (dvbdevice.c,2206): Ungültiger Dateideskriptor
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR (dvbdevice.c,1936): /dev/dvb/adapter0/demux0: Zu viele offene Dateien
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR (dvbdevice.c,2191): Zu viele offene Dateien
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR: can't set PID 3584 on device 1
Apr 25 22:36:09 vdr vdr[1085]: [1414] ERROR (dvbdevice.c,2206): Ungültiger Dateideskriptor
Apr 25 22:36:11 vdr vdr[1085]: [3060] streamdev-server: streamer done - writer exiting
Apr 25 22:36:12 vdr kernel: perf: interrupt took too long (3939 > 3932), lowering kernel.perf_event_max_sample_rate to 50750

Alles anzeigen

Egal, vergiss es - könnte noch probieren, eine "ältere Version" vom vtuner Kernel Module manuell zu übersetzen, aber könnte ja ganz woanders liegen ..

[suspended][vtuner-ng] Probleme mit verschlüsselten Kanälen/Programmen via streamdev Plugin

ciax 26. April 2024

Jetzt mitmachen!

Teilen

Benutzer online in diesem Thema