[yaVDR ansible] Headless Server stürzt während der Nacht ab

klausb · July 16, 2024 at 3:11 PM

Ich habe auf meinem alten System einen VDR-headless Server (Ubuntu focal) mit Hilfe von yaVDR-ansible aufgesetzt. Zusätzlich noch vnsiserver aktiviert. Play via Kodi-Client, Aufnahmen, etc. alles läuft normal und problemlos jedoch: Irgendwann, wenn die Server nicht mehr genutzt wird, läuft er in einen undefinierten Zustand:

kein Ping und damit natürlich auch
keine Verbindung via ssh mehr möglich
Power ist noch an, jedoch keinen Disk-Aktivität mehr zu sehen
im log gibt es die Meldung kernel: [11159.910200] forcedeth 0000:00:0a.0 enp0s10: link down(up). Das hat offensichtlich etwas mit "nForce Ethernet support" zu tun, der auch in dmesg auftaucht forcedeth: Reverse Engineered nForce ethernet driver. Version 0.64., jedoch habe ich keine Ahnung, was dort zu tun ist
auch der nouveau Treiber wird initialisiert, da aber weder Tastatur, noch Display angeschlossen ist, sollte das doch keinen Einfluss haben.

Bisher hilft nur noch Hard-Reset.

Konfiguration:

Code

klaus@vdr:~$ vdrctl
PLUGIN                STATE     PRIORITY
dbus2vdr              enabled   50
devstatus             enabled   50
markad                enabled   50
vdr                   static    00
vdr-charset-override  static    02
vdr-hide-first-recordinglevelstatic    04
vdr-instance          static    05
vdr-recordings        static    06
vnsiserver            enabled   50
xineliboutput         disabled  50

Display More

Ausschnitte aus dem syslog

Code

