Hi,
heute am Sonntag gab´s bei mir bei der Red Bull Stratos-Übertragung einen Ausfall von über 30 Minuten in der Aufnahme. In den letzten Monaten konnte ich keine derartigen Ausfälle beobachten, weder mit yaVDR 0.4, noch mit yaVDR 0.5 alpha/beta, aber bei dieser ellenlangen Aufzeichnung (habe den VDR seit 14.00 Uhr durchlaufen lassen) gab´s o.g. Fehlermeldung, die wohl bisher nur ein paarmal hier im Portal aufgetaucht ist:
Oct 14 17:17:00 yavdr vdr: [1662] EPGSearch: timer conflict check finished
Oct 14 17:17:00 yavdr vdr: [1661] EPGSearch: search timer update started
Oct 14 17:17:01 yavdr CRON[3158]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Oct 14 17:17:03 yavdr vdr: [1661] EPGSearch: search timer update finished
Oct 14 17:17:22 yavdr vdr: video: 14:42:51.273 +25 45 0/\ms 21+6 v-buf
Oct 14 17:18:13 yavdr vdr: audio/alsa: writei underrun error? 'Datenübergabe unterbrochen (broken pipe)'
Oct 14 17:18:15 yavdr vdr: [1649] changing ca descriptors of channel 1164
Oct 14 17:18:15 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:15 yavdr vdr: [1712] buffer usage: 70% (tid=1710)
Oct 14 17:18:15 yavdr vdr: [1712] buffer usage: 80% (tid=1710)
Oct 14 17:18:16 yavdr vdr: [1712] buffer usage: 90% (tid=1710)
Oct 14 17:18:16 yavdr vdr: [1712] buffer usage: 100% (tid=1710)
Oct 14 17:18:16 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:18 yavdr vdr: [1712] ERROR: driver buffer overflow on device 1
Oct 14 17:18:21 yavdr vdr: [1712] ERROR: driver buffer overflow on device 1
Oct 14 17:18:21 yavdr vdr: [1710] ERROR: skipped 11 bytes to sync on TS packet on device 1
Oct 14 17:18:21 yavdr vdr: [2528] cNaluDumper: TS continuity offset 5
Oct 14 17:18:22 yavdr vdr: video: slow down video, duping frame
Oct 14 17:18:22 yavdr vdr: video: 14:43:47.033+2819 3743 0/\ms 4+6 v-buf
Oct 14 17:18:23 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:24 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:24 yavdr vdr: [1712] ERROR: driver buffer overflow on device 1
Oct 14 17:18:25 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:25 yavdr vdr: video/vdpau: 14:43:48.773: decoder render too slow 3199ms
Oct 14 17:18:26 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:26 yavdr vdr: [1712] ERROR: driver buffer overflow on device 1
Oct 14 17:18:27 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:28 yavdr vdr: [1710] ERROR: TS packet not accepted in Transfer Mode
Oct 14 17:18:28 yavdr vdr: [1712] ERROR: driver buffer overflow on device 1
Oct 14 17:18:29 yavdr vdr: video/vdpau: 14:43:48.773: decoder render too slow 3420ms
Oct 14 17:18:29 yavdr vdr: video: slow down video, duping frame
Oct 14 17:18:29 yavdr vdr: video: decoder buffer empty, duping frame (384/1262694) 3 v-buf
Oct 14 17:18:29 yavdr vdr: video: 14:43:48.673+4459 3887 0/\ms 3+5 v-buf
Oct 14 17:18:29 yavdr vdr: video/vdpau: missed frame (3/1262694)
Oct 14 17:18:29 yavdr vdr: video: slow down video, duping frame
Oct 14 17:18:29 yavdr vdr: video: 14:43:48.673+4459 3887 0/\ms 3+5 v-buf
Oct 14 17:18:29 yavdr vdr: video/vdpau: missed frame (4/1262694)
Oct 14 17:18:29 yavdr vdr: video: decoder buffer empty, duping frame (386/1262694) 3 v-buf
Oct 14 17:18:29 yavdr vdr: video: 14:43:48.673+4123 3959 0/\ms 3+5 v-buf
Oct 14 17:18:29 yavdr vdr: video: slow down video, duping frame
Oct 14 17:18:29 yavdr vdr: video: 14:43:48.673+4123 4007 0/\ms 3+5 v-buf
Oct 14 17:18:29 yavdr vdr: video: decoder buffer empty, duping frame (388/1262694) 3 v-buf
Alles anzeigen
Dieses "decoder buffer empty" geht dann 30 Minuten so weiter. Irgendwann "fängt" er sich wohl dann - anscheinend deswegen, weil ich die laufende Aufnahme abgespielt habe - war zwischenzeitlich nicht zuhause und wollte sehen, was ich verpasst habe:
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: TS continuity offset 2
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: TS continuity offset 6
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: TS continuity offset 13
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: TS continuity offset 13
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: Unexpected NALU fill data: 00
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: TS continuity offset 6
Oct 14 17:55:46 yavdr vdr: [2528] cNaluDumper: TS continuity offset 5
Oct 14 17:55:46 yavdr rsyslogd-2177: imuxsock begins to drop messages from pid 1077 due to rate-limiting
Oct 14 17:55:52 yavdr rsyslogd-2177: imuxsock lost 65 messages from pid 1077 due to rate-limiting
Oct 14 17:55:52 yavdr vdr: [1649] channel 9 (RTLNITRO) event Son 14.10.2012 17:15-18:00 'Walker, Texas Ranger' status 4
Oct 14 17:55:52 yavdr vdr: [1649] channel 5 (RTL Television) event Son 14.10.2012 17:45-18:45 'Exclusiv - Weekend' status 4
Oct 14 17:55:52 yavdr vdr: [1649] changing pids of channel 222 from 163+163=2:104=deu@3:0:105 to 200+200=2:201=deu@3:0:105
Oct 14 17:55:53 yavdr vdr: [3278] Text2Skin: channelInfo display update thread ended (pid=1077, tid=3278)
Oct 14 17:55:57 yavdr vdr: [3288] Text2Skin: menu display update thread started (pid=1077, tid=3288)
Oct 14 17:56:06 yavdr vdr: [1077] [softhddev]SetPlayMode: 0
Oct 14 17:56:06 yavdr vdr: [1077] [softhddev]SetVideoDisplayFormat: 1
Oct 14 17:56:06 yavdr vdr: [1077] [extrecmenu] starting replay of recording
Oct 14 17:56:06 yavdr vdr: [1077] replay /srv/vdr/video.00/Red_Bull_Stratos/2012-10-14.14.00.10-0.rec
Oct 14 17:56:06 yavdr vdr: [1077] playing '/srv/vdr/video.00/Red_Bull_Stratos/2012-10-14.14.00.10-0.rec/00001.ts'
Oct 14 17:56:06 yavdr vdr: [1077] loading /srv/vdr/video.00/Red_Bull_Stratos/2012-10-14.14.00.10-0.rec//marks
Oct 14 17:56:06 yavdr vdr: [1077] loading /srv/vdr/video.00/Red_Bull_Stratos/2012-10-14.14.00.10-0.rec//marks
Oct 14 17:56:06 yavdr vdr: [3288] Text2Skin: menu display update thread ended (pid=1077, tid=3288)
Oct 14 17:56:06 yavdr vdr: [1077] [softhddev]SetPlayMode: 1
Oct 14 17:56:06 yavdr vdr: [3289] dvbplayer thread started (pid=1077, tid=3289)
Oct 14 17:56:06 yavdr vdr: [3290] non blocking file reader thread started (pid=1077, tid=3290)
Oct 14 17:56:06 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:06 yavdr vdr: video: slow down video, duping frame
Oct 14 17:56:06 yavdr vdr: video/vdpau: missed frame (8/12)
Oct 14 17:56:06 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:07 yavdr vdr: video: slow down video, duping frame
Oct 14 17:56:07 yavdr vdr: video: 11:25:32.533+1368 1080 0/\ms 4+8 v-buf
Oct 14 17:56:07 yavdr vdr: [3291] Text2Skin: replayInfo display update thread started (pid=1077, tid=3291)
Oct 14 17:56:07 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:07 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:07 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:08 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:08 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:08 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:08 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:08 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:08 yavdr vdr: video: slow down video, duping frame
Oct 14 17:56:08 yavdr vdr: video: decoder buffer empty, duping frame (111291/76) 0 v-buf
Oct 14 17:56:08 yavdr vdr: video: --:--:--.--- +0 0 0/\ms 0+5 v-buf
Oct 14 17:56:08 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:08 yavdr vdr: video: decoder buffer empty, duping frame (111293/76) 0 v-buf
Oct 14 17:56:08 yavdr vdr: video: slow down video, duping frame
Oct 14 17:56:08 yavdr vdr: video: 11:28:31.713 +932 960 0/\ms 20+7 v-buf
Oct 14 17:56:08 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:08 yavdr vdr: video: decoder buffer empty, duping frame (111295/78) 0 v-buf
Oct 14 17:56:08 yavdr vdr: video: --:--:--.--- +0 0 0/\ms 0+5 v-buf
Oct 14 17:56:08 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:08 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:08 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:08 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:09 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:09 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:09 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:09 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: video: decoder buffer empty, duping frame (111324/80) 0 v-buf
Oct 14 17:56:09 yavdr vdr: video: slow down video, duping frame
Oct 14 17:56:09 yavdr vdr: video: 11:34:30.033+1004 936 0/\ms 18+7 v-buf
Oct 14 17:56:09 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:09 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: video: slow down video, duping frame
Oct 14 17:56:09 yavdr vdr: video: decoder buffer empty, duping frame (111327/84) 0 v-buf
Oct 14 17:56:09 yavdr vdr: video: --:--:--.--- +0 0 0/\ms 0+5 v-buf
Oct 14 17:56:09 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:09 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: audio/alsa: using device 'default'
Oct 14 17:56:09 yavdr vdr: [1077] [softhddev]Clear:
Oct 14 17:56:09 yavdr vdr: audio/alsa: start delay 336ms
Oct 14 17:56:09 yavdr vdr: video: decoder buffer empty, duping frame (111339/86) 2 v-buf
Alles anzeigen
Gibt´s irgendwelche weiteren Logs, die mehr Aufschluss geben könnten? Bei anderen Threads hier im Portal gab´s bisher auch keine Lösungen, und ich schätze, dass die einzige Möglichkeit zum Testen wäre, den VDR stundenlang laufen zu lassen. Habe es auch gelegentlich erlebt, dass ich nach Hause gekommen bin und der VDR - auch ohne dass eine Aufnahme lief - bei einem HD-Sender ein verpixeltes Standbild angezeigt hat, was sich nach dem Umschalten jedoch immer erledigte.
Sieht fast so aus, als sollte ich softhddev erst einmal nicht mehr nutzen...
P.S.: Letztes dist-upgrade habe ich gestern oder vorgestern durchgeführt.
Viele Grüße
Jörg