merkwürdiges Problem bei zeitgleichen Aufnahmen

[Muschimann]

Hallo,

seit einigen Tagen habe ich das Problem das ich nicht mehrere Aufnahmen zeitgleich machen kann. Ich habe an meinem VDR-Server 2 Sundtek Sticks die ich mit DVB-C betreibe. Wenn ich zwei Aufnahmen von verschiedenen Transpondern machen möchte, dann knallt mir der VDR weg. Im Syslog steht leider nur:

Code: [Auswählen]

Sep 23 19:37:00 ramsdorf-srv01 vdr: [5819] executing '/usr/lib/vdr/vdr-recordingaction before "/media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec"'
Sep 23 19:38:00 ramsdorf-srv01 vdr: [5819] PANIC: watchdog timer expired - exiting!

Messages sagt nur:

Code: [Auswählen]

Sep 23 19:38:01 ramsdorf-srv01 runvdr: restarting VDR

Und im Mediasrv.log steht nix weiter außer dem was da immer steht, also:

Code: [Auswählen]

2010-09-23 18:38:00 [1069] =============
2010-09-23 18:38:00 [1069] Symbol rate: 6899939
2010-09-23 18:38:00 [1069] Constellation: QAM256
2010-09-23 18:38:00 [1069] Mirroring: Mirrored
2010-09-23 18:38:01 [1069] Channel found (786000000)
2010-09-23 18:38:01 [1069] =============

Treiber habe ich den Neuesten, hatte die Probleme jedoch auch mit älteren Versionen.

System ist Ubuntu 10.04 Server mit YaVDR Quellen, also VDR 1.7.15 + EPGSearch + Streamdev-Server - mehr nicht.

Das Problem äußert sich wie folgt, der VDR knallt komplett weg, die erste Aufnahme wird jedoch weiter gemacht und die Zweite nimmt auch irgendwie auch, jedoch nicht brauchbar und in ~ 30 MB Stücken. Wenn die erste Aufnahme beendet ist nimmt er die Zweite (defekte) jedoch anstandslos bis zum Schluß an einem Stück auf.

Zur Verdeutlichung mal ls -lh:

Code: [Auswählen]

-rw-r--r-- 1 vdr vdr  35M 2010-09-23 19:39 00001.ts
-rw-r--r-- 1 vdr vdr  30M 2010-09-23 19:40 00002.ts
-rw-r--r-- 1 vdr vdr  36M 2010-09-23 19:43 00003.ts
-rw-r--r-- 1 vdr vdr  30M 2010-09-23 19:44 00004.ts
-rw-r--r-- 1 vdr vdr  30M 2010-09-23 19:45 00005.ts
-rw-r--r-- 1 vdr vdr  31M 2010-09-23 19:47 00006.ts
-rw-r--r-- 1 vdr vdr  34M 2010-09-23 19:49 00007.ts
-rw-r--r-- 1 vdr vdr  32M 2010-09-23 19:50 00008.ts
-rw-r--r-- 1 vdr vdr  31M 2010-09-23 19:52 00009.ts
-rw-r--r-- 1 vdr vdr  35M 2010-09-23 19:54 00010.ts
-rw-r--r-- 1 vdr vdr  41M 2010-09-23 19:57 00011.ts
-rw-r--r-- 1 vdr vdr  32M 2010-09-23 19:58 00012.ts
-rw-r--r-- 1 vdr vdr  32M 2010-09-23 20:00 00013.ts
-rw-r--r-- 1 vdr vdr  32M 2010-09-23 20:02 00014.ts
-rw-r--r-- 1 vdr vdr  34M 2010-09-23 20:04 00015.ts
-rw-r--r-- 1 vdr vdr  32M 2010-09-23 20:05 00016.ts
-rw-r--r-- 1 vdr vdr  34M 2010-09-23 20:07 00017.ts
-rw-r--r-- 1 vdr vdr  38M 2010-09-23 20:09 00018.ts
-rw-r--r-- 1 vdr vdr  31M 2010-09-23 20:10 00019.ts
-rw-r--r-- 1 vdr vdr  34M 2010-09-23 20:12 00020.ts
-rw-r--r-- 1 vdr vdr  40M 2010-09-23 20:14 00021.ts
-rw-r--r-- 1 vdr vdr 1,9G 2010-09-23 21:05 00022.ts
-rw-r--r-- 1 vdr vdr 792K 2010-09-23 21:05 index
-rw-r--r-- 1 vdr vdr  328 2010-09-23 20:15 info

