[vdr] VPS autotimer stopped prematurely

Hans-Peter Jansen hpj at urpla.net
Wed Mar 7 13:53:58 CET 2007


Hi *,

my family and me greatly enjoy vdr since years, but I finally got around to
update the system to a current state (on top of openSUSE 10.2) lately. The 
system is running dedicated for vdr (1.4.5) purposes, using two hauppauge ff 
2.1 cards.

Yesterday we decided to watch the new Dittsche programme from Monday night,
but had to notice, that the recording stopped prematurely:

Mar  6 00:39:46 ziggy vdr: [3975] channel 1 (Das Erste) event Die 06.03.2007 00:35-01:05 (VPS: 06.03 00:35) 'Dittsche' status 2
Mar  6 00:39:46 ziggy vdr: [3838] switching device 2 to channel 1
Mar  6 00:39:46 ziggy vdr: [3838] timer 3 (1 0035-0105 VPS 'Dittsche~Dittsche~Das wirklich wahre Leben') start
Mar  6 00:39:46 ziggy vdr: [3838] Title: 'Dittsche' Subtitle: 'Das wirklich wahre Leben'
Mar  6 00:39:46 ziggy vdr: [3838] record /video0/Dittsche/Dittsche/Das_wirklich_wahre_Leben/2007-03-06.00.35.50.99.rec
Mar  6 00:39:46 ziggy vdr: [3838] creating directory /video0/Dittsche/Dittsche/Das_wirklich_wahre_Leben/2007-03-06.00.35.50.99.rec
Mar  6 00:39:46 ziggy vdr: [3838] recording to '/video0/Dittsche/Dittsche/Das_wirklich_wahre_Leben/2007-03-06.00.35.50.99.rec/001.vdr'
Mar  6 00:39:46 ziggy vdr: [11192] file writer thread started (pid=3838, tid=11192)
Mar  6 00:39:46 ziggy vdr: [11193] recording thread started (pid=3838, tid=11193)
Mar  6 00:39:46 ziggy vdr: [11194] receiver on device 2 thread started (pid=3838, tid=11194)
Mar  6 00:39:46 ziggy vdr: [11195] TS buffer on device 2 thread started (pid=3838, tid=11195)
Mar  6 00:40:33 ziggy vdr: [3975] channel 1 (Das Erste) event Die 06.03.2007 00:35-01:05 (VPS: 06.03 00:35) 'Dittsche' status 4

Mar  6 00:44:00 ziggy vdr: [3991] EPGSearch: search timer update started
Mar  6 00:44:00 ziggy vdr: [3991] EPGSearch: search timer update finished
Mar  6 00:46:39 ziggy vdr: [3838] switching to channel 1
Mar  6 00:46:45 ziggy vdr: [3838] switching to channel 2
Mar  6 00:46:48 ziggy vdr: [3838] switching to channel 3
Mar  6 00:46:50 ziggy vdr: [3838] switching to channel 4
Mar  6 00:46:56 ziggy vdr: [3838] switching to channel 5
Mar  6 00:47:00 ziggy vdr: [3838] switching to channel 6
Mar  6 00:47:01 ziggy vdr: [3838] switching to channel 7
Mar  6 00:47:03 ziggy vdr: [3838] switching to channel 8
Mar  6 00:47:05 ziggy vdr: [3838] switching to channel 9
Mar  6 00:47:08 ziggy vdr: [3838] switching to channel 10
Mar  6 00:47:14 ziggy vdr: [3838] switching to channel 11
Mar  6 00:47:18 ziggy vdr: [3838] switching to channel 12
Mar  6 00:47:19 ziggy vdr: [3838] switching to channel 13
Mar  6 00:47:22 ziggy vdr: [3838] switching to channel 14
Mar  6 00:47:33 ziggy vdr: [3838] switching to channel 15
Mar  6 00:47:36 ziggy vdr: [3838] switching to channel 16
Mar  6 00:47:39 ziggy vdr: [3838] switching to channel 17
Mar  6 00:48:00 ziggy vdr: [3838] switching to channel 18
Mar  6 00:48:03 ziggy vdr: [3838] switching to channel 19
Mar  6 00:52:55 ziggy vdr: [3975] changing pids of channel 1101 from 201+201:202:204 to 901+901:902:204
Mar  6 00:53:55 ziggy vdr: [3975] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 00:54:54 ziggy vdr: [3975] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 00:55:55 ziggy vdr: [3975] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 00:56:55 ziggy vdr: [3975] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 00:57:54 ziggy vdr: [3975] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 00:58:54 ziggy vdr: [3975] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 00:59:55 ziggy vdr: [3975] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204

