TVHeadend jede Stunde Verbindungsverlust und reconnect

[Kamerad_Schnürschuh]

Hallo!
Ein Thema ist wieder in den Fokus gerückt. Ich habe ein Problem mit wiederkehrenden Verbindungsabbrüchen. Jede Stunde verliert TVH die Verbindung. Das führt dazu, dass Aufnahmen, die über die Zeitmarke xx:47 gehen, abbrechen und eine neue Aufnahme begonnen wird. Aufnahmen außerhalb xx:47 sind fehlerfrei!
Das Problem bestand nicht immer, ist aber zu einem Zeitpunkt aufgetaucht, dem ich keine Änderung im System zuordnen kann.

Was habe ich derweil versucht:
- Neuer User
- TVH Update auf 4.3
- Tritt sowohl bei Privaten als auch ÖR auf
- Wechsel auf Easymouse und Oscam
- Größe der Aufnahme spielt auch keine Rolle

Finde im System nichts, was diese stündlichen Fehler triggert.
Ideen willkommen!

 

[Gunah]

Welchen Rechner hast du dahinter?
welchen Stick nutzt du?
Was sagt TVH zu den Signalqualität?

 

[Kamerad_Schnürschuh]

Aloha,
Signal kommt über DVB-C ->Vodafone. Signalqualität ist laut Fritzbox "gut".
THV läuft auf einem Unraid-Server.
HD Signale werden über Easymouse&Oscam bereit gestellt. ->Der Fehler lag aber schon vor dem Wechsel auf die Easymouse vor und zeigte sich auch bei den SD Programmen.

 

[Gunah]

Welchen Rechner hast du dahinter?
welchen Stick nutzt du?
Was sagt TVH zu den Signalqualität?

^Die Frage nochmal beantworten^

Signal kommt über DVB-C ->Vodafone. Signalqualität ist laut Fritzbox "gut".

via DVB-C kommt schon von den Screenshots her raus - oder nutzt du die Tuner von der Fritte?

 

[Kamerad_Schnürschuh]

Moin,
verstehe Deine Fragen nicht so recht.
Was ist mit "welchen Rechner hast Du dahinter" gemeint? TVH läuft auf einem UR Server. Als Clients sind es Raspi oder FireTv Sticks.
Ich nutze keinen Stick (ok, abseits des FVTV ).
Wo sehe ich in TVH die Signalqualität? ->Aufgrund des stündlichen Systems erscheint das aber nicht so plausibel, doer?
Ja, die Signale kommen über die 4 Tuner der Fritte und werden dann auf der Easymouse (via USB am Server) ggf. entschlüsselt via dvbapi in TVH gedengelt.

 

[dewildschwein500]

Hi @Kamerad_Schnürschuh,
viele Anhaltspunkte hab' ich noch nicht ... lediglich, dass die Aufnahmen (Daten-)fehler enthalten, die Aufnahme in der 47. Minute gestoppt wird und der Browser die Verbindung zum TVH in dem Moment verliert.

Die Aufnahme wird ja mehr oder weniger direkt wieder gestartet ... kannst du einschätzen wie viele Sekunden/Minuten zwischen dem Stop und Start fehlen; sind es wenige Sekunden oder mehrere Minuten?

Du kannst auch mal prüfen ob irgendwas stündlich per Cron in der 47. Minute ausgeführt wird.

 

[Kamerad_Schnürschuh]

Moin @dewildschwein500,
ich habe gerade mal LiveTv via dem THV Client in Libreelec geschaut und da war das Signal weg und ich wurde in die Programmübersicht zurück geworfen. Ich konnte aber direkt nach ein paar Sekunden weiter schauen. Ich habe in TVH schon wild rumgesucht und nichts gefunden, was zu dem "krummen" Zeitpunkt ausgeführt wird. Am Unraid Server scheint es nicht zu liegen, da Filme, die ich über Jellyfin schaue, nicht unterbrochen werden und auch Datentransfers über die 47er Zeitmarke durchlaufen.

Gerade nochmal alle DockerLogs durchschaut, ob in Min. 47 etwas regelmäßig ausgeführt wird. Leider nix gefunden...*schulterzuck*

 

[dewildschwein500]

Hi,
Jellyfin greift wo das SAT-Signal oder den Stream ab?
kannst du über eine Prozessliste erkennen, ob der tvheadend Prozess jedesmal in der 47sten Minute neu gestartet wird ... bzw. wenn TVH in einem Docker Container läuft, ob der Container jedes mal neu gestartet wird.

Startzeit ermitteln: Z.B. ps -C tvheadend -o lstart

 

[Kamerad_Schnürschuh]