Hat jemand ne Idee woran das liegen kann? Evtl. ein Tuner defekt!?

[Sundtek]

Sep 23 19:37:00 ramsdorf-srv01 vdr: [5819] executing '/usr/lib/vdr/vdr-recordingaction before "/media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec"'
Sep 23 19:38:00 ramsdorf-srv01 vdr: [5819] PANIC: watchdog timer expired - exiting!

dort sollte noch etwas mehr ueberhalb dieser Nachricht stehen bitte mehr Loginformationen posten oder an kontakt at sundtek de schicken.
Ein aehnliches Thema wurde bereits auf vdr-portal.de behandelt.

[Muschimann]

Also darunter steht eigentlich nur der log das der VDR halt neu startet und das wird dann 'ne Endlosschleife bis ich es mal bemerke und stoppe.

Code: [Auswählen]

Sep 23 19:38:00 ramsdorf-srv01 vdr: [5819] PANIC: watchdog timer expired - exiting!
Sep 23 19:38:01 ramsdorf-srv01 runvdr: restarting VDR
Sep 23 19:38:01 ramsdorf-srv01 kernel: [560853.080490] saa7146: unregister extension 'dvb'.
Sep 23 19:38:03 ramsdorf-srv01 kernel: [560855.463117] saa7146: register extension 'dvb'.
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] cTimeMs: using monotonic clock (resolution is 1 ns)
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] VDR version 1.7.15 started
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] switched to user 'vdr'
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] codeset is 'UTF-8' - known
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] found 26 locales in /usr/share/locale
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading plugin: /usr/lib/vdr/plugins/libvdr-streamdev-server.so.1.7.15
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearch.so.1.7.15
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/setup.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/sources.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/diseqc.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/channels.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/timers.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/commands.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/reccmds.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/svdrphosts.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/keymacros.conf
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7891] video directory scanner thread started (pid=7890, tid=7891)
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7890] reading EPG data from /var/cache/vdr/epg.data
Sep 23 19:38:19 ramsdorf-srv01 vdr: [7892] video directory scanner thread started (pid=7890, tid=7892)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7892] video directory scanner thread ended (pid=7890, tid=7892)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7891] video directory scanner thread ended (pid=7890, tid=7891)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] registered source parameters for 'A - ATSC'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] registered source parameters for 'C - DVB-C'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] registered source parameters for 'S - DVB-S'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] registered source parameters for 'T - DVB-T'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] probing /dev/dvb/adapter0/frontend0
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] creating cDvbDevice
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] new device number 1
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] LNB-sharing: setting device 0 to use LNB 1
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] frontend 0/0 provides DVB-C with QAM64,QAM128,QAM256 ("Sundtek DVB-C")
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7893] tuner on frontend 0/0 thread started (pid=7890, tid=7893)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7894] section handler thread started (pid=7890, tid=7894)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] probing /dev/dvb/adapter1/frontend0
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] creating cDvbDevice
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] new device number 2
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] LNB-sharing: setting device 1 to use LNB 2
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] frontend 1/0 provides DVB-C with QAM64,QAM128,QAM256 ("Sundtek DVB-C")
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] found 2 DVB devices
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7896] section handler thread started (pid=7890, tid=7896)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7895] tuner on frontend 1/0 thread started (pid=7890, tid=7895)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] initializing plugin: streamdev-server (0.5.0-pre): VDR Streaming Server
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] initializing plugin: epgsearch (0.9.25.beta17): Suche im EPG nach Wiederholungen und anderem
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] ERROR: invalid primary device number: 3
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] ERROR: no primary device found - using first device!
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] setting primary device to 1
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] assuming manual start of VDR
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] SVDRP listening on port 6419
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] setting current skin to "sttng"
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/themes/sttng-default.theme
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] starting plugin: streamdev-server
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/plugins/streamdev/streamdevhosts.conf
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] starting plugin: epgsearch
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7897] streamdev server thread started (pid=7890, tid=7897)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7897] Streamdev: Couldn't listen (VTP) 0.0.0.0:2004: Address already in use
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7897] Streamdev: Couldn't listen (HTTP) 0.0.0.0:3000: Address already in use
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7897] ERROR: no streamdev server activated, exiting
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7897] streamdev server thread ended (pid=7890, tid=7897)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/plugins/epgsearch/epgsearchcats.conf
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/plugins/epgsearch/epgsearchmenu.conf
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] EPGSearch: loading /var/lib/vdr/plugins/epgsearch/epgsearch.conf
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] EPGSearch: loading /var/lib/vdr/plugins/epgsearch/epgsearchtemplates.conf
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] EPGSearch: loading /var/lib/vdr/plugins/epgsearch/epgsearchdone.data
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] loading /var/lib/vdr/plugins/epgsearch/timersdone.conf
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] EPGSearch: loading /var/lib/vdr/plugins/epgsearch/epgsearchupdmail.templ
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7898] EPGSearch: searchtimer thread started (pid=7890, tid=7898)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] ERROR: /dev/lircd: No such file or directory
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] ERROR: remote control LIRC not ready!
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7899] EPGSearch: conflictcheck thread started (pid=7890, tid=7899)
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] switching to channel 51
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] setting watchdog timer to 60 seconds
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] OSD size changed to 720x480 @ 1
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] ERROR: no OSD provider available - using dummy OSD!
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 1 (5 2255-0015 'Harald Schmidt') set to event Don 23.09.2010 23:00-23:45 (VPS: 23.09 23:00) 'Harald Schmidt'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 2 (7 1237-1325 'c't magazin') set to event Sam 25.09.2010 12:40-13:10 (VPS: 25.09 12:40) 'c't magazin'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 3 (25 2242-2325 'extra 3') set to event Son 26.09.2010 22:45-23:15 (VPS: 26.09 22:45) 'extra 3'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 4 (43 1628-1710 'neues') set to event Son 26.09.2010 16:30-17:00 (VPS: 26.09 16:30) 'neues'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 5 (51 2312-0025 'Die Oliver Pocher Show') set to event Fre 24.09.2010 23:15-00:10 'Die Oliver Pocher Show'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 6 (42 1937-2105 'Georg Schramm: "Thomas Bernhard hätte ge') set to event Don 23.09.2010 19:40-20:55 (VPS: 23.09 19:40) 'Georg Schramm: "Thomas Bernhard hätte geschossen"'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 7 (45 1845-2015 'Zeuch') set to event Don 23.09.2010 19:05-19:40 'Alles was zählt'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] switching device 2 to channel 42
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] timer 6 (42 1937-2105 'Georg Schramm: "Thomas Bernhard hätte ge') start
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] Title: 'Georg Schramm: "Thomas Bernhard hätte geschossen"' Subtitle: '(null)'
Sep 23 19:38:20 ramsdorf-srv01 vdr: [7890] executing '/usr/lib/vdr/vdr-recordingaction before "/media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec"'
Sep 23 19:38:20 ramsdorf-srv01 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec as shell script
Sep 23 19:38:21 ramsdorf-srv01 vdr: [7894] channel 51 (SAT.1) event Don 23.09.2010 19:30-20:00 'K 11 - Kommissare im Einsatz' status 4
Sep 23 19:38:21 ramsdorf-srv01 vdr: [7896] changing pids of channel 39 from 110+110=2:120=deu@3,121=2ch@3;125=deu@106:0:130 to 110+110=2:120=deu@3,121=2ch@3;125=deu@106:131=deu:130
Sep 23 19:38:21 ramsdorf-srv01 vdr: [7896] channel 42 (ZDFtheaterkanal) event Don 23.09.2010 19:00-19:40 (VPS: 23.09 19:00) 'Foyer' status 4
Sep 23 19:38:22 ramsdorf-srv01 vdr: [7896] channel 43 (3sat) event Don 23.09.2010 19:20-20:00 (VPS: 23.09 19:20) 'Kulturzeit' status 4
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7890] record /media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7890] creating directory /media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7890] creating directory /media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7890] recording to '/media/vdr/Georg_Schramm#3A_#22Thomas_Bernhard_hätte_ge/2010-09-23.19.37.42-0.rec/00001.ts'
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7909] recording thread started (pid=7890, tid=7909)
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7910] receiver on device 2 thread started (pid=7890, tid=7910)
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7890] switching device 1 to channel 45
Sep 23 19:38:30 ramsdorf-srv01 vdr: [7911] TS buffer on device 2 thread started (pid=7890, tid=7911)
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7890] ERROR (svdrp.c,84): Address already in use
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7890] max. latency time 10 seconds
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7890] switching device 1 to channel 45
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7890] timer 7 (45 1845-2015 'Zeuch') start
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7890] Title: 'Alles was zählt' Subtitle: '(null)'
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7890] executing '/usr/lib/vdr/vdr-recordingaction before "/media/vdr/Zeuch/2010-09-23.18.45.45-0.rec"'
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7898] EPGSearch: search timer update started
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7899] EPGSearch: timer conflict check started
Sep 23 19:38:31 ramsdorf-srv01 vdr: [7899] EPGSearch: timer conflict check finished
Sep 23 19:38:32 ramsdorf-srv01 vdr: [7894] channel 45 (RTL Television) event Don 23.09.2010 19:05-19:40 'Alles was zählt' status 4
Sep 23 19:39:01 ramsdorf-srv01 CRON[7915]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 | xargs -n 200 -r -0 rm)
Sep 23 19:39:08 ramsdorf-srv01 vdr: [7896] channel 42 (ZDFtheaterkanal) event Don 23.09.2010 19:00-19:40 (VPS: 23.09 19:00) 'Foyer' status 1
Sep 23 19:39:09 ramsdorf-srv01 vdr: [7896] channel 42 (ZDFtheaterkanal) event Don 23.09.2010 19:40-20:55 (VPS: 23.09 19:40) 'Georg Schramm: "Thomas Bernhard hätte geschossen"' status 2
Sep 23 19:39:31 ramsdorf-srv01 vdr: [7890] PANIC: watchdog timer expired - exiting!
Sep 23 19:39:31 ramsdorf-srv01 runvdr: restarting VDR