Mar  6 01:00:43 ziggy vdr: [3975] channel 1 (Das Erste) event Die 06.03.2007 00:35-01:05 (VPS: 06.03 00:35) 'Dittsche' status 1
Mar  6 01:00:43 ziggy vdr: [11193] recording thread ended (pid=3838, tid=11193)
Mar  6 01:00:43 ziggy vdr: [11195] TS buffer on device 2 thread ended (pid=3838, tid=11195)
Mar  6 01:00:43 ziggy vdr: [11194] buffer stats: 118440 (5%) used
Mar  6 01:00:43 ziggy vdr: [11194] receiver on device 2 thread ended (pid=3838, tid=11194)
Mar  6 01:00:43 ziggy vdr: [11192] file writer thread ended (pid=3838, tid=11192)
Mar  6 01:00:43 ziggy vdr: [3838] buffer stats: 118816 (2%) used
Mar  6 01:00:43 ziggy vdr: [3838] timer 3 (1 0035-0105 VPS 'Dittsche~Dittsche~Das wirklich wahre Leben') stop

Mar  6 01:00:54 ziggy vdr: [3975] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 01:01:56 ziggy vdr: [3975] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 01:02:55 ziggy vdr: [3975] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 01:03:43 ziggy vdr: [3838] switching to channel 1
Mar  6 01:03:46 ziggy vdr: [3838] switching to channel 2
Mar  6 01:03:49 ziggy vdr: [3838] switching to channel 3
Mar  6 01:03:54 ziggy vdr: [3838] switching to channel 4
Mar  6 01:03:55 ziggy vdr: [3975] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 01:03:57 ziggy vdr: [3838] switching to channel 5
Mar  6 01:03:59 ziggy vdr: [3838] switching to channel 6
Mar  6 01:04:00 ziggy vdr: [3928] channel 5 (RTL Television) event Die 06.03.2007 01:00-01:05 'Teleshoppingsendung' status 4
Mar  6 01:04:00 ziggy vdr: [3838] switching to channel 7
Mar  6 01:04:02 ziggy vdr: [3838] switching to channel 8
Mar  6 01:04:04 ziggy vdr: [3838] switching to channel 9
Mar  6 01:04:07 ziggy vdr: [3838] switching to channel 8
Mar  6 01:04:54 ziggy vdr: [3928] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 01:05:53 ziggy vdr: [3928] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 01:06:55 ziggy vdr: [3928] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 01:07:07 ziggy vdr: [3975] changing ca descriptors of channel 1132
Mar  6 01:07:48 ziggy vdr: [3975] changing name of channel 52 from 'Golf,;' to 'Basketball BBL,;'
Mar  6 01:07:55 ziggy vdr: [3928] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 01:08:30 ziggy vdr: [3975] changing pids of channel 45 from 511+511:512=deu,513=deu;515=deu:32 to 511+511:512=deu;515=deu:32
Mar  6 01:08:54 ziggy vdr: [3928] changing pids of channel 1101 from 701+701:702:204 to 901+901:902:204
Mar  6 01:09:42 ziggy vdr: [3974] frontend 1 timed out while tuning to channel 0, tp 111914
Mar  6 01:09:56 ziggy vdr: [3928] changing pids of channel 1101 from 901+901:902:204 to 701+701:702:204
Mar  6 01:10:04 ziggy vdr: [3928] channel 1 (Das Erste) event Die 06.03.2007 01:05-02:45 (VPS: 06.03 01:05) 'Das letzte Kommando' status 4

Mar  6 01:10:08 ziggy vdr: [3838] timer 3 (1 0035-0105 VPS 'Dittsche~Dittsche~Das wirklich wahre Leben') set to no event
Mar  6 01:10:26 ziggy vdr: [3838] deleting timer 3 (1 0035-0105 VPS 'Dittsche~Dittsche~Das wirklich wahre Leben')

The clock is reliable (adjusted via NTP), thus vdr happened to start the 
recording at 00:39:46, about 48 sec before the broadcast started, very nice 
so far. But what happened at 01:00:43? vdr just decided to stop the recording, 
even a few minutes _before_ the original VPS time frame, vastly prematurely.

For the sake of completeness, I included all messages in between, while I don't
understand some of them. Yes, I zapped through the channels two times, but what
happened at 01:04:00 and 01:10:04? Both times it shows status 4, which means
"event running", if I read the source correctly:
0=undefined, 1=not running, 2=starts in a few seconds, 3=pausing, 4=running
waht does event mean here, but most most important question is:
What happened, that stopped the timer prematurely, and how can I prevent
this from happening the next time.

Anyway thanks for your patience and this great project.

Kind regards,
   Pete



More information about the vdr mailing list