gerade habe ich am Ende der Sendung einen Timer programmiert.
Also:
Es läuft eine HD Aufnahme seit einiger Zeit
Anderes Programm schauen (HD)
"Menu"
"Schedule"
"Now"
Scroll über meherere Seiten zum Sender (SD)
Timer setzen
Timer editeren
"0", jeden Sonntag
"OK"
Nach schliessen des Menues war der Ton(HDMI) weg
und liess sich nicht durch Umschalten wieder beleben.
Auch nicht auf der bereits laufenden Aufzeichung.
Erst ein Neustart des VDR brachte ihn wieder, was die zeitgleich laufende HD-Aufnahme gestört hat.
Der Ton der Aufzeichnung wurde nicht gestört, also ein Problem der Ausgabe.
root@vdr4:~# cat 20131204-KeinTonNachTimerProgrammierung.log
Jan 6 19:50:00 vdr4 vdr: [1808] changing pids of channel 12 from 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224 to 5221+5221=27:5222=deu@3,5223=mis@3:5225=deu:5224
Jan 6 19:50:00 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:50:00 vdr4 vdr: video: decoder buffer empty, duping frame (8082/1) 25 v-buf
Jan 6 19:50:00 vdr4 vdr: video: 23:36:43.075+8888 0 0/\ms 25+0 v-buf
Jan 6 19:50:00 vdr4 vdr: video: decoder buffer empty, duping frame (8083/1) 22 v-buf
Jan 6 19:50:00 vdr4 vdr: video/vdpau: missed frame (38/5)
Jan 6 19:50:00 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:50:00 vdr4 vdr: video: 23:36:43.155 +655 341 0/\ms 18+3 v-buf
Jan 6 19:50:00 vdr4 vdr: audio/alsa: using device 'default'
Jan 6 19:50:00 vdr4 vdr: audio/alsa: start delay 336ms
Jan 6 19:50:01 vdr4 vdr: [1808] changing pids of channel 13 from 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224 to 5231+5231=27:5232=deu@3,5233=mis@3:0:5224
Jan 6 19:50:01 vdr4 vdr: [1808] changing pids of channel 14 from 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224 to 5241+5241=27:5242=deu@3,5243=mis@3:0:5224
Jan 6 19:50:02 vdr4 vdr: [1808] changing pids of channel 85 from 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224 to 5251+5251=27:5252=deu@3,5253=mis@3:0:5224
Jan 6 19:50:07 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:50:07 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:50:07 vdr4 vdr: video: 23:36:49.415 -17 458 0/\ms 55+1 v-buf
Jan 6 19:50:07 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:50:07 vdr4 vdr: video: 23:36:49.475 +45 514 0/\ms 56+3 v-buf
Jan 6 19:51:00 vdr4 vdr: video: 23:37:42.155 +25 518 0/\ms 60+2 v-buf
Jan 6 19:51:20 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:51:20 vdr4 vdr: video: 23:38:02.035 -17 534 0/\ms 58+1 v-buf
Jan 6 19:51:20 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:51:20 vdr4 vdr: video: 23:38:02.095 +45 494 0/\ms 55+3 v-buf
Jan 6 19:51:21 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:51:21 vdr4 vdr: video: 23:38:03.355 -17 534 0/\ms 56+1 v-buf
Jan 6 19:51:21 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:51:21 vdr4 vdr: video: 23:38:03.415 +45 494 0/\ms 53+3 v-buf
Jan 6 19:51:24 vdr4 vdr: [1205] connect from 127.0.0.1, port 36816 - accepted
Jan 6 19:51:28 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:51:28 vdr4 vdr: video: 23:38:09.595 -17 486 0/\ms 57+1 v-buf
Jan 6 19:51:28 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:51:28 vdr4 vdr: video: 23:38:09.655 +45 542 0/\ms 60+3 v-buf
Jan 6 19:51:28 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:51:28 vdr4 vdr: video: 23:38:09.955 -17 534 0/\ms 59+1 v-buf
Jan 6 19:51:28 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:51:28 vdr4 vdr: video: 23:38:10.015 +45 494 0/\ms 56+3 v-buf
Jan 6 19:51:28 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:51:28 vdr4 vdr: video: 23:38:10.195 -17 486 0/\ms 58+1 v-buf
Jan 6 19:51:28 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:51:28 vdr4 vdr: video: 23:38:10.255 +45 542 0/\ms 60+3 v-buf
Jan 6 19:51:29 vdr4 vdr: [1205] closing SVDRP connection
Jan 6 19:52:00 vdr4 vdr: video: 23:38:42.155 +25 518 0/\ms 58+2 v-buf
Jan 6 19:52:09 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:52:09 vdr4 vdr: video: 23:38:50.995 -17 510 0/\ms 58+1 v-buf
Jan 6 19:52:09 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:52:09 vdr4 vdr: video: 23:38:51.055 +45 470 0/\ms 55+3 v-buf
Jan 6 19:52:32 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:52:32 vdr4 vdr: video: 23:39:13.675 -16 462 0/\ms 52+1 v-buf
Jan 6 19:52:32 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:52:32 vdr4 vdr: video: 23:39:13.735 +45 542 0/\ms 55+3 v-buf
Jan 6 19:52:35 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:52:35 vdr4 vdr: video: 23:39:16.915 -16 486 0/\ms 56+1 v-buf
Jan 6 19:52:35 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:52:35 vdr4 vdr: video: 23:39:16.975 +45 542 0/\ms 55+3 v-buf
Jan 6 19:52:59 vdr4 vdr: [1850] read incomplete section - len = 331, r = 278
Jan 6 19:53:00 vdr4 vdr: video: 23:39:42.155 +25 518 0/\ms 58+2 v-buf
Jan 6 19:53:14 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:53:14 vdr4 vdr: video: 23:39:55.795 -16 486 0/\ms 57+1 v-buf
Jan 6 19:53:14 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:53:14 vdr4 vdr: video: 23:39:55.855 +45 542 0/\ms 59+3 v-buf
Jan 6 19:54:00 vdr4 vdr: video: 23:40:42.155 +25 542 0/\ms 57+2 v-buf
Jan 6 19:55:00 vdr4 vdr: video: 23:41:42.155 +25 542 0/\ms 56+2 v-buf
Jan 6 19:55:02 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:55:02 vdr4 vdr: video: 23:41:43.915 -21 507 0/\ms 58+1 v-buf
Jan 6 19:55:02 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:55:02 vdr4 vdr: video: 23:41:43.975 +45 470 0/\ms 55+3 v-buf
Jan 6 19:55:12 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:55:12 vdr4 vdr: video: 23:41:54.115 -16 558 0/\ms 60+1 v-buf
Jan 6 19:55:12 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:55:12 vdr4 vdr: video: 23:41:54.175 +45 518 0/\ms 57+3 v-buf
Jan 6 19:55:20 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:55:20 vdr4 vdr: video: 23:42:01.915 -16 462 0/\ms 54+1 v-buf
Jan 6 19:55:20 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:55:20 vdr4 vdr: video: 23:42:01.975 +45 518 0/\ms 58+3 v-buf
Jan 6 19:55:24 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:55:24 vdr4 vdr: video: 23:42:05.995 -16 486 0/\ms 59+1 v-buf
Jan 6 19:55:24 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:55:24 vdr4 vdr: video: 23:42:06.055 +45 518 0/\ms 58+3 v-buf
Jan 6 19:55:36 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:55:36 vdr4 vdr: video: 23:42:17.875 -16 462 0/\ms 55+1 v-buf
Jan 6 19:55:36 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:55:36 vdr4 vdr: video: 23:42:17.935 +45 518 0/\ms 53+3 v-buf
Jan 6 19:56:00 vdr4 vdr: video: 23:42:42.155 +25 542 0/\ms 54+2 v-buf
Jan 6 19:56:30 vdr4 vdr: [1205] connect from 127.0.0.1, port 36819 - accepted
Jan 6 19:56:33 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:56:33 vdr4 vdr: video: 23:43:15.475 -15 534 0/\ms 55+1 v-buf
Jan 6 19:56:33 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:56:33 vdr4 vdr: video: 23:43:15.535 +45 494 0/\ms 52+3 v-buf
Jan 6 19:56:34 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:56:34 vdr4 vdr: video: 23:43:15.715 -15 486 0/\ms 57+1 v-buf
Jan 6 19:56:34 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:56:34 vdr4 vdr: video: 23:43:15.775 +45 542 0/\ms 59+3 v-buf
Jan 6 19:56:35 vdr4 vdr: [1205] closing SVDRP connection
Jan 6 19:56:48 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:56:48 vdr4 vdr: video: 23:43:29.635 -15 486 0/\ms 52+1 v-buf
Jan 6 19:56:48 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:56:48 vdr4 vdr: video: 23:43:29.695 +45 542 0/\ms 60+3 v-buf
Jan 6 19:57:00 vdr4 vdr: video: 23:43:42.155 +25 542 0/\ms 56+2 v-buf
Jan 6 19:57:08 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:57:08 vdr4 vdr: video: 23:43:50.395 -15 534 0/\ms 59+1 v-buf
Jan 6 19:57:08 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:57:08 vdr4 vdr: video: 23:43:50.455 +45 494 0/\ms 56+3 v-buf
Jan 6 19:57:20 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:57:20 vdr4 vdr: video: 23:44:02.495 -15 506 0/\ms 56+1 v-buf
Jan 6 19:57:20 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:57:20 vdr4 vdr: video: 23:44:02.555 +45 466 0/\ms 53+3 v-buf
Jan 6 19:57:28 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:57:28 vdr4 vdr: video: 23:44:09.595 -15 510 0/\ms 58+1 v-buf
Jan 6 19:57:28 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:57:28 vdr4 vdr: video: 23:44:09.655 +45 470 0/\ms 55+3 v-buf
Jan 6 19:57:37 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:57:37 vdr4 vdr: video: 23:44:18.595 -15 462 0/\ms 53+1 v-buf
Jan 6 19:57:37 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:57:37 vdr4 vdr: video: 23:44:18.655 +45 518 0/\ms 58+3 v-buf
Jan 6 19:57:44 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:57:44 vdr4 vdr: video: 23:44:26.255 -16 530 0/\ms 60+1 v-buf
Jan 6 19:57:44 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:57:44 vdr4 vdr: video: 23:44:26.315 +45 490 0/\ms 57+3 v-buf
Jan 6 19:57:47 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:57:47 vdr4 vdr: video: 23:44:29.035 -15 486 0/\ms 57+1 v-buf
Jan 6 19:57:47 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:57:47 vdr4 vdr: video: 23:44:29.095 +45 542 0/\ms 60+3 v-buf
Jan 6 19:58:00 vdr4 vdr: video: 23:44:42.155 +25 566 0/\ms 61+2 v-buf
Jan 6 19:58:01 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:58:01 vdr4 vdr: video: 23:44:42.595 -15 510 0/\ms 59+1 v-buf
Jan 6 19:58:01 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:58:01 vdr4 vdr: video: 23:44:42.655 +45 470 0/\ms 56+3 v-buf
Jan 6 19:58:07 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:58:07 vdr4 vdr: video: 23:44:49.195 -15 510 0/\ms 56+1 v-buf
Jan 6 19:58:07 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:58:07 vdr4 vdr: video: 23:44:49.255 +45 470 0/\ms 53+3 v-buf
Jan 6 19:58:11 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:58:11 vdr4 vdr: video: 23:44:53.035 -15 534 0/\ms 56+1 v-buf
Jan 6 19:58:11 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:58:11 vdr4 vdr: video: 23:44:53.095 +45 494 0/\ms 53+3 v-buf
Jan 6 19:58:20 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:58:20 vdr4 vdr: video: 23:45:02.515 -15 510 0/\ms 54+1 v-buf
Jan 6 19:58:20 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:58:20 vdr4 vdr: video: 23:45:02.575 +45 470 0/\ms 51+3 v-buf
Jan 6 19:58:25 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:58:25 vdr4 vdr: video: 23:45:06.955 -15 534 0/\ms 58+1 v-buf
Jan 6 19:58:25 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:58:25 vdr4 vdr: video: 23:45:07.015 +45 494 0/\ms 55+3 v-buf
Jan 6 19:59:00 vdr4 vdr: video: 23:45:42.155 +25 470 0/\ms 57+2 v-buf
Jan 6 19:59:04 vdr4 vdr: [1850] channel 20 (WDR HD Köln (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 2
Jan 6 19:59:06 vdr4 vdr: [1850] channel 20 (WDR HD Köln (S)) event Sun 06.01.2013 19:30-20:00 (VPS: 06.01. 19:30) 'Lokalzeit-Geschichten' status 1
Jan 6 19:59:10 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:59:10 vdr4 vdr: video: 23:45:52.435 -15 534 0/\ms 58+1 v-buf
Jan 6 19:59:10 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:59:10 vdr4 vdr: video: 23:45:52.495 +45 494 0/\ms 55+3 v-buf
Jan 6 19:59:11 vdr4 vdr: video: speed up video, droping frame
Jan 6 19:59:11 vdr4 vdr: video: 23:45:52.675 -15 510 0/\ms 54+1 v-buf
Jan 6 19:59:11 vdr4 vdr: video: slow down video, duping frame
Jan 6 19:59:11 vdr4 vdr: video: 23:45:52.735 +45 470 0/\ms 51+3 v-buf
Jan 6 19:59:36 vdr4 vdr: [1205] timer 1 (73 1930-2000 VPS 'Schleswig-Holstein Magazin') set to event Sun 06.01.2013 19:30-20:00 (VPS: 06.01. 19:30) 'Schleswig-Holstein Magazin'
Jan 6 19:59:57 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 19:59:57 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 19:59:57 vdr4 vdr: [1205] switching device 5 to channel 73
Er muss device 5 nehmen, da die anderen auf der falschen Ebene liegen
Jan 6 19:59:57 vdr4 vdr: [1205] timer 20 (73 1930-2000 VPS 'Schleswig-Holstein Magazin~Unser Land.Unser Programm.') start
Jan 6 19:59:57 vdr4 vdr: [1205] Title: 'Schleswig-Holstein Magazin' Subtitle: 'Unser Land.Unser Programm.'
Jan 6 19:59:57 vdr4 vdr: [1205] executing '/usr/lib/vdr/vdr-recordingaction before "/srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec"'
Jan 6 19:59:57 vdr4 vdr: [1808] changing pids of channel 12 from 5221+5221=27:5222=deu@3,5223=mis@3:5225=deu:5224 to 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224
Jan 6 19:59:57 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R50correct_videodirs before recording /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec as shell script
Jan 6 19:59:57 vdr4 vdr: [3401] TS buffer on device 5 thread ended (pid=1205, tid=3401)
Jan 6 19:59:57 vdr4 vdr: [3400] buffer stats: 170140 (3%) used
Jan 6 19:59:57 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R60copy_epgimage before recording /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec as shell script
Jan 6 19:59:57 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec
Jan 6 19:59:57 vdr4 vdr: [1205] record /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec
Jan 6 19:59:57 vdr4 vdr: [1205] creating directory /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm.
Jan 6 19:59:57 vdr4 vdr: [1205] creating directory /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm.
Jan 6 19:59:57 vdr4 vdr: [1205] creating directory /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec
Jan 6 19:59:57 vdr4 vdr: [1205] recording to '/srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec/00001.ts'
Jan 6 19:59:57 vdr4 vdr: [3400] receiver on device 5 thread ended (pid=1205, tid=3400)
Jan 6 19:59:57 vdr4 vdr: [3416] recording thread started (pid=1205, tid=3416, prio=high)
Jan 6 19:59:57 vdr4 vdr: [3417] receiver on device 5 thread started (pid=1205, tid=3417, prio=high)
Jan 6 19:59:57 vdr4 vdr: [1205] markad: cannot find timer for 'Schleswig-Holstein Magazin~Unser Land.Unser Programm.~Unser Land.Unser Programm.'
Jan 6 19:59:57 vdr4 vdr: [1205] markad: no logo found for Schleswig-Holstein Magazin~Unser Land.Unser Programm.~Unser Land.Unser Programm.
Jan 6 19:59:57 vdr4 vdr: [3418] TS buffer on device 5 thread started (pid=1205, tid=3418, prio=high)
Jan 6 19:59:57 vdr4 vdr: [1205] setting /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm. to be sorted by time
Jan 6 19:59:57 vdr4 vdr: [1857] XVDR: Recordings state changed (1397)
Jan 6 19:59:57 vdr4 vdr: [1857] XVDR: Requesting clients to reload recordings list
Jan 6 19:59:57 vdr4 vdr: [1850] changing pids of channel 75 from 601+601=2:602=deu@3,603=mis@3:0:604 to 501+501=2:502=deu@3,503=mis@3:0:504
Jan 6 19:59:57 vdr4 vdr: audio/alsa: wait underrun error? 'Broken pipe'
Jan 6 19:59:58 vdr4 vdr: video: decoder buffer empty, duping frame (8157/29844) 0 v-buf
Jan 6 19:59:58 vdr4 vdr: video: 23:46:39.935+8888 0 0/\ms 0+0 v-buf
Jan 6 19:59:58 vdr4 vdr: [1205] switching to channel 73
Jan 6 20:00:00 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:00 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:00 vdr4 vdr: [3416] recording thread ended (pid=1205, tid=3416)
Jan 6 20:00:00 vdr4 vdr: [1205] buffer stats: 119004 (0%) used
Jan 6 20:00:00 vdr4 vdr: [1205] timer 20 (73 1930-2000 VPS 'Schleswig-Holstein Magazin~Unser Land.Unser Programm.') stop
Jan 6 20:00:00 vdr4 vdr: [1205] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec"'
Jan 6 20:00:00 vdr4 vdr: audio/alsa: using device 'default'
Jan 6 20:00:00 vdr4 vdr: audio/alsa: playback open 'default' error: Device or resource busy
Wieso will der hier "Playback" machen?
Jan 6 20:00:00 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Was will er auf channel 2?
Jan 6 20:00:00 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R50correct_videodirs after recording /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec as shell script
Jan 6 20:00:00 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R60copy_epgimage after recording /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec as shell script
Jan 6 20:00:00 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /srv/vdr/video.00/Schleswig-Holstein_Magazin/Unser_Land.Unser_Programm./Unser_Land.Unser_Programm./2013-01-06.19.30.73-0.rec
Jan 6 20:00:00 vdr4 vdr: video: decoder buffer empty, duping frame (8268/29844) 0 v-buf
Jan 6 20:00:00 vdr4 vdr: video: --:--:--.--- +0 0 0/\ms 0+0 v-buf
Jan 6 20:00:00 vdr4 vdr: codec: buggy ffmpeg/libav
Jan 6 20:00:00 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:00 vdr4 vdr: video: decoder buffer empty, duping frame (8275/29844) 0 v-buf
Jan 6 20:00:00 vdr4 vdr: video/vdpau: missed frame (39/2)
Jan 6 20:00:00 vdr4 vdr: video: decoder buffer empty, duping frame (8276/8) 0 v-buf
Jan 6 20:00:00 vdr4 vdr: video: 19:01:57.874+8888 0 240/\ms 0+5 v-buf
Jan 6 20:00:01 vdr4 vdr: [1808] changing pids of channel 13 from 5231+5231=27:5232=deu@3,5233=mis@3:0:5224 to 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224
Jan 6 20:00:02 vdr4 vdr: [1850] channel 73 (NDR FS SH (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 2
Jan 6 20:00:02 vdr4 vdr: [1808] changing pids of channel 14 from 5241+5241=27:5242=deu@3,5243=mis@3:0:5224 to 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224
Jan 6 20:00:02 vdr4 vdr: [1808] changing pids of channel 85 from 5251+5251=27:5252=deu@3,5253=mis@3:0:5224 to 5221+5221=27:5222=deu@3,5223=mis@3;5226=deu@106:5225=deu:5224
Jan 6 20:00:06 vdr4 vdr: [1850] channel 73 (NDR FS SH (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:00:07 vdr4 vdr: [1850] changing pids of channel 75 from 501+501=2:502=deu@3,503=mis@3:0:504 to 601+601=2:602=deu@3,603=mis@3:0:604
Jan 6 20:00:07 vdr4 vdr: [1205] timer 20 (73 1930-2000 VPS 'Schleswig-Holstein Magazin~Unser Land.Unser Programm.') set to event Sun 13.01.2013 19:30-20:00 (VPS: 13.01. 19:30) 'Schleswig-Holstein Magazin'
Jan 6 20:00:13 vdr4 vdr: [1205] switching to channel 74
Jan 6 20:00:13 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:13 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:13 vdr4 vdr: [3418] TS buffer on device 5 thread ended (pid=1205, tid=3418)
Jan 6 20:00:13 vdr4 vdr: [3417] buffer stats: 112800 (2%) used
Jan 6 20:00:13 vdr4 vdr: [3417] receiver on device 5 thread ended (pid=1205, tid=3417)
Jan 6 20:00:13 vdr4 vdr: [3430] receiver on device 5 thread started (pid=1205, tid=3430, prio=high)
Jan 6 20:00:13 vdr4 vdr: [3431] TS buffer on device 5 thread started (pid=1205, tid=3431, prio=high)
Jan 6 20:00:13 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:13 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:13 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:13 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:14 vdr4 vdr: codec: buggy ffmpeg/libav
Jan 6 20:00:14 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:16 vdr4 vdr: [1205] switching to channel 73
Jan 6 20:00:16 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:16 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:17 vdr4 vdr: [3431] TS buffer on device 5 thread ended (pid=1205, tid=3431)
Jan 6 20:00:17 vdr4 vdr: [3430] buffer stats: 111484 (2%) used
Jan 6 20:00:17 vdr4 vdr: [3430] receiver on device 5 thread ended (pid=1205, tid=3430)
Jan 6 20:00:17 vdr4 vdr: [3433] receiver on device 5 thread started (pid=1205, tid=3433, prio=high)
Jan 6 20:00:17 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:17 vdr4 vdr: [3434] TS buffer on device 5 thread started (pid=1205, tid=3434, prio=high)
Jan 6 20:00:17 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:17 vdr4 vdr: codec: buggy ffmpeg/libav
Jan 6 20:00:17 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:23 vdr4 vdr: [1205] switching to channel 4
Jan 6 20:00:23 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:23 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:23 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:23 vdr4 vdr: [3435] receiver on device 2 thread started (pid=1205, tid=3435, prio=high)
Jan 6 20:00:23 vdr4 vdr: [3436] TS buffer on device 2 thread started (pid=1205, tid=3436, prio=high)
Jan 6 20:00:23 vdr4 vdr: [3434] TS buffer on device 5 thread ended (pid=1205, tid=3434)
Jan 6 20:00:23 vdr4 vdr: [3433] buffer stats: 112800 (2%) used
Jan 6 20:00:23 vdr4 vdr: [3433] receiver on device 5 thread ended (pid=1205, tid=3433)
Jan 6 20:00:23 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:23 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:23 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:23 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:23 vdr4 vdr: video: decoder buffer empty, duping frame (8380/1) 5 v-buf
Jan 6 20:00:23 vdr4 vdr: video/vdpau: missed frame (40/4)
Jan 6 20:00:23 vdr4 vdr: video: decoder buffer empty, duping frame (8381/4) 0 v-buf
Jan 6 20:00:23 vdr4 vdr: video: 8:00:58.707+8888 0 0/\ms 0+0 v-buf
Jan 6 20:00:24 vdr4 vdr: [1808] channel 4 (Das Erste HD (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:00:27 vdr4 vdr: [1205] switching to channel 5
Jan 6 20:00:27 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:27 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:27 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:27 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:27 vdr4 vdr: [1808] changing pids of channel 16 from 5131+5131=27:5132=deu@3,5133=mis@3;5136=deu@106:0:5134 to 5121+5121=27:5122=deu@3,5123=mis@3;5126=deu@106:0:5134
Jan 6 20:00:27 vdr4 vdr: [3436] TS buffer on device 2 thread ended (pid=1205, tid=3436)
Jan 6 20:00:27 vdr4 vdr: [3435] buffer stats: 191948 (3%) used
Jan 6 20:00:27 vdr4 vdr: [3435] receiver on device 2 thread ended (pid=1205, tid=3435)
Jan 6 20:00:28 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:28 vdr4 vdr: video: decoder buffer empty, duping frame (8410/1) 0 v-buf
Jan 6 20:00:28 vdr4 vdr: video/vdpau: missed frame (41/5)
Jan 6 20:00:28 vdr4 vdr: video: decoder buffer empty, duping frame (8411/5) 0 v-buf
Jan 6 20:00:28 vdr4 vdr: video: 24:35:10.662+8888 0 0/\ms 0+0 v-buf
Jan 6 20:00:29 vdr4 vdr: [1205] switching to channel 7
Jan 6 20:00:29 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:29 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:29 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:29 vdr4 vdr: [3437] receiver on device 2 thread started (pid=1205, tid=3437, prio=high)
Jan 6 20:00:29 vdr4 vdr: [3438] TS buffer on device 2 thread started (pid=1205, tid=3438, prio=high)
Jan 6 20:00:29 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:29 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:29 vdr4 vdr: [softhddev] invalid PES video packet
Jan 6 20:00:30 vdr4 vdr: video: decoder buffer empty, duping frame (8424/1) 1 v-buf
Jan 6 20:00:30 vdr4 vdr: video/vdpau: missed frame (42/1)
Jan 6 20:00:30 vdr4 vdr: video: decoder buffer empty, duping frame (8425/1) 0 v-buf
Jan 6 20:00:30 vdr4 vdr: video: 8:01:04.675+8888 0 0/\ms 0+0 v-buf
Jan 6 20:00:30 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:31 vdr4 vdr: [1205] switching to channel 8
Jan 6 20:00:31 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:31 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:32 vdr4 vdr: [3438] TS buffer on device 2 thread ended (pid=1205, tid=3438)
Jan 6 20:00:32 vdr4 vdr: [3437] buffer stats: 211312 (4%) used
Jan 6 20:00:32 vdr4 vdr: [3437] receiver on device 2 thread ended (pid=1205, tid=3437)
Jan 6 20:00:32 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:32 vdr4 vdr: [3440] receiver on device 5 thread started (pid=1205, tid=3440, prio=high)
Jan 6 20:00:32 vdr4 vdr: [3441] TS buffer on device 5 thread started (pid=1205, tid=3441, prio=high)
Jan 6 20:00:32 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:32 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:32 vdr4 vdr: video: decoder buffer empty, duping frame (8443/1) 4 v-buf
Jan 6 20:00:32 vdr4 vdr: video/vdpau: missed frame (43/3)
Jan 6 20:00:32 vdr4 vdr: video: decoder buffer empty, duping frame (8444/3) 0 v-buf
Jan 6 20:00:32 vdr4 vdr: video: 23:47:15.475+8888 0 0/\ms 0+0 v-buf
Jan 6 20:00:33 vdr4 vdr: [1205] switching to channel 9
Jan 6 20:00:33 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:33 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:33 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:33 vdr4 vdr: [3442] receiver on device 2 thread started (pid=1205, tid=3442, prio=high)
Jan 6 20:00:33 vdr4 vdr: [3443] TS buffer on device 2 thread started (pid=1205, tid=3443, prio=high)
Jan 6 20:00:33 vdr4 vdr: [3441] TS buffer on device 5 thread ended (pid=1205, tid=3441)
Jan 6 20:00:33 vdr4 vdr: [3440] buffer stats: 139684 (2%) used
Jan 6 20:00:33 vdr4 vdr: [3440] receiver on device 5 thread ended (pid=1205, tid=3440)
Jan 6 20:00:33 vdr4 vdr: [1850] channel 20 (WDR HD Köln (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:00:33 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:34 vdr4 vdr: video: decoder buffer empty, duping frame (8485/1) 3 v-buf
Jan 6 20:00:34 vdr4 vdr: video/vdpau: missed frame (44/2)
Jan 6 20:00:34 vdr4 vdr: video: decoder buffer empty, duping frame (8486/2) 0 v-buf
Jan 6 20:00:34 vdr4 vdr: video: 17:20:07.984+8888 0 0/\ms 0+0 v-buf
Jan 6 20:00:34 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:00:35 vdr4 vdr: [1205] switching to channel 8
Jan 6 20:00:35 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:00:35 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:00:35 vdr4 vdr: [3444] receiver on device 5 thread started (pid=1205, tid=3444, prio=high)
Jan 6 20:00:35 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:00:35 vdr4 vdr: [3445] TS buffer on device 5 thread started (pid=1205, tid=3445, prio=high)
Jan 6 20:00:35 vdr4 vdr: [3443] TS buffer on device 2 thread ended (pid=1205, tid=3443)
Jan 6 20:00:35 vdr4 vdr: [3442] buffer stats: 187060 (3%) used
Jan 6 20:00:35 vdr4 vdr: [3442] receiver on device 2 thread ended (pid=1205, tid=3442)
Jan 6 20:00:35 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:00:36 vdr4 vdr: video: decoder buffer empty, duping frame (8516/1) 0 v-buf
Jan 6 20:00:36 vdr4 vdr: video/vdpau: missed frame (45/1)
Jan 6 20:00:36 vdr4 vdr: video: decoder buffer empty, duping frame (8517/1) 0 v-buf
Jan 6 20:00:36 vdr4 vdr: video: 23:47:19.275+8888 0 0/\ms 0+0 v-buf
Jan 6 20:00:36 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Luater stellen geht...
Jan 6 20:00:42 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 30
Jan 6 20:00:42 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 35
Jan 6 20:00:43 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 40
Jan 6 20:00:43 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 45
Jan 6 20:00:43 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 50
Jan 6 20:00:43 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 55
Jan 6 20:00:44 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 60
Jan 6 20:00:44 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 55
Jan 6 20:00:45 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 50
Jan 6 20:00:45 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 45
Jan 6 20:00:45 vdr4 vdr: [1205] [softhddev]SetVolumeDevice: 40
Jan 6 20:01:13 vdr4 vdr: [softhddev] empty video packet 83 bytes
Jan 6 20:01:16 vdr4 vdr: [1205] switching to channel 5
Jan 6 20:01:16 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:01:16 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:01:16 vdr4 vdr: [1205] [softhddev]SetPlayMode: 1
Jan 6 20:01:16 vdr4 vdr: audio: can't set channels 2 sample-rate 0Hz
Jan 6 20:01:16 vdr4 vdr: [3445] TS buffer on device 5 thread ended (pid=1205, tid=3445)
Jan 6 20:01:16 vdr4 vdr: [3444] buffer stats: 151904 (2%) used
Jan 6 20:01:16 vdr4 vdr: [3444] receiver on device 5 thread ended (pid=1205, tid=3444)
Jan 6 20:01:17 vdr4 vdr: audio: can't set channels 2 sample-rate 48000Hz
Jan 6 20:01:17 vdr4 vdr: video: decoder buffer empty, duping frame (8533/1) 9 v-buf
Jan 6 20:01:17 vdr4 vdr: video/vdpau: missed frame (46/6)
Jan 6 20:01:19 vdr4 vdr: video: decoder buffer empty, duping frame (8534/95) 0 v-buf
Jan 6 20:01:19 vdr4 vdr: video: 24:36:01.422+8888 0 0/\ms 0+0 v-buf
Jan 6 20:01:35 vdr4 vdr: [1205] connect from 127.0.0.1, port 36820 - accepted
Jan 6 20:01:40 vdr4 vdr: [1205] closing SVDRP connection
Es kommt nirgends mehr ton (HDMI)!
Jan 6 20:01:55 vdr4 vdr: [1205] confirm: Restart VDR?
Jan 6 20:01:55 vdr4 vdr: [1205] warning: Restart VDR?
Jan 6 20:01:56 vdr4 vdr: [1205] confirmed
Jan 6 20:01:56 vdr4 vdr: [1205] executing command 'sudo /sbin/initctl restart vdr'
Jan 6 20:01:56 vdr4 kernel: [40363.035691] init: vdr-frontend main process (1939) killed by TERM signal
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: 2 idle message handler, reusing 0x7f5790004440
Jan 6 20:01:57 vdr4 vdr: [3456] dbus2vdr message handler thread started (pid=1205, tid=3456, prio=high)
Jan 6 20:01:57 vdr4 vdr: [3456] dbus2vdr: de.tvdr.vdr.plugin.SVDRPCommand: command 'STAT' has no option
Jan 6 20:01:57 vdr4 vdr: [3456] dbus2vdr: invoking softhddevice.SVDRPCommand("STAT", "")
Jan 6 20:01:57 vdr4 vdr: [3456] dbus2vdr: moving message handler 0x7f5790004440 from active to finished
Jan 6 20:01:57 vdr4 vdr: [3456] dbus2vdr message handler thread ended (pid=1205, tid=3456)
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: connection has messages to send, flushing
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: done flushing
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: 2 idle message handler, reusing 0x7f5790001ac0
Jan 6 20:01:57 vdr4 vdr: [3459] dbus2vdr message handler thread started (pid=1205, tid=3459, prio=high)
Jan 6 20:01:57 vdr4 vdr: [3459] dbus2vdr: de.tvdr.vdr.plugin.SVDRPCommand: command 'DETA' has no option
Jan 6 20:01:57 vdr4 vdr: [3459] dbus2vdr: invoking softhddevice.SVDRPCommand("DETA", "")
Jan 6 20:01:57 vdr4 vdr: [3459] [softhddev]SetPlayMode: 0
Jan 6 20:01:57 vdr4 vdr: [3459] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:01:57 vdr4 vdr: [3459] [softhddev]SetPlayMode: 1
Jan 6 20:01:57 vdr4 vdr: [3459] dbus2vdr: moving message handler 0x7f5790001ac0 from active to finished
Jan 6 20:01:57 vdr4 vdr: [3459] dbus2vdr message handler thread ended (pid=1205, tid=3459)
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: connection has messages to send, flushing
Jan 6 20:01:57 vdr4 vdr: [1875] dbus2vdr: done flushing
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: dynamite
Jan 6 20:01:57 vdr4 vdr: [1205] dynamite: force detaching all devices
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: epgsearchonly
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: devstatus
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: dvdswitch
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: mplayer
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: live
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: epgsearch
Jan 6 20:01:57 vdr4 vdr: [1205] stopping plugin: burn
Jan 6 20:01:58 vdr4 vdr: [1877] burn-chain manager thread ended (pid=1205, tid=1877)
Jan 6 20:01:58 vdr4 vdr: [1205] stopping plugin: restfulapi
Jan 6 20:01:58 vdr4 vdr: [1205] restfulapi: will end server thread: /1357498918/
Jan 6 20:01:58 vdr4 vdr: [1876] restfulapi: server thread end: /1357498918/
Jan 6 20:01:58 vdr4 vdr: [1876] restfulapi: server thread ended (pid=1205)
Jan 6 20:01:58 vdr4 vdr: [1205] stopping plugin: softhddevice
Jan 6 20:01:58 vdr4 vdr: [1205] stopping plugin: conflictcheckonly
Jan 6 20:01:58 vdr4 vdr: [1205] stopping plugin: dbus2vdr
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: emit upstart-signal stopped for all plugins
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: upstart-signal stopped queued
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: stop DBus-Upstart-Signal-Sender
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: dequeue signal vdr-plugin/stopped
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: retrieving connection for sending signal
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: SendSignal Lock
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: SendSignal Unlock
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: SendSignal: dbus_connection_send
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: SendSignal: dbus_message_unref
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: signal sent
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: emit upstart-signal vdr-plugin for stopped
Jan 6 20:01:58 vdr4 vdr: [1926] dbus2vdr: DBus-Upstart-Signal-Sender thread ended (pid=1205, tid=1926)
Jan 6 20:01:58 vdr4 vdr: [1875] dbus2vdr: connection has messages to send, flushing
Jan 6 20:01:58 vdr4 vdr: [1875] dbus2vdr: done flushing
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: delete DBus-Upstart-Signal-Sender
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: StopMonitor Lock
Jan 6 20:01:58 vdr4 vdr: [1875] dbus2vdr: monitor stopped on bus de.tvdr.vdr
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: StopMonitor Unlock 2
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting 2 message handlers
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.channel
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.epg
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.osd
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.plugin
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.recording
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.remote
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.setup
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.shutdown
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.skin
Jan 6 20:01:58 vdr4 vdr: [1205] dbus2vdr: deleting message dispatcher for interface de.tvdr.vdr.timer
Jan 6 20:01:58 vdr4 vdr: [1205] stopping plugin: extrecmenu
Jan 6 20:01:59 vdr4 vdr: [1874] extrecmenu worker thread thread ended (pid=1205, tid=1874)
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: wirbelscan
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: text2skin
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: quickepgsearch
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: markad
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: femon
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: vdrmanager
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: skinpearlhd
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: channellists
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: xvdr
Jan 6 20:01:59 vdr4 vdr: [1205] XVDR: XVDR Server stopped
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: streamdev-server
Jan 6 20:01:59 vdr4 vdr: [1856] streamdev server thread ended (pid=1205, tid=1856)
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: vompserver
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: menuorg
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: iptv
Jan 6 20:01:59 vdr4 vdr: [1205] stopping plugin: undelete
Jan 6 20:01:59 vdr4 vdr: [3389] recording thread ended (pid=1205, tid=3389)
Jan 6 20:01:59 vdr4 vdr: [1205] buffer stats: 409276 (1%) used
Jan 6 20:01:59 vdr4 vdr: [1205] timer 19 (5 1930-2015 VPS 'Terra X Abenteuer Sibirien (2/2)') stop
Jan 6 20:01:59 vdr4 vdr: [1205] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec"'
Jan 6 20:01:59 vdr4 vdr: [3378] TS buffer on device 1 thread ended (pid=1205, tid=3378)
Jan 6 20:01:59 vdr4 vdr: [3377] buffer stats: 231616 (4%) used
Jan 6 20:01:59 vdr4 vdr: [3377] receiver on device 1 thread ended (pid=1205, tid=3377)
Jan 6 20:01:59 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R50correct_videodirs after recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:01:59 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R60copy_epgimage after recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:01:59 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec
Jan 6 20:01:59 vdr4 vdr: [1205] [softhddev]SetPlayMode: 0
Jan 6 20:01:59 vdr4 vdr: [1205] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:01:59 vdr4 vdr: [1205] [softhddev]~cSoftHdControl: dummy player stopped
Jan 6 20:02:00 vdr4 vdr: [1205] saved setup to /var/lib/vdr/setup.conf
Jan 6 20:02:00 vdr4 vdr: [1738] section handler thread ended (pid=1205, tid=1738)
Jan 6 20:02:00 vdr4 vdr: [1737] tuner on frontend 0/0 thread ended (pid=1205, tid=1737)
Jan 6 20:02:00 vdr4 vdr: [1205] tuner 0/0 unbonded from tuner 3/0
Jan 6 20:02:00 vdr4 vdr: [1205] device 1 unbonded from device 4
Jan 6 20:02:00 vdr4 vdr: [1205] dynamite: deleted device for /dev/dvb/adapter0/frontend0
Jan 6 20:02:00 vdr4 vdr: [1807] frontend 1/0 lost lock on channel 9, tp 111582
Jan 6 20:02:01 vdr4 vdr: [1808] section handler thread ended (pid=1205, tid=1808)
Jan 6 20:02:01 vdr4 vdr: [1807] tuner on frontend 1/0 thread ended (pid=1205, tid=1807)
Jan 6 20:02:01 vdr4 vdr: [1205] tuner 1/0 unbonded from tuner 3/0
Jan 6 20:02:01 vdr4 vdr: [1205] device 2 unbonded from device 4
Jan 6 20:02:01 vdr4 vdr: [1205] dynamite: deleted device for /dev/dvb/adapter1/frontend0
Jan 6 20:02:02 vdr4 vdr: [1829] section handler thread ended (pid=1205, tid=1829)
Jan 6 20:02:02 vdr4 vdr: [1828] tuner on frontend 2/0 thread ended (pid=1205, tid=1828)
Jan 6 20:02:02 vdr4 vdr: [1205] tuner 2/0 unbonded from tuner 3/0
Jan 6 20:02:02 vdr4 vdr: [1205] device 3 unbonded from device 4
Jan 6 20:02:02 vdr4 vdr: [1205] dynamite: deleted device for /dev/dvb/adapter2/frontend0
Jan 6 20:02:03 vdr4 vdr: [1839] section handler thread ended (pid=1205, tid=1839)
Jan 6 20:02:03 vdr4 vdr: [1838] tuner on frontend 3/0 thread ended (pid=1205, tid=1838)
Jan 6 20:02:03 vdr4 vdr: [1205] dynamite: deleted device for /dev/dvb/adapter3/frontend0
Jan 6 20:02:03 vdr4 vdr: [1850] section handler thread ended (pid=1205, tid=1850)
Jan 6 20:02:03 vdr4 vdr: [1849] tuner on frontend 4/0 thread ended (pid=1205, tid=1849)
Jan 6 20:02:03 vdr4 vdr: [1205] dynamite: deleted device for /dev/dvb/adapter4/frontend0
Jan 6 20:02:03 vdr4 vdr: [1503] section handler thread ended (pid=1205, tid=1503)
Jan 6 20:02:03 vdr4 vdr: [1205] deleting plugin: dynamite
Jan 6 20:02:04 vdr4 vdr: [1456] dynamite udev monitor for subsystem dvb thread ended (pid=1205, tid=1456)
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: epgsearchonly
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: devstatus
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: dvdswitch
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: mplayer
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: live
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: epgsearch
Jan 6 20:02:04 vdr4 vdr: [1898] EPGSearch: Leaving conflict check thread
Jan 6 20:02:04 vdr4 vdr: [1898] EPGSearch: conflictcheck thread ended (pid=1205, tid=1898)
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: burn
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: restfulapi
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: softhddevice
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: conflictcheckonly
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: dbus2vdr
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: extrecmenu
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: wirbelscan
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: text2skin
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: quickepgsearch
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: markad
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: femon
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: vdrmanager
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: skinpearlhd
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: channellists
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: xvdr
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: streamdev-server
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: vompserver
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: menuorg
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: iptv
Jan 6 20:02:04 vdr4 vdr: [1205] deleting plugin: undelete
Jan 6 20:02:04 vdr4 vdr: [1205] caught signal 15
Jan 6 20:02:04 vdr4 vdr: [1205] exiting, exit code 0
Jan 6 20:02:05 vdr4 vdr: [3471] VDR version 1.7.33 started
Jan 6 20:02:05 vdr4 vdr: [3471] switched to user 'vdr'
Jan 6 20:02:05 vdr4 vdr: [3471] codeset is 'UTF-8' - known
Jan 6 20:02:05 vdr4 vdr: [3471] found 1 locales in /usr/share/locale
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'slv,slo'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'ita'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'dut,nla,nld'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'prt'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'fra,fre'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'nor'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'fin,suo'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'pol'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'esl,spa'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'ell,gre'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'sve,swe'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'rom,rum'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'hun'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'cat,cln'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'rus'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'srb,srp,scr,scc'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'hrv'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'est'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'dan'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'cze,ces'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'tur'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'ukr'
Jan 6 20:02:05 vdr4 vdr: [3471] no locale for language code 'ara'
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-undelete.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-iptv.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-menuorg.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-vompserver.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-streamdev-server.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-xvdr.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-channellists.so.1.7.33
Jan 6 20:02:05 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-skinpearlhd.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-vdrmanager.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-femon.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-markad.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-quickepgsearch.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-text2skin.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-wirbelscan.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-extrecmenu.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-dbus2vdr.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] dbus2vdr: use shutdown-hooks in /usr/share/vdr/shutdown-hooks
Jan 6 20:02:06 vdr4 vdr: [3471] dbus2vdr: use shutdown-hooks-wrapper /usr/share/vdr-plugin-dbus2vdr/shutdown-wrapper
Jan 6 20:02:06 vdr4 vdr: [3471] dbus2vdr: enable Upstart support
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-conflictcheckonly.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-softhddevice.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-restfulapi.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] restfulapi: Port has been set to 8002!
Jan 6 20:02:06 vdr4 vdr: [3471] restfulapi: Ip has been set to 0.0.0.0!
Jan 6 20:02:06 vdr4 vdr: [3471] restfulapi: trying to parse command line arguments
Jan 6 20:02:06 vdr4 vdr: [3471] restfulapi: Port has been set to 8002!
Jan 6 20:02:06 vdr4 vdr: [3471] restfulapi: Ip has been set to 0.0.0.0!
Jan 6 20:02:06 vdr4 vdr: [3471] RESTful-API Settings: port: 8002, ip: 0.0.0.0, eimgs: , cimgs: i, headers: activated
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-burn.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] burn: couldn't stat /dev/dvdrw, assuming iso-creation only
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearch.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-live.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] [live] INFO: validating server ip '0.0.0.0'
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-mplayer.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-dvdswitch.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-devstatus.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearchonly.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading plugin: /usr/lib/vdr/plugins/libvdr-dynamite.so.1.7.33
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/setup.conf
Jan 6 20:02:06 vdr4 vdr: [3538] dynamite udev monitor for subsystem dvb thread started (pid=3471, tid=3538, prio=high)
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown config parameter: DumpNaluFill = 1
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown config parameter: HardLinkCutter = 0
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown config parameter: MaxRecordingSize = 100
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown config parameter: softhddevice.SkipLines = 2
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown config parameter: streamdev-server.SuspendMode = 1
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/sources.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/diseqc.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/scr.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/channels.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/timers.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/commands.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/reccmds.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/svdrphosts.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/remote.conf
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/keymacros.conf
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown plugin 'osdteletext'
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: empty key macro
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: unknown plugin 'xineliboutput'
Jan 6 20:02:06 vdr4 vdr: [3539] video directory scanner thread started (pid=3471, tid=3539, prio=high)
Jan 6 20:02:06 vdr4 vdr: [3471] registered source parameters for 'A - ATSC'
Jan 6 20:02:06 vdr4 vdr: [3471] registered source parameters for 'C - DVB-C'
Jan 6 20:02:06 vdr4 vdr: [3471] registered source parameters for 'S - DVB-S'
Jan 6 20:02:06 vdr4 vdr: [3471] registered source parameters for 'T - DVB-T'
Jan 6 20:02:06 vdr4 vdr: [3471] probing /dev/dvb/adapter0/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: preparing 8 dynamic device slots for dvb devices
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 1
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 2
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 3
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 4
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 5
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 6
Jan 6 20:02:06 vdr4 vdr: [3540] video directory scanner thread started (pid=3471, tid=3540, prio=high)
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 7
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 8
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: grab dvb device 0/0
Jan 6 20:02:06 vdr4 vdr: [3471] probing /dev/dvb/adapter1/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: grab dvb device 1/0
Jan 6 20:02:06 vdr4 vdr: [3471] probing /dev/dvb/adapter2/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: grab dvb device 2/0
Jan 6 20:02:06 vdr4 vdr: [3471] probing /dev/dvb/adapter3/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: grab dvb device 3/0
Jan 6 20:02:06 vdr4 vdr: [3471] probing /dev/dvb/adapter4/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: grab dvb device 4/0
Jan 6 20:02:06 vdr4 vdr: [3471] found 5 DVB devices
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: device '2' in device bondings '1 1 1 1 0 0 0 0 0 0 0 0 0 0 0 0' is not a cDvbDevice
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: undelete (0.0.6): undelete for recordings
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: iptv (0.5.2): Experience the IPTV
Jan 6 20:02:06 vdr4 vdr: [3471] registered source parameters for 'I - IPTV'
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 12
Jan 6 20:02:06 vdr4 vdr: [3471] cTimeMs: using monotonic clock (resolution is 1 ns)
Jan 6 20:02:06 vdr4 vdr: [3471] creating IPTV device 0 (CardIndex=11)
Jan 6 20:02:06 vdr4 vdr: [3541] epg data reader thread started (pid=3471, tid=3541, prio=high)
Jan 6 20:02:06 vdr4 vdr: [3541] reading EPG data from /var/cache/vdr/epg.data
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: menuorg (0.4.5): Reorganizes the main menu
Jan 6 20:02:06 vdr4 vdr: [3471] loading menuorg config file from /var/lib/vdr/plugins/menuorg.xml
Jan 6 20:02:06 vdr4 vdr: [3542] section handler thread started (pid=3471, tid=3542, prio=low)
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: vompserver (0.3.1-3): VDR on MVP plugin by Chris Tallon
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: streamdev-server (0.6.0-git): VDR Streaming Server
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: xvdr (0.9.6): XVDR Server
Jan 6 20:02:06 vdr4 vdr: [3540] video directory scanner thread ended (pid=3471, tid=3540)
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR (recording.c,250): /srv/vdr/video.00/Rentenangst!/2008-03-30.16.00.50.99.rec/resume.vdr: Success
Jan 6 20:02:06 vdr4 vdr: [3539] ERROR (recording.c,250): /srv/vdr/video.00/Rentenangst!/2008-03-30.16.00.50.99.rec/resume.vdr: Success
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR (recording.c,250): /srv/vdr/video.00/Manfred_Spitzer_-_Geist_&_Gehirn/Die_Farben_des_Denkens/2009-10-23.22.45.50.99.rec/resume.vdr: Success
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR (recording.c,250): /srv/vdr/video.00/Manfred_Spitzer_-_Geist_&_Gehirn/Gott_und_Gemeinschaft_-_Alpha-intermezzo/2009-06-19.22.45.50.99.rec/resume.vdr: Success
Jan 6 20:02:06 vdr4 vdr: [3539] ERROR (recording.c,250): /srv/vdr/video.00/Manfred_Spitzer_-_Geist_&_Gehirn/Die_Farben_des_Denkens/2009-10-23.22.45.50.99.rec/resume.vdr: Success
Jan 6 20:02:06 vdr4 vdr: [3539] ERROR (recording.c,250): /srv/vdr/video.00/Manfred_Spitzer_-_Geist_&_Gehirn/Gott_und_Gemeinschaft_-_Alpha-intermezzo/2009-06-19.22.45.50.99.rec/resume.vdr: Success
Jan 6 20:02:06 vdr4 vdr: [3471] XVDR-Error: unable to open resume data: /var/lib/vdr/plugins/xvdr/resume.data
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: channellists (0.0.4): Manage your channellists
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: skinpearlhd (0.0.1): PearlHD Skin
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: vdrmanager (0.8): VDR-Manager support plugin
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: femon (1.7.17): DVB Signal Information Monitor (OSD)
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: markad (0.1.5pre): Mark advertisements
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: quickepgsearch (0.0.1): Quick search for broadcasts
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: text2skin (1.3.2+git): Loader for text-based skins
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: wirbelscan (0.0.7): DVB and pvrinput channel scan for VDR
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: extrecmenu (1.2.2): Extended recordings menu
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: dbus2vdr (1): control vdr via D-Bus
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: conflictcheckonly (0.0.1): Direct access to epgsearch's conflict check menu
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: softhddevice (0.5.3): A software and GPU emulated HD device
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 13
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: restfulapi (0.1.0): Offers a RESTful-API to retrieve data from VDR
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: burn (0.2.0): Versatile convert-and-burn plugin
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: epgsearch (1.0.1.beta2): search the EPG for repeats and more
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: live (0.2.0): Live Interactive VDR Environment
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: mplayer (0.10.2): Media replay via MPlayer
Jan 6 20:02:06 vdr4 vdr: [3471] loading /var/lib/vdr/plugins/mplayersources.conf
Jan 6 20:02:06 vdr4 vdr: [3471] WARNING: removing trailing '/' from base /media/Video/
Jan 6 20:02:06 vdr4 vdr: [3471] WARNING: source base /srv/vdr/video.00 expands to /var/lib/video.00
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: dvdswitch (0.2.1): Allows playback of DVD images
Jan 6 20:02:06 vdr4 vdr: [3471] dvdswitch: Initialize plugin
Jan 6 20:02:06 vdr4 vdr: [3471] dvdswitch: parse cmdline of dvd-plugin for DVD Device
Jan 6 20:02:06 vdr4 vdr: [3471] dvdswitch: use DVD Device: /dev/dvd
Jan 6 20:02:06 vdr4 vdr: [3471] dvdswitch: create link /var/lib/vdr/plugins/dvdswitch/dvdlink to /dev/dvd
Jan 6 20:02:06 vdr4 vdr: [3471] dvdswitch: ...Directory with 'VIDEO_TS' Folder|Directory|1|video_ts|1
Jan 6 20:02:06 vdr4 vdr: [3471] dvdswitch: ...File with '.iso' Extension|Iso-Image|2|.iso|1
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: devstatus (0.4.1): Status of dvb devices
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: epgsearchonly (0.0.1): Direct access to epgsearch's search menu
Jan 6 20:02:06 vdr4 vdr: [3471] initializing plugin: dynamite (0.1.1): attach/detach devices on the fly
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: creating dynamic device slots as much as possible
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 14
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 15
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 16
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 17
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 18
Jan 6 20:02:06 vdr4 vdr: [3471] new device number 19
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: probing /dev/dvb/adapter0/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: probing /dev/dvb/adapter1/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: probing /dev/dvb/adapter2/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: probing /dev/dvb/adapter3/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: probing /dev/dvb/adapter4/frontend0
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: /dev/dvb/adapter0/frontend0 is a dvb adapter trying to set card index to 0
Jan 6 20:02:06 vdr4 vdr: [3471] new sub-device number 1
Jan 6 20:02:06 vdr4 vdr: [3539] video directory scanner thread ended (pid=3471, tid=3539)
Jan 6 20:02:06 vdr4 vdr: [3471] frontend 0/0 provides DVB-S,DVB-S2 with QPSK ("STV090x Multistandard")
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: attached device /dev/dvb/adapter0/frontend0 to dynamic device slot 1
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: device '2' in device bondings '1 1 1 1 0 0 0 0 0 0 0 0 0 0 0 0' is not a cDvbDevice
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: /dev/dvb/adapter1/frontend0 is a dvb adapter trying to set card index to 1
Jan 6 20:02:06 vdr4 vdr: [3471] new sub-device number 2
Jan 6 20:02:06 vdr4 vdr: [3546] section handler thread started (pid=3471, tid=3546, prio=low)
Jan 6 20:02:06 vdr4 vdr: [3545] tuner on frontend 0/0 thread started (pid=3471, tid=3545, prio=high)
Jan 6 20:02:06 vdr4 vdr: [3471] frontend 1/0 provides DVB-S,DVB-S2 with QPSK ("STV090x Multistandard")
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: attached device /dev/dvb/adapter1/frontend0 to dynamic device slot 2
Jan 6 20:02:06 vdr4 vdr: [3549] section handler thread started (pid=3471, tid=3549, prio=low)
Jan 6 20:02:06 vdr4 vdr: [3548] tuner on frontend 1/0 thread started (pid=3471, tid=3548, prio=high)
Jan 6 20:02:06 vdr4 vdr: [3471] tuner 1/0 bonded with tuner 0/0
Jan 6 20:02:06 vdr4 vdr: [3471] device 2 bonded with device 1
Jan 6 20:02:06 vdr4 vdr: [3471] ERROR: device '3' in device bondings '1 1 1 1 0 0 0 0 0 0 0 0 0 0 0 0' is not a cDvbDevice
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: /dev/dvb/adapter2/frontend0 is a dvb adapter trying to set card index to 2
Jan 6 20:02:06 vdr4 vdr: [3471] new sub-device number 3
Jan 6 20:02:06 vdr4 vdr: [3471] frontend 2/0 provides DVB-S,DVB-S2 with QPSK ("STV090x Multistandard")
Jan 6 20:02:06 vdr4 vdr: [3471] dynamite: attached device /dev/dvb/adapter2/frontend0 to dynamic device slot 3
Jan 6 20:02:06 vdr4 vdr: [3471] tuner 0/0 unbonded from tuner 1/0
Jan 6 20:02:06 vdr4 vdr: [3471] device 1 unbonded from device 2
Jan 6 20:02:06 vdr4 rsyslogd-2177: imuxsock begins to drop messages from pid 3471 due to rate-limiting
Jan 6 20:02:07 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R50correct_videodirs before recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:02:07 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R60copy_epgimage before recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:02:07 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec
Jan 6 20:02:08 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R50correct_videodirs after recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:02:08 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R60copy_epgimage after recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:02:08 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom after recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec
Jan 6 20:02:08 vdr4 vdr-frontend[3618]: acip-wakeup.time.old hatte Wert: Tue Jan 1 19:10:00 2013
Jan 6 20:02:08 vdr4 vdr-frontend[3618]: vergleiche mit jetzt: Sun Jan 6 19:02:08 2013
Jan 6 20:02:08 vdr4 vdr-frontend[3618]: assuming manual start
Jan 6 20:02:08 vdr4 vdr-frontend[3618]: watching "AT Translated Set 2 keyboard": /dev/input/event2
Jan 6 20:02:08 vdr4 vdr-frontend[3618]: watching "MCE IR Keyboard/Mouse (cx88xx)": /dev/input/event12
Jan 6 20:02:08 vdr4 vdr-frontend[3618]: started udev monitoring of input devices
Jan 6 20:02:09 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R50correct_videodirs before recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:02:09 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R60copy_epgimage before recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec as shell script
Jan 6 20:02:09 vdr4 recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /srv/vdr/video.00/Terra_X_Abenteuer_Sibirien_(2~2)/_/2013-01-06.19.30.5-0.rec
Jan 6 20:02:12 vdr4 rsyslogd-2177: imuxsock lost 314 messages from pid 3471 due to rate-limiting
Jan 6 20:02:12 vdr4 vdr: [3471] timer 20 (73 1930-2000 VPS 'Schleswig-Holstein Magazin~Unser Land.Unser Programm.') set to event Sun 13.01.2013 19:30-20:00 (VPS: 13.01. 19:30) 'Schleswig-Holstein Magazin'
Jan 6 20:02:18 vdr4 vdr: [3471] switching device 5 to channel 58
Jan 6 20:02:18 vdr4 vdr: [3471] switching device 5 to channel 57
Jan 6 20:02:18 vdr4 vdr: [3471] switching device 5 to channel 60
Jan 6 20:02:18 vdr4 vdr: [3471] switching device 5 to channel 63
Jan 6 20:02:18 vdr4 vdr: [3471] switching device 5 to channel 70
Jan 6 20:02:18 vdr4 vdr: [3558] channel 90 (WDR Köln (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:02:18 vdr4 vdr: [3558] channel 70 (hr-fernsehen (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:02:20 vdr4 vdr: [3558] channel 57 (Das Erste (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:02:22 vdr4 vdr: [3471] timer 2 (57 1100-1130 VPS 'Kopfball') set to event Sun 20.01.2013 11:00-11:30 (VPS: 20.01. 11:00) 'Kopfball'
Jan 6 20:02:24 vdr4 vdr: [3471] switching to channel 4
Jan 6 20:02:24 vdr4 vdr: [3471] [softhddev]SetPlayMode: 0
Jan 6 20:02:24 vdr4 vdr: [3471] [softhddev]SetVideoDisplayFormat: 1
Jan 6 20:02:24 vdr4 vdr: [3471] [softhddev]SetPlayMode: 1
Jan 6 20:02:24 vdr4 vdr: [3654] receiver on device 2 thread started (pid=3471, tid=3654, prio=high)
Jan 6 20:02:24 vdr4 vdr: [3655] TS buffer on device 2 thread started (pid=3471, tid=3655, prio=high)
Jan 6 20:02:24 vdr4 vdr: audio/alsa: using device 'default'
Jan 6 20:02:25 vdr4 vdr: audio/alsa: start delay 336ms
Jan 6 20:02:25 vdr4 vdr: video: slow down video, duping frame
Jan 6 20:02:25 vdr4 vdr: video: decoder buffer empty, duping frame (32/1) 3 v-buf
Jan 6 20:02:25 vdr4 vdr: video: 8:03:00.007+8888 0 0/\ms 3+0 v-buf
Jan 6 20:02:25 vdr4 vdr: video: decoder buffer empty, duping frame (34/1) 4 v-buf
Jan 6 20:02:25 vdr4 vdr: video/vdpau: missed frame (1/4)
Jan 6 20:02:25 vdr4 vdr: video: decoder buffer empty, duping frame (35/8) 0 v-buf
Jan 6 20:02:25 vdr4 vdr: video: 8:03:00.147+8888 0 0/\ms 0+0 v-buf
Jan 6 20:02:25 vdr4 vdr: video: decoder buffer empty, duping frame (38/22) 0 v-buf
Jan 6 20:02:25 vdr4 vdr: video: slow down video, duping frame
Jan 6 20:02:25 vdr4 vdr: video: 8:03:00.527+1096 450 0/\ms 5+3 v-buf
Jan 6 20:02:25 vdr4 vdr: audio/alsa: using device 'default'
Jan 6 20:02:26 vdr4 vdr: audio/alsa: start delay 336ms
Jan 6 20:02:27 vdr4 vdr: [3549] channel 4 (Das Erste HD (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:02:27 vdr4 vdr: [3549] changing pids of channel 16 from 5131+5131=27:5132=deu@3,5133=mis@3;5136=deu@106:0:5134 to 5121+5121=27:5122=deu@3,5123=mis@3;5126=deu@106:0:5134
Jan 6 20:02:29 vdr4 vdr: [3471] switching device 5 to channel 58
Jan 6 20:02:31 vdr4 vdr: [3471] switching device 5 to channel 60
Jan 6 20:02:31 vdr4 vdr: [3471] switching device 5 to channel 63
Jan 6 20:02:33 vdr4 vdr: [3558] channel 63 (KiKA (S)) event Sun 06.01.2013 20:00-20:10 (VPS: 06.01. 20:00) 'ERDE AN ZUKUNFT' status 4
Jan 6 20:02:34 vdr4 vdr: [3558] channel 58 (ZDF (S)) event Sun 06.01.2013 19:30-20:15 (VPS: 06.01. 19:30) 'Terra X Abenteuer Sibirien (2/2)' status 4
Jan 6 20:02:37 vdr4 vdr: [3471] timer 12 (63 1130-1200 VPS 'Die Sendung mit der Maus~Die Weihnachtsmaus') set to no event
Jan 6 20:02:40 vdr4 vdr: [3471] switching device 5 to channel 60
Jan 6 20:02:40 vdr4 vdr: [3558] channel 62 (EinsPlus (S)) event Sun 06.01.2013 20:00-20:15 (VPS: 06.01. 20:00) 'Tagesschau' status 4
Jan 6 20:02:41 vdr4 vdr: [3558] channel 60 (arte (S)) event Sun 06.01.2013 19:45-20:15 (VPS: 06.01. 19:45) 'Zu Tisch im ...' status 4
Jan 6 20:02:42 vdr4 vdr: [3471] timer 7 (60 1930-1940 VPS 'Karambolage') set to event Sun 13.01.2013 19:30-19:45 (VPS: 13.01. 19:30) 'Karambolage'
Jan 6 20:03:01 vdr4 vdr: [3558] changing pids of channel 574 from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0
Jan 6 20:03:25 vdr4 vdr: video: slow down video, duping frame
Jan 6 20:03:25 vdr4 vdr: video: 8:03:58.567 +33 651 0/\ms 46+2 v-buf
Jan 6 20:03:59 vdr4 vdr: [3558] changing pids of channel 574 from 401+401=2:402=deu@3:0:0 to 501+501=2:502=deu@3:0:0
Jan 6 20:04:25 vdr4 vdr: video: 8:04:58.567 +33 627 0/\ms 57+2 v-buf
Jan 6 20:05:02 vdr4 vdr: [3558] changing pids of channel 574 from 501+501=2:502=
Alles anzeigen