[Sundtek]

Wie sieht es denn ohne Streamdev aus? Es werden ja Fehler insbesondere bezueglich Streamdev in der Logfile angegeben.

[Muschimann]

Hab Streamdev mal raus genommen und über den Tag so an die 30 Aufnahmen programmiert, teilweise gleich 4 nebeneinander.

Wenn es wirklich daran liegt ist das allerdings doof, da ich das Plugin nutze um das Signal zu den Endgeräten zu verteilen.

Naja, mal sehen. Ich meld mich heut Abend mit den Resultaten.

[Muschimann]

Also an Streamdev scheints nicht zu liegen, denn ohne passiert leider genau das selbe.

Die einzigen Fehler die ich dann im Log finde ist das hier:

Code: [Auswählen]

Sep 27 01:57:54 ramsdorf-srv01 vdr: [21063] ERROR (svdrp.c,84): Address already in use


[Sundtek]

Funktionieren die Sticks denn beide einzeln, oder gibt es mit einem ein Problem?

[Muschimann]

So, ich war jetzt mal ne Weile nicht zu Hause und konnte daher nicht weiter testen. Was ich jedoch heraus gefunden habe ist, dass der VDR nach einem Absturz zwei neue Instanzen startet und das genau ist mein Problem. Da muß ich mich mal durch die Konfigurationen wurschteln und gucken warum der das macht. Bei einer zweiten Instanz ist natürlich klar das der nicht per SVDRP zugreifen kann und das erklärt auch weshalb die normalen Aufnahmen weiter gehen. Scheinbar bekommt jeder Stick eine Instanz zugewiesen, bzw. läuft eine Instanz ja "stabil" und die zweite startet sich permanent neu.