Moin @dewildschwein500,
das mit Jellyfin war vielleicht mißverständlich. Gemeint war, wenn ich einen Film von der Platte an den JF Client streame. Wollte damit nur sagen, dass der Server nicht seine Verbindung ins Netz verliert. Ansonsten greife ich auf den von TVH bereit gestellten Stream mit dem THV Client für Libreelec ab.
Sieht so aus, als würde der Container neu gestartet werden:

Spoiler: TVH Docker Log

2022-01-24 18:47:01.637 [ INFO] mpegts: 546MHz in Unitymedia (0x14ad4827a0e0) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 538MHz in Unitymedia (0x14ad4827bb50) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 522MHz in Unitymedia (0x14ad4827b610) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 530MHz in Unitymedia (0x14ad4827b0d0) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 674MHz in Unitymedia (0x14ad4837fb40) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 418MHz in Unitymedia (0x14ad4837f600) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 121MHz in Unitymedia (0x14ad4837f0c0) - deleting
2022-01-24 18:47:01.637 [ INFO] mpegts: 698MHz in Unitymedia (0x14ad49aa5aa0) - deleting
2022-01-24 18:47:01.638 [ INFO] mpegts: 562MHz in Unitymedia (0x14ad49aa5580) - deleting
2022-01-24 18:47:01.640 [ INFO] capmt: J inactive
2022-01-24 18:47:01.640 [ INFO] capmt: J: mode 5 IP address 192.168.0.99 port 9000 destroyed
2022-01-24 18:47:01.659 [ INFO] epgdb: save start
2022-01-24 18:47:01.751 [ INFO] epgdb: stored (size 974050)
2022-01-24 18:47:01.752 [ INFO] codec: removing codec profiles
2022-01-24 18:47:01.752 [ INFO] codec: forgetting codecs
2022-01-24 18:47:01.752 [ INFO] transcode: forgetting context helpers
2022-01-24 18:47:01.752 [ INFO] transcode: forgetting context types
2022-01-24 18:47:02.070 [ NOTICE] STOP: Exiting HTS Tvheadend
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] 01-envfile: executing...
[cont-init.d] 01-envfile: exited 0.
[cont-init.d] 01-migrations: executing...
[migrations] started
[migrations] no migrations found
[cont-init.d] 01-migrations: exited 0.
[cont-init.d] 10-adduser: executing...
usermod: no changes

-------------------------------------
_ ()
| | ___ _ __
| | / __| | | / \
| | \__ \ | | | () |
|_| |___/ |_| \__/


Brought to you by linuxserver.io
-------------------------------------

To support LSIO projects visit:

Sie müssen registriert sein, um Links zu sehen.

-------------------------------------
GID/UID
-------------------------------------

User uid: 99
User gid: 100
-------------------------------------

[cont-init.d] 10-adduser: exited 0.
[cont-init.d] 30-config: executing...
Setting permissions
[cont-init.d] 30-config: exited 0.
[cont-init.d] 50-gid-video: executing...
[cont-init.d] 50-gid-video: exited 0.
[cont-init.d] 90-custom-folders: executing...
[cont-init.d] 90-custom-folders: exited 0.
[cont-init.d] 99-custom-files: executing...
[custom-init] no custom files found exiting...
[cont-init.d] 99-custom-files: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
2022-01-24 18:47:11.552 [ INFO] main: Log started
2022-01-24 18:47:11.554 [ INFO] http: Starting HTTP server 0.0.0.0:9981
2022-01-24 18:47:11.554 [ INFO] htsp: Starting HTSP server 0.0.0.0:9982
2022-01-24 18:47:11.554 [ ERROR] satips: use --satip_bindaddr parameter to select the local IP for SAT>IP
2022-01-24 18:47:11.554 [ ERROR] satips: using Google lookup (might block the task until timeout)
2022-01-24 18:47:11.575 [ INFO] satips: Starting SAT>IP RTSP server 192.168.0.125:9983
2022-01-24 18:47:11.575 [ INFO] satips: SAT>IP Server initialized
2022-01-24 18:47:11.575 [ INFO] satips: HTTP 192.168.0.125:9981, RTSP 192.168.0.125:9983
2022-01-24 18:47:11.575 [ INFO] satips: descramble 1, muxcnf 0
2022-01-24 18:47:11.575 [ INFO] satips: tuner[fe=1]: DVB-C #4
2022-01-24 18:47:11.575 [ INFO] satips: tuner[fe=2]: DVB-C #4
2022-01-24 18:47:11.575 [ INFO] satips: tuner[fe=3]: DVB-C #4
2022-01-24 18:47:11.575 [ INFO] satips: tuner[fe=4]: DVB-C #4
2022-01-24 18:47:11.596 [ INFO] config: loaded
2022-01-24 18:47:11.597 [ INFO] config: scanfile (re)initialization with path <none>
2022-01-24 18:47:11.597 [ INFO] transcode: 'video' context type registered
2022-01-24 18:47:11.597 [ INFO] transcode: 'audio' context type registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHH264Decoder' decoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHTHEORADecoder' decoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHAACDecoder' decoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHVORBISDecoder' decoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHOPUSDecoder' decoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHMPEG2VIDEOEncoder' encoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHH264Encoder' encoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHHEVCEncoder' encoder helper registered
2022-01-24 18:47:11.597 [ INFO] transcode: '&TVHAACEncoder' encoder helper registered
2022-01-24 18:47:11.597 [ INFO] codec: 'mpeg2video' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'mp2' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'aac' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'vorbis' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'flac' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libx264' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libx265' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libvpx' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libvpx-vp9' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libtheora' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libvorbis' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'libopus' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'h264_vaapi' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'hevc_vaapi' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'vp8_vaapi' encoder registered
2022-01-24 18:47:11.597 [ INFO] codec: 'vp9_vaapi' encoder registered
2022-01-24 18:47:11.600 [ INFO] codec: 'webtv-vp8' codec profile created
2022-01-24 18:47:11.600 [ INFO] codec: 'webtv-h264' codec profile created
2022-01-24 18:47:11.600 [ INFO] codec: 'webtv-vorbis' codec profile created
2022-01-24 18:47:11.600 [ INFO] codec: 'webtv-aac' codec profile created

 