Jul 15 23:40:28 vdr kernel: [11159.910200] forcedeth 0000:00:0a.0 enp0s10: link down
Jul 15 23:40:28 vdr systemd-networkd[457]: enp0s10: Lost carrier
Jul 15 23:40:28 vdr avahi-linker[888]: 2024-07-15 23:40:28,875 INFO   service removed: 2 0 Recordings on vdr _nfs._tcp local 4
Jul 15 23:40:28 vdr avahi-linker[888]: 2024-07-15 23:40:28,876 INFO   service removed: 2 0 Pictures on vdr _nfs._tcp local 4
Jul 15 23:40:28 vdr avahi-linker[888]: 2024-07-15 23:40:28,876 INFO   service removed: 2 0 Files on vdr _nfs._tcp local 4
Jul 15 23:40:28 vdr avahi-linker[888]: 2024-07-15 23:40:28,876 INFO   service removed: 2 0 Backups on vdr _nfs._tcp local 4
Jul 15 23:40:28 vdr avahi-linker[888]: 2024-07-15 23:40:28,876 INFO   service removed: 2 0 Audio on vdr _nfs._tcp local 4
Jul 15 23:40:28 vdr avahi-linker[888]: 2024-07-15 23:40:28,876 INFO   service removed: 2 0 Video on vdr _nfs._tcp local 4
Jul 15 23:40:28 vdr avahi-daemon[715]: Withdrawing address record for 192.168.10.62 on enp0s10.
Jul 15 23:40:28 vdr systemd-networkd[457]: enp0s10: DHCP lease lost
Jul 15 23:40:28 vdr avahi-daemon[715]: Leaving mDNS multicast group on interface enp0s10.IPv4 with address 192.168.10.62.
Jul 15 23:40:28 vdr avahi-daemon[715]: Interface enp0s10.IPv4 no longer relevant for mDNS.
Jul 15 23:40:30 vdr kernel: [11161.977624] forcedeth 0000:00:0a.0 enp0s10: link up
Jul 15 23:40:30 vdr systemd-networkd[457]: enp0s10: Gained carrier
Jul 15 23:40:30 vdr systemd-timesyncd[673]: Network configuration changed, trying to establish connection.
Jul 15 23:40:38 vdr systemd-networkd[457]: enp0s10: DHCPv4 address 192.168.10.62/24 via 192.168.10.1
Jul 15 23:40:38 vdr systemd-timesyncd[673]: Network configuration changed, trying to establish connection.
Jul 15 23:40:38 vdr avahi-daemon[715]: Joining mDNS multicast group on interface enp0s10.IPv4 with address 192.168.10.62.
Jul 15 23:40:38 vdr avahi-daemon[715]: New relevant interface enp0s10.IPv4 for mDNS.
Jul 15 23:40:38 vdr avahi-daemon[715]: Registering new address record for 192.168.10.62 on enp0s10.IPv4.
Jul 15 23:40:38 vdr systemd-timesyncd[673]: Initial synchronization to time server 192.168.10.1:123 (192.168.10.1).
Jul 15 23:40:39 vdr avahi-linker[888]: 2024-07-15 23:40:39,019 INFO   skip local service 'Video on vdr' type '_nfs._tcp' domain 'local'
Jul 15 23:40:39 vdr avahi-linker[888]: 2024-07-15 23:40:39,019 INFO   skip local service 'Audio on vdr' type '_nfs._tcp' domain 'local'
Jul 15 23:40:39 vdr avahi-linker[888]: 2024-07-15 23:40:39,020 INFO   skip local service 'Backups on vdr' type '_nfs._tcp' domain 'local'
Jul 15 23:40:39 vdr avahi-linker[888]: 2024-07-15 23:40:39,020 INFO   skip local service 'Files on vdr' type '_nfs._tcp' domain 'local'
Jul 15 23:40:39 vdr avahi-linker[888]: 2024-07-15 23:40:39,020 INFO   skip local service 'Pictures on vdr' type '_nfs._tcp' domain 'local'
Jul 15 23:40:39 vdr avahi-linker[888]: 2024-07-15 23:40:39,021 INFO   skip local service 'Recordings on vdr' type '_nfs._tcp' domain 'local'
Jul 15 23:42:21 vdr canonical-livepatch.canonical-livepatchd[787]: Client information is recent, not refreshing.
Jul 15 23:42:40 vdr vdr: [1067] changing pids of channel 211 (HISTORY Channel) from 1023+1023=27:1027=deu@3,1028=eng@3:0:0 to 1023+1023=27:1027=deu@3:0:0
Jul 15 23:42:40 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
------------------------------------------------------------------------------------
hier eine Reihe von "NUL" Einträgen --> Keine Reaktion mehr
---------------------------------------------------------------------
<RESET> -- Neustart                                                                                                                                                                                                                             Jul 16 14:33:15 vdr systemd[1]: Mounted Huge Pages File System.
... normaler Start ...

Display More

Wo kann ich weiter suchen?

was kann ich noch alles deaktivieren?
außer vnsi/ssh/scp Zugriff benötige ich an sich nichts

vdr_rossi · July 16, 2024 at 3:52 PM

Onboard Netzwerkkarte deaktivieren und PCI/PCIe Netzwerkkarte (am besten Intel) verwenden.

Alternativ USB Netzwerkkarte.

Realtek bashing spare ich mir.

wirbel · July 16, 2024 at 4:45 PM

Die Zeilen vor deiner Zeile #1 wären interessant.

klausb · July 16, 2024 at 5:00 PM

Quote from wirbel

Die Zeilen vor deiner Zeile #1 wären interessant.

Hier sind sie. Sahen für mich nicht wirklich verdächtig aus. Interessant ist, dass die link down / up Meldung mehrfach währen des Betriebs auftaucht, die Kiste aber erst während der Nacht ohne laufenden Client abschmiert.

Übrigens habe ich jetzt vorsichtshalber mal das Kabel getauscht

Code