Naja, mal gucken was da los ist...

[sundown]

Hi, wurde der Auslöser inzwischen gefunden?
Habe noch keine Ahnung wo ich suchen könnte.

Habe vermutlich das selbe Problem, allerdings obwohl ich nur einen Stick habe und die 2 Aufnahmen sogar auf dem selben Kanal lagen.

VDR ist yaVDR 0.3

fehler ist:

Code: [Auswählen]

...

Oct 20 22:17:01 udattsvdr CRON[1624]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 20 22:17:01 udattsvdr vdr: [1487] ERROR (svdrp.c,84): Address already in use
Oct 20 22:18:01 udattsvdr vdr: last message repeated 59 times
Oct 20 22:19:01 udattsvdr vdr: last message repeated 60 times
Oct 20 22:20:00 udattsvdr vdr: last message repeated 59 times
Oct 20 22:20:00 udattsvdr vdr: [1609] recording thread ended (pid=1487, tid=1609)
Oct 20 22:20:01 udattsvdr vdr: [1487] buffer stats: 247220 (4%) used
Oct 20 22:20:01 udattsvdr vdr: [1487] timer 8 (4 2013-2220 'Eiskalte Engel') stop
Oct 20 22:20:01 udattsvdr vdr: [1487] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/Eiskalte_Engel/2010-10-20.20.13.4-0.rec"'
Oct 20 22:21:00 udattsvdr vdr: [1487] PANIC: watchdog timer expired - exiting!
Oct 20 22:21:00 udattsvdr vdr: [1487] radio: delete cRadioAudio
Oct 20 22:21:01 udattsvdr vdr: [1564] radiocheck thread ended (pid=1487, tid=1564)
Oct 20 22:21:01 udattsvdr vdr: [1563] radioimage thread ended (pid=1487, tid=1563)
Oct 20 22:21:01 udattsvdr vdr: [1587] osdteletext-receiver thread ended (pid=1487, tid=1587)
Oct 20 22:21:01 udattsvdr kernel: [ 7969.197111] vdr[1487]: segfault at 6c00302f ip 0080a81b sp bf918100 error 4 in libstdc++.so.6.0.13[74f000+e9000]
Oct 20 22:21:01 udattsvdr init: vdr main process (1487) killed by SEGV signal
Oct 20 22:21:01 udattsvdr vdr-sxfe[1560]: [1560] [vdr-fe]    caught signal 15
Oct 20 22:21:03 udattsvdr init: vdr-frontend main process (1543) terminated with status 255
Oct 20 22:21:04 udattsvdr vdr-crash: vdr exit with exit status  . Restarting
...