[dewildschwein500]

Hi,
ja, würde ich auch sagen. Der Container scheint aber sauber gestoppt und gestartet zu werden; sprich der stürzt nicht ab und wird dann automatisch "wiederbelebt".
Gibt es irgendwelche Health/Monitoring Skripte/Tools für die Container, die z.B. prüfen ob mit den Containern noch alles Ok oder auf neue Container-Image Versionen prüfen und nicht sauber funktionieren?

 

[Kamerad_Schnürschuh]

Startzeit ermitteln: Z.B. ps -C tvheadend -o lstart

Oscam verliert die Verbindung 47.01 und danach wird um 47:05 wieder gestartet:
root@Tower:/# ps -C tvheadend -o lstart
STARTED
Mon Jan 24 19:47:05 2022
root@Tower:/#

 

[dewildschwein500]

hi,
dann noch einmal diesen Befehl, um sicher zu gehen, dass der Container neu startet und nicht "nur" der TVHeadend Prozess innerhalb des Containers
docker container ls -a | grep tvh
In der "STATUS"-Spalte bekommt man die Laufzeit des Containers angezeigt.

 

[Kamerad_Schnürschuh]

Hi,
ja, würde ich auch sagen. Der Container scheint aber sauber gestoppt und gestartet zu werden; sprich der stürzt nicht ab und wird dann automatisch "wiederbelebt".
Gibt es irgendwelche Health/Monitoring Skripte/Tools für die Container, die z.B. prüfen ob mit den Containern noch alles Ok oder auf neue Container-Image Versionen prüfen und nicht sauber funktionieren?

Alles unauffällig. Hatte auch schon auf die :latest Version geupdatet, ohne Erfolg. Es ist auch nur TVH, der das Restartverhalten zeigt,

hi,
dann noch einmal diesen Befehl, um sicher zu gehen, dass der Container neu startet und nicht "nur" der TVHeadend Prozess innerhalb des Containers
docker container ls -a | grep tvh
In der "STATUS"-Spalte bekommt man die Laufzeit des Containers angezeigt.

Wenn ich das richtig sehe, scheint es nur der Prozess zu sein:
root@Tower:~# docker container ls -a | grep tvh
8c230adb48ec lscr.io/linuxserver/tvheadend:latest "/init" 26 hours ago Up Less than a second

 

[dewildschwein500]

Hi,
Up Less than a second sollte die Laufzeit des Containers sein.
26 hours ago wurde das Image erstellt.

Führ' den Befehl nochmal aus; scheinst da genau den Restart-Zeitpunkt erwischt zu haben : docker container ls -a | grep tvh

 

[Kamerad_Schnürschuh]

Ja, genau getroffen. Hier mal aktuell:

root@Tower:~# docker container ls -a | grep tvh
8c230adb48ec lscr.io/linuxserver/tvheadend:latest "/init" 38 hours ago Up 22 minutes tvheadend

Die 22 min korrespondieren wieder genau mit der 47 Min Nummer.