Jul 15 23:24:08 vdr vdr: [2323] VNSI: exit streamer thread
Jul 15 23:24:08 vdr vdr: [2323] cLiveStreamer stream processor thread ended (pid=902, tid=2323)
Jul 15 23:24:08 vdr vdr: [2320] VNSI: LiveStreamer::Close - close
Jul 15 23:24:08 vdr vdr: [2320] VNSI: close video input ...
Jul 15 23:24:08 vdr vdr: [2320] VNSI: activate live receiver: 0
Jul 15 23:24:08 vdr vdr: [2320] VNSI: Dummy receiver (0x7f18ec000ad0) deactivated
Jul 15 23:24:08 vdr vdr: [2320] VNSI: close video input ...
Jul 15 23:24:08 vdr vdr: [2320] VNSI: cxSocket::read(fd=4): eof, connection closed
Jul 15 23:24:08 vdr vdr: [2320] VNSI Client 14->192.168.10.88:56664 thread ended (pid=902, tid=2320)
Jul 15 23:24:08 vdr vdr: [1085] VNSI: removing client with ID 14 from client list
Jul 15 23:24:08 vdr vdr: [2324] device 1 TS buffer thread ended (pid=902, tid=2324)
Jul 15 23:24:08 vdr vdr: [2322] buffer stats: 237632 (1%) used
Jul 15 23:24:08 vdr vdr: [2322] device 1 receiver thread ended (pid=902, tid=2322)
Jul 15 23:24:11 vdr vdr: [1242] VNSI: cxSocket::read(fd=35): eof, connection closed
Jul 15 23:24:11 vdr vdr: [1242] VNSI Client 3->192.168.10.88:57740 thread ended (pid=902, tid=1242)
Jul 15 23:24:11 vdr vdr: [1085] VNSI: removing client with ID 3 from client list
Jul 15 23:26:32 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:27:41 vdr vdr: message repeated 6 times: [ [1085] VNSI: Requesting clients to reload channel list]
Jul 15 23:27:57 vdr vdr: [1067] changing pids of channel 335 (Sky Sport Top Event HD) from 767+767=27:0;770=qae@106,772=qaf@106:33=deu:32 to 767+767=27:0;770=qae@106:33=deu:32
Jul 15 23:27:57 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:28:45 vdr vdr: message repeated 5 times: [ [1085] VNSI: Requesting clients to reload channel list]
Jul 15 23:29:00 vdr vdr: [1067] changing pids of channel 374 (Sky Sport Bundesliga HD) from 255+255=27:0;258=qab@106,260=qac@106:0:32 to 255+255=27:0;258=qab@106:0:32
Jul 15 23:29:00 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:29:00 vdr vdr: [1067] changing pids of channel 383 (Sky Sport UHD) from 0+0=0:0:0:0 to 1535+1535=36:0;1282=qae@122,1539=qaf@122,1540=qtz@122:0:0
Jul 15 23:29:00 vdr vdr: [1067] changing caids of channel 383 (Sky Sport UHD) from 0 to 98E,1854
Jul 15 23:29:05 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:29:47 vdr vdr: message repeated 2 times: [ [1085] VNSI: Requesting clients to reload channel list]
Jul 15 23:30:01 vdr CRON[2414]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
Jul 15 23:31:14 vdr systemd[1]: Started Run anacron jobs.
Jul 15 23:31:14 vdr anacron[2427]: Anacron 2.3 started on 2024-07-15
Jul 15 23:31:14 vdr anacron[2427]: Normal exit (0 jobs run)
Jul 15 23:31:14 vdr systemd[1]: anacron.service: Succeeded.
Jul 15 23:32:30 vdr vdr: [1067] changing pids of channel 201 (Sky Sport F1 HD) from 2047+2047=27:0;2050=qae@106:0:32 to 2047+2047=27:0;2050=qae@106,2052=qaf@106:0:32
Jul 15 23:32:30 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:32:51 vdr vdr: [1067] changing pids of channel 215 (Sky Replay HD) from 1791+1791=27:0;1795=deu@106:0:0 to 1791+1791=27:0;1795=deu@106,1796=eng@106:0:0
Jul 15 23:32:51 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:32:51 vdr vdr: [1067] changing pids of channel 218 (Sky Sport Mix HD) from 255+255=27:0;258=qae@106,260=qaf@106:0:32 to 255+255=27:0;258=qae@106:0:32
Jul 15 23:32:56 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:35:07 vdr vdr: [2064] recording to '/srv/vdr/video/Angela_Merkel_-_Schicksalsjahre_einer_Kanzlerin/2024-07-15.22.30.1-0.rec/00003.ts'
Jul 15 23:39:09 vdr vdr: [1067] changing pids of channel 376 (Sky Documentaries HD) from 511+511=27:0;515=deu@106,516=eng@106:0:0 to 511+511=27:0;515=deu@106:0:0
Jul 15 23:39:09 vdr vdr: [1085] VNSI: Requesting clients to reload channel list
Jul 15 23:40:28 vdr kernel: [11159.910200] forcedeth 0000:00:0a.0 enp0s10: link down