[Sundtek]

Das sollte etwas anderes sein - werden wir morgen gleich überprüfen. Bitte auch immer den aktuellen Beta Treiber verwenden wenn solche Reports gemacht werden.

[sundown]

Ich weiß halt nicht ob es mit dem Stick oder mit dem VDR zutun hat. Hab zZ den Treiber aus den yaVDR Repos installiert:

Code: [Auswählen]

:~$ /opt/bin/mediaclient --build
Build date: Oct 13 2010

:~$ /opt/bin/mediasrv --build
Build date: Oct 13 2010

Gerade eben hat sich mein VDR wieder aufgehangen nachdem er eine Aufnahme starten wollte.

VDR war auf Kabel1 und soll auf RTL schalten. DVB-C mit KDG.

Code: [Auswählen]

Oct 21 20:14:12 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] wait_stream_sync: discard_index 3080776492 != curpos 3080742652 ! (diff 33840)
Oct 21 20:14:12 udattsvdr vdr: [1469] osdteletext-receiver thread ended (pid=1265, tid=1469)
Oct 21 20:14:12 udattsvdr vdr: [1265] switching device 1 to channel 5
Oct 21 20:14:12 udattsvdr vdr: [1467] TS buffer on device 1 thread ended (pid=1265, tid=1467)
Oct 21 20:14:12 udattsvdr vdr: [1466] buffer stats: 369420 (8%) used
Oct 21 20:14:12 udattsvdr vdr: [1466] receiver on device 1 thread ended (pid=1265, tid=1466)
Oct 21 20:14:12 udattsvdr vdr: [1265] timer 6 (5 2010-2125 'Lasko - Die Faust Gottes') start
Oct 21 20:14:12 udattsvdr vdr: [1265] Title: 'Lasko - Die Faust Gottes' Subtitle: ''
Oct 21 20:14:12 udattsvdr vdr: [1265] executing '/usr/lib/vdr/vdr-recordingaction before "/srv/vdr/video.00/Lasko_-_Die_Faust_Gottes/2010-10-21.20.10.5-0.rec"'
Oct 21 20:14:20 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:14:20 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:14:28 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:14:28 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:14:37 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:14:37 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:14:45 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:14:45 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:14:54 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:14:54 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:15:02 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:15:02 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:15:10 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] No data in 8 seconds, queuing no signal image
Oct 21 20:15:10 udattsvdr vdr-sxfe[1380]: [1400] [input_vdr] using custom "no signal" image /usr/share/libxine1-xvdr/nosignal.mpg
Oct 21 20:15:12 udattsvdr vdr: [1265] PANIC: watchdog timer expired - exiting!
Oct 21 20:15:12 udattsvdr lircd-0.8.6[1249]: removed client
Oct 21 20:15:12 udattsvdr vdr: [1265] buffer stats: 0 (0%) used
Oct 21 20:15:12 udattsvdr init: vdr main process (1265) terminated with status 1
Oct 21 20:15:12 udattsvdr vdr-sxfe[1380]: [1380] [vdr-fe]    caught signal 15
Oct 21 20:15:12 udattsvdr vdr-sxfe[1380]: [1380] [input_vdr] Connections closed.
Oct 21 20:15:12 udattsvdr vdr-sxfe[1380]: [1380] [vdr-fe]    Failed to open connection to bus: /bin/dbus-launch terminated abnormally without any error message
Oct 21 20:15:12 udattsvdr vdr-sxfe[1380]: [1380] [vdr-fe]       (ERROR (tools/gnome_screensaver.c,61): Resource temporarily unavailable)
Oct 21 20:15:14 udattsvdr init: vdr-frontend main process (1368) terminated with status 255
Oct 21 20:15:14 udattsvdr init: logo-stop main process (1564) killed by TERM signal
Oct 21 20:15:14 udattsvdr init: logo-start main process (1601) killed by TERM signal
Oct 21 20:15:14 udattsvdr vdr: [1614] cTimeMs: using monotonic clock (resolution is 1 ns)
Oct 21 20:15:14 udattsvdr vdr: [1602] cTimeMs: using monotonic clock (resolution is 1 ns)
Oct 21 20:15:14 udattsvdr vdr: [1602] VDR version 1.7.16 started


