Jul 6 07:00:00 vdr-backend vdr: [1123] switching device 1 to channel 1 Jul 6 07:00:00 vdr-backend vdr: [1123] timer 1 (1 0700-0715 'test1') start Jul 6 07:00:00 vdr-backend vdr: [1123] Title: 'Durch die Wildnis (18/20)' Subtitle: 'Abenteuerserie Deutschland 2011' Jul 6 07:00:00 vdr-backend vdr: [1123] executing '/usr/lib/vdr/vdr-recordingaction before "/srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec"' Jul 6 07:00:00 vdr-backend recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec Jul 6 07:00:00 vdr-backend vdr: [1123] record /srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec Jul 6 07:00:00 vdr-backend vdr: [1123] creating directory /srv/vdr/video.00/test1 Jul 6 07:00:00 vdr-backend vdr: [1123] creating directory /srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec Jul 6 07:00:00 vdr-backend vdr: [1123] recording to '/srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec/00001.ts' Jul 6 07:00:00 vdr-backend vdr: [2400] recording thread started (pid=1123, tid=2400, prio=high) Jul 6 07:00:00 vdr-backend vdr: [2401] receiver on device 1 thread started (pid=1123, tid=2401, prio=high) Jul 6 07:00:00 vdr-backend vdr: [2402] TS buffer on device 1 thread started (pid=1123, tid=2402, prio=high) Jul 6 07:00:00 vdr-backend vdr: [1123] markad: no logo found for test1 Jul 6 07:00:00 vdr-backend vdr: [1780] XVDR: Recordings state changed (7) Jul 6 07:00:01 vdr-backend vdr: [1123] switching device 2 to channel 2 Jul 6 07:00:01 vdr-backend vdr: [1123] timer 2 (2 0700-0715 'test2') start Jul 6 07:00:01 vdr-backend vdr: [1123] Title: 'Die Biene Maja' Subtitle: '' Jul 6 07:00:01 vdr-backend vdr: [1123] executing '/usr/lib/vdr/vdr-recordingaction before "/srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec"' Jul 6 07:00:01 vdr-backend recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec Jul 6 07:00:01 vdr-backend vdr: [1123] record /srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec Jul 6 07:00:01 vdr-backend vdr: [1123] creating directory /srv/vdr/video.00/test2 Jul 6 07:00:01 vdr-backend vdr: [1123] creating directory /srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec Jul 6 07:00:01 vdr-backend vdr: [1123] recording to '/srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec/00001.ts' Jul 6 07:00:01 vdr-backend vdr: [2409] recording thread started (pid=1123, tid=2409, prio=high) Jul 6 07:00:01 vdr-backend vdr: [2410] receiver on device 2 thread started (pid=1123, tid=2410, prio=high) Jul 6 07:00:01 vdr-backend vdr: [2413] TS buffer on device 2 thread started (pid=1123, tid=2413, prio=high) Jul 6 07:00:01 vdr-backend markad: [2414] starting v0.1.5pre (64bit) Jul 6 07:00:01 vdr-backend markad: [2414] on /srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec Jul 6 07:00:01 vdr-backend vdr: [1123] markad: executing "/usr/bin"/markad -l "/var/lib/markad" --online=2 before "/srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec" Jul 6 07:00:01 vdr-backend vdr: [1780] XVDR: Recordings state changed (8) Jul 6 07:00:03 vdr-backend vdr: [1780] XVDR: Starting garbage collection in recordings cache Jul 6 07:00:11 vdr-backend markad: [2414] broadcast aspectratio 16:9 (from info) Jul 6 07:00:11 vdr-backend markad: [2414] getting broadcast start from info mtime Jul 6 07:00:11 vdr-backend markad: [2414] pre-timer 4m Jul 6 07:00:11 vdr-backend markad: [2414] broadcast length 15m Jul 6 07:00:11 vdr-backend markad: [2414] found H264-video (0x17de) Jul 6 07:00:11 vdr-backend markad: [2414] found AC3 (0x17ea) Jul 6 07:00:11 vdr-backend markad: [2414] using libavcodec.so.53.35.0 with 2 threads Jul 6 07:00:11 vdr-backend markad: [2414] using codec H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Jul 6 07:00:11 vdr-backend markad: [2414] channel ZDF_HD Jul 6 07:00:27 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:00:27 vdr-backend vdr: video: 16:53:51.319 +52 215 0/\ms 14+6 v-buf Jul 6 07:00:37 vdr-backend vdr: [1224] system time adjustment initiated from Sun Jul 6 07:00:37 2014 (1404622837) to Sun Jul 6 07:00:41 2014 (1404622841) Jul 6 07:00:41 vdr-backend vdr: video: packet buffer too small for 531041 Jul 6 07:00:41 vdr-backend markad: [2414] no new data after 30s, skipping wait! Jul 6 07:00:41 vdr-backend markad: [2414] sequence error 7->11 (0x17de) Jul 6 07:00:41 vdr-backend markad: [2414] HDTV 1280x720p50 Jul 6 07:00:41 vdr-backend markad: [2414] sequence error 10->9 (0x17ea) Jul 6 07:00:41 vdr-backend markad: [2414] sequence error 14->11 (0x17de) Jul 6 07:00:41 vdr-backend markad: [2414] sequence error 15->3 (0x17de) Jul 6 07:00:41 vdr-backend markad: [2414] sequence error 0->6 (0x17de) Jul 6 07:00:41 vdr-backend markad: [2414] failed to open 00002.ts Jul 6 07:00:41 vdr-backend markad: [2414] skipped 95544 bytes Jul 6 07:00:41 vdr-backend markad: [2414] processed time 0.13s, 245/0 frames, 1886.3 fps, 37.7 pps Jul 6 07:01:17 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:01:17 vdr-backend vdr: video: 16:54:41.319 +50 181 0/\ms 17+6 v-buf Jul 6 07:02:07 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:02:07 vdr-backend vdr: video: 16:55:31.319 +48 219 0/\ms 20+6 v-buf Jul 6 07:02:29 vdr-backend vdr: [1272] frontend 1/0 lost lock on channel 2, tp 370 Jul 6 07:02:29 vdr-backend vdr: [1272] frontend 1/0 regained lock on channel 2, tp 370 Jul 6 07:02:57 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:02:57 vdr-backend vdr: video: 16:56:21.319 +46 161 0/\ms 17+6 v-buf Jul 6 07:03:47 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:03:47 vdr-backend vdr: video: 16:57:11.319 +43 223 0/\ms 19+6 v-buf Jul 6 07:04:08 vdr-backend vdr: [softhddev] empty video packet 14 bytes Jul 6 07:04:37 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:04:37 vdr-backend vdr: video: 16:58:01.319 +41 164 0/\ms 10+6 v-buf Jul 6 07:05:00 vdr-backend vdr: [1770] EPGSearch: timer conflict check started Jul 6 07:05:00 vdr-backend vdr: [1770] timer 1 (1 0700-0715 'test1') set to event Son 06.07.2014 06:45-07:10 (VPS: 06.07. 06:45) 'Durch die Wildnis (18/20)' Jul 6 07:05:00 vdr-backend vdr: [1770] timer 1 (2 0700-0715 'test2') set to event Son 06.07.2014 07:05-07:20 (VPS: 06.07. 07:05) 'Die Biene Maja' Jul 6 07:05:00 vdr-backend vdr: [1770] EPGSearch: timer conflict check finished Jul 6 07:05:27 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:05:27 vdr-backend vdr: video: 16:58:51.339 +59 154 0/\ms 7+8 v-buf Jul 6 07:05:43 vdr-backend vdr: [1224] system time adjustment initiated from Sun Jul 6 07:05:43 2014 (1404623143) to Sun Jul 6 07:05:47 2014 (1404623147) Jul 6 07:06:10 vdr-backend vdr: [1272] frontend 1/0 lost lock on channel 2, tp 370 Jul 6 07:06:10 vdr-backend vdr: [1272] frontend 1/0 regained lock on channel 2, tp 370 Jul 6 07:06:17 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:06:17 vdr-backend vdr: video: 16:59:41.339 +57 192 0/\ms 17+8 v-buf Jul 6 07:07:07 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:07:07 vdr-backend vdr: video: 17:00:31.339 +55 206 0/\ms 16+7 v-buf Jul 6 07:07:57 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:07:57 vdr-backend vdr: video: 17:01:21.339 +52 148 0/\ms 18+7 v-buf Jul 6 07:08:47 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:08:47 vdr-backend vdr: video: 17:02:11.339 +50 185 0/\ms 13+7 v-buf Jul 6 07:09:10 vdr-backend vdr: [1224] channel 1 (Das Erste HD) event Son 06.07.2014 06:45-07:10 (VPS: 06.07. 06:45) 'Durch die Wildnis (18/20)' status 1 Jul 6 07:09:11 vdr-backend vdr: [1224] channel 1 (Das Erste HD) event Son 06.07.2014 07:10-07:35 (VPS: 06.07. 07:10) 'Willi wills wissen' status 2 Jul 6 07:09:37 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:09:37 vdr-backend vdr: video: 17:03:01.339 +48 127 0/\ms 14+7 v-buf Jul 6 07:10:02 vdr-backend vdr: audio/alsa: writei underrun error? 'Datenübergabe unterbrochen (broken pipe)' Jul 6 07:10:27 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:10:27 vdr-backend vdr: video: 17:03:51.139 +46 389 0/\ms 20+7 v-buf Jul 6 07:10:49 vdr-backend vdr: [1224] system time adjustment initiated from Sun Jul 6 07:10:49 2014 (1404623449) to Sun Jul 6 07:10:53 2014 (1404623453) Jul 6 07:11:17 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:11:17 vdr-backend vdr: video: 17:04:41.139 +44 331 0/\ms 20+7 v-buf Jul 6 07:12:07 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:12:07 vdr-backend vdr: video: 17:05:31.139 +42 369 0/\ms 17+7 v-buf Jul 6 07:12:46 vdr-backend vdr: [1224] channel 1 (Das Erste HD) event Son 06.07.2014 07:10-07:35 (VPS: 06.07. 07:10) 'Willi wills wissen' status 4 Jul 6 07:12:57 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:12:57 vdr-backend vdr: video: 17:06:21.159 +60 407 0/\ms 20+7 v-buf Jul 6 07:13:47 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:13:47 vdr-backend vdr: video: 17:07:11.159 +57 349 0/\ms 19+7 v-buf Jul 6 07:14:37 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:14:37 vdr-backend vdr: video: 17:08:01.159 +55 386 0/\ms 21+6 v-buf Jul 6 07:15:00 vdr-backend vdr: [2400] recording thread ended (pid=1123, tid=2400) Jul 6 07:15:00 vdr-backend vdr: [1123] buffer stats: 324676 (1%) used Jul 6 07:15:00 vdr-backend vdr: [1123] timer 1 (1 0700-0715 'test1') stop Jul 6 07:15:00 vdr-backend vdr: [1123] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec"' Jul 6 07:15:00 vdr-backend recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /srv/vdr/video.00/test1/2014-07-06.07.00.1-0.rec Jul 6 07:15:00 vdr-backend vdr: [2402] TS buffer on device 1 thread ended (pid=1123, tid=2402) Jul 6 07:15:00 vdr-backend vdr: [2401] buffer stats: 178788 (8%) used Jul 6 07:15:00 vdr-backend vdr: [2401] receiver on device 1 thread ended (pid=1123, tid=2401) Jul 6 07:15:00 vdr-backend vdr: [2409] recording thread ended (pid=1123, tid=2409) Jul 6 07:15:00 vdr-backend vdr: [1123] buffer stats: 54144 (0%) used Jul 6 07:15:00 vdr-backend vdr: [1123] timer 2 (2 0700-0715 'test2') stop Jul 6 07:15:00 vdr-backend vdr: [1123] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec"' Jul 6 07:15:00 vdr-backend recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /srv/vdr/video.00/test2/2014-07-06.07.00.2-0.rec Jul 6 07:15:00 vdr-backend vdr: [2413] TS buffer on device 2 thread ended (pid=1123, tid=2413) Jul 6 07:15:00 vdr-backend vdr: [2410] buffer stats: 43992 (2%) used Jul 6 07:15:00 vdr-backend vdr: [2410] receiver on device 2 thread ended (pid=1123, tid=2410) Jul 6 07:15:01 vdr-backend vdr: [2438] epg data writer thread started (pid=1123, tid=2438, prio=low) Jul 6 07:15:02 vdr-backend vdr: [2438] epg data writer thread ended (pid=1123, tid=2438) Jul 6 07:15:27 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:15:27 vdr-backend vdr: video: 17:08:51.159 +53 352 0/\ms 21+6 v-buf Jul 6 07:15:35 vdr-backend vdr: [softhddev] empty video packet 16 bytes Jul 6 07:16:17 vdr-backend vdr: video: slow down video, duping frame Jul 6 07:16:17 vdr-backend vdr: video: 17:09:41.159 +51 318 0/\ms 15+6 v-buf Jul 6 07:16:22 vdr-backend vdr: [1123] deleting timer 1 (1 0700-0715 'test1') Jul 6 07:16:22 vdr-backend vdr: [1123] deleting timer 1 (2 0700-0715 'test2')