Display More

Quote from vdr_rossi

Realtek bashing spare ich mir.

Das ist gut, denn es ist ja keine Realtek Karte, sondern Teil des Nvidia Chips.

wirbel · July 16, 2024 at 6:36 PM

Ein uralter nforce-2 Chipsatz?

Auf jeden Fall ein Rentner.

Deine Netzwerkverbindung stirbt, damit sind deine NFS mounts nicht erreichbar.

Timeouts, System hängt, evtl stirbt der VDR Prozess beim Zugriff.

Mit deinem Setup wäre es besser, wenn das Netzwerk stabil läuft.

klausb · July 16, 2024 at 6:56 PM

Quote from wirbel

Mit deinem Setup wäre es besser, wenn das Netzwerk stabil läuft.

Yes Sir! "I'll try my very best"

Quote from wirbel

Auf jeden Fall ein Rentner.

Ja, aber der war eben noch da ... und lebt auch noch.

Übrigens: Kann es sein, dass Kodi beim Shutdown ein Signal an den VDR schickt?

wirbel · July 16, 2024 at 7:26 PM

Hast du evtl eine billige Netzwerkkarte rumzuliegen, die du temporär gegentesten kannst?

seahawk1986 · July 16, 2024 at 7:55 PM

Quote from klausb

was kann ich noch alles deaktivieren?

Du kannst die avahi-linker.service, sowie die vdr-network-monitor.service und vdr-update-monitor.service deaktivieren, wenn du nicht die von anderen yaVDRs angekündigte Freigaben automatisch einbinden lassen willst.

wmautner · July 16, 2024 at 8:28 PM

Muß der vdr-SERVER unbedingt eine DHCP-Lease erhalten? Ja, sicher reserviert nach MAC am Router, aber warum nicht mal dieselbe IP einfach fix am VDR einstellen? Wer weiß, was der Router zu dem Zeitpunkt gerade macht, vielleicht einen internen Reset/"Zwangstrennung" auf Clients weitergereicht und kurz "links down" ...

Es muß nicht unbedingt die Onboard-NIC gewesen sein ...

klausb · July 16, 2024 at 8:30 PM

Quote from wirbel

Hast du evtl eine billige Netzwerkkarte rumzuliegen, die du temporär gegentesten kannst?

Habe ich, aber keinen freien Platz mehr, da ja zwei DVB-C Karten eingebaut sind.

klausb · July 16, 2024 at 8:32 PM

Quote from wmautner

aber warum nicht mal dieselbe IP einfach fix am VDR einstellen?

Ja danke, das werde ich mal versuchen.

Quote from seahawk1986

Du kannst die avahi-linker.service, sowie die vdr-network-monitor.service und vdr-update-monitor.service deaktivieren, wenn du nicht die von anderen yaVDRs angekündigte Freigaben automatisch einbinden lassen willst.