[Sundtek]

Wir haben heute den Tipp bekommen das es sich wirklich um ein Umschaltproblem handeln koennte. Vielleicht kommen sich hierbei tatsaechlich 2 Plugins in die Quere.

Bitte auch den Auszug aus /var/log/mediasrv.log fuer diesen Zeitraum angeben +/-15 Minuten.

Es ist schon eine weile her als wir Aufnahmen getestet haben jedoch hatte es zu diesem Zeitpunkt problemlos funktioniert.

Die VDR Logfile laesst darauf schliessen das keine Daten mehr vorhanden sind.

Dieses Problem tritt ja auch nicht bei allen auf ansonsten haetten wir hier wohl schon viel mehr Benachrichtigungen bekommen.

Ab dem morgigen Update wird es ein weiteres Feature geben:

./mediaclient --cat /dev/dvb/adapter0/dvr0 | ./mediaclient --pipecount=100
[2010-10-21 21:22:41]          0 bytes |       0.00 kb |       0.00 mb transferred
[2010-10-21 21:22:41]          0 bytes |       0.00 kb |       0.00 mb transferred
[2010-10-21 21:22:41]          0 bytes |       0.00 kb |       0.00 mb transferred
[2010-10-21 21:22:41]          0 bytes |       0.00 kb |       0.00 mb transferred
[2010-10-21 21:22:42]          0 bytes |       0.00 kb |       0.00 mb transferred
[2010-10-21 21:22:42]          0 bytes |       0.00 kb |       0.00 mb transferred

--pipecount zaehlt wieviele Bytes uebertragen wurden, in dem Fall in 100 Millisekunden.

Diese Informationen haetten wir dann auch noch gerne, damit sollte sich dann herausfinden lassen ob die Videodaten nicht mehr an's System geliefert werden wenn so ein Problem auftritt -- oder ob ein anderer Sender konfiguriert wurde und es aus diesem Grund zu einem Stillstand kommt.

[Sundtek]

Bitte mit dem aktuellen Entwicklungstreiber erneut testen, Aufgrund der anderen Beschreibung konnten wir ein aehnliches Problem reproduzieren und loesen.

[sundown]

Hab den aktuellen Treiber von heute installiert. Hatte allerdings eben wieder einen totalcrash nachdem der VDR zum aufnehmen aufgewacht ist.
Der VDR lies sich erst wieder starten als ich alle Einträge aus der timers.conf gelöscht habe.

Kann man den oben genannten Befehl "./mediaclient --cat /dev/dvb/adapter0/dvr0 | ./mediaclient --pipecount=100" irgendwie in ein Skript bekommen und dauerhaft mitloggen lassen mit Zeitangabe, damit ich nachvollziehen kann ob es daran hing?

[Sundtek]

Sind da sicherlich die aktuellen Treiber installiert?

/opt/bin/mediasrv --build

Bitte aktuelle Logfiles anfuegen, und falls moeglich eine genaue Beschreibung wie wir das nachstellen koennen.

Wir hatten das ganze jetzt mit 2 Sticks getestet und es laeuft seit heute morgen problemlos.

Sie schreiben nach dem Aufwachen, wie schlaeft vdr denn? Eventuell ist dies ein logisches Problem von der Anwendung her.

Wenn der komplette PC schlaeft muss VDR zuvor beendet werden und danach wieder gestartet. Nach dem jeweiligen Aufwachen muss das Geraet ordnungsgemaesz konfiguriert werden.