Auch das ist eine gute Idee. Danke!

Übrigens:

vdr-network-monitor.service ist nicht vorhanden
wozu ist der "VDR ACPI power button handling daemon" da? (gerade selbst nachgeschaut)

seahawk1986 · July 16, 2024 at 11:07 PM

Quote from klausb

vdr-network-monitor.service ist nicht vorhanden

Ich habe gerade nochmal nachgesehen, die heißt vdr-net-monitor.service: https://github.com/yavdr/vdr-addo…monitor.service

don-baba · July 18, 2024 at 10:39 AM

Hast du in der setup.conf vom VDR mal die Timeouts ausgestellt?

SVDRPTimeout=0

MinUserInactivity=0

Denn ich meine wenn die MinUserInactivity weiterhin auf 300 gestellt ist, dann verschut der vdr den PC nach 5 Stunden Leerlauf zu beenden.
Wenn noch irgend ein anderer Interrupt dazu kommt könnte dieses "Beenden" unterbrochen werden und das System nicht mehr erreichbar sein.
Sprich, Netzwerk und diverse andere Dienste sind dann down aber der PC ist nicht heruntergefahren...

klausb · July 19, 2024 at 9:11 PM

Quote from don-baba

Hast du in der setup.conf vom VDR mal die Timeouts ausgestellt?
SVDRPTimeout=0
MinUserInactivity=0

Die anderen Timeouts hatte ich schon auf 0 gestellt, nicht jedoch den SVDRPTimeout das muss ich nochmal testen. Denn es ist wirklich so, dass der Rechner problemlos durchläuft, wenn der VDR Prozess nicht läuft. Auch solange der VDR genutzt wird, gibt es keine Probleme.

Die Kiste lässt sich ohne Probleme ganz klassisch herunterfahren. Die Frage bleibt, warum der VDR bei einem Inactivity Timeout den Rechner nicht regulär herunterfährt. Welcher Part ist dafür verantwortlich?

klausb · July 20, 2024 at 10:43 AM

Leider hat alles nichts genutzt Status jetzt:

Ethernet Probleme behoben --> war wohl das Kabel
ein cron-job schreibt alle 5 Minuten eine Nachricht, damit man sieht, bis wann der Rechner normal läuft.
in der setup.conf (s. Anlage) alle Timeouts auf "0" gesetzt
dummydevice aktiviert

Resultat:

läuft VDR, so gibt es schon 38 Minuten nach Start des VDR einen Absturz (Zeitstempel 9:12 - 9:50 im Log)
läuft VDR und wird von einem Client genutzt, so ist alles o.k.
läuft VDR nicht, so gibt es keine Probleme über mehr als 24-Stunden.

In der beiliegende syslog Datei sind wichtige Punkte mit "@@@" zusätzlich markiert

Insgesamt ratlos

rfehr · July 24, 2024 at 11:06 PM

Quote from klausb

Leider hat alles nichts genutzt Status jetzt:
Ethernet Probleme behoben --> war wohl das Kabel
ein cron-job schreibt alle 5 Minuten eine Nachricht, damit man sieht, bis wann der Rechner normal läuft.
in der setup.conf (s. Anlage) alle Timeouts auf "0" gesetzt
dummydevice aktiviert
Resultat:
läuft VDR, so gibt es schon 38 Minuten nach Start des VDR einen Absturz (Zeitstempel 9:12 - 9:50 im Log)
läuft VDR und wird von einem Client genutzt, so ist alles o.k.
läuft VDR nicht, so gibt es keine Probleme über mehr als 24-Stunden.
In der beiliegende syslog Datei sind wichtige Punkte mit "@@@" zusätzlich markiert
Insgesamt ratlos

Das sieht irgendwie danach aus als wenn systemd den vdr beendet.

Jul 20 09:12:33 vdr systemd[1]: Stopping Video Disk Recorder...

Participate now!