[vdr] VDR 1.3.12 ritual suicide from HD timer?

[Michal Dobrzynski <michal@appl.com.au>]

This problem occured with VDR 1.3.12 Debian packages from Darren Salt. 
These ARE patched but to be fair I have been running the packages for 
50 days with absolutely no problems and under heavy use. The only 
reason it was interrupted was a power outage a couple of days ago.

Anyway I tested whether recording HD steams worked and determined that 
it should be no problem as long as it never attempted to decode the 
signal to my FF DVB-S card. I have two DVB-T Budget cards which I use 
for all recording and viewing (the DVB-S FF card is used only for 
output to a TV).

My biggest mistake was setting the HD recording for something I really 
wanted to watch. I set timers for both the SD and HD channels (both on 
the same frequency) so that I could watch the SD recording on TV and 
play around with the HD recording on a PC. With an intuitive feeling of 
doom associated with trying something new I went to sleep.

Here is what I found the next morning when I woke up (I know it's a bit 
long but I've tried to summarize and didn't want to miss anything which 
may prove to be important).

Regards,
Michal Dobrzynski

P.S. I know VDR can happily record SD and HD off the same frequency at 
the same time while watching either the SD channel being recorded or 
another channel on my 1 x DVB-S FF + 2 x DVB-T Budget system.


Oct 20 23:25:00 htpc vdr[7367]: timer 15 (9 2325-0040 'Enterprise') 
start
Oct 20 23:25:00 htpc vdr[7367]: Title: 'Enterprise' Subtitle: '(null)'
Oct 20 23:25:00 htpc vdr[7367]: record 
/media/video/Enterprise/2004-10-20.23.25.99.99.rec
Oct 20 23:25:00 htpc vdr[7367]: creating directory 
/media/video/Enterprise
Oct 20 23:25:00 htpc vdr[7367]: creating directory 
/media/video/Enterprise/2004-10-20.23.25.99.99.rec
Oct 20 23:25:01 htpc vdr[7367]: SpinUpDisk took 0.64 seconds
Oct 20 23:25:01 htpc vdr[7367]: recording to 
'/media/video/Enterprise/2004-10-20.23.25.99.99.rec/001.vdr'
Oct 20 23:25:01 htpc vdr[1255]: recording thread started (pid=1255, 
tid=1359882)
Oct 20 23:25:02 htpc vdr[7367]: switching to channel 7
Oct 20 23:25:02 htpc vdr[7367]: buffer stats: 0 (0%) used
Oct 20 23:25:02 htpc vdr[7367]: SetChannelDevice: Channel 7 
Digital;Seven Network, LiveView false
Oct 20 23:25:02 htpc vdr[1256]: transfer thread started (pid=1256, 
tid=1376268)
Oct 20 23:25:02 htpc vdr[1257]: receiver on device 2 thread started 
(pid=1257, tid=1392653)
Oct 20 23:25:02 htpc vdr[7367]: cEITScanner::Activity called
Oct 20 23:25:02 htpc vdr[7367]: switching device 1 to channel 102
Oct 20 23:25:02 htpc vdr[7367]: SetChannelDevice: Channel Nine High 
Definition;Nine Network Australia, LiveView false
Oct 20 23:25:02 htpc vdr[7367]: timer 18 (102 2325-0040 'Enterprise 
HD') start
Oct 20 23:25:02 htpc vdr[7367]: Title: 'ENTERPRISE' Subtitle: 'Impulse'
Oct 20 23:25:02 htpc vdr[7367]: record 
/media/video/Enterprise_HD/2004-10-20.23.25.99.99.rec
Oct 20 23:25:02 htpc vdr[7367]: creating directory 
/media/video/Enterprise_HD
Oct 20 23:25:02 htpc vdr[7367]: creating directory 
/media/video/Enterprise_HD/2004-10-20.23.25.99.99.rec
Oct 20 23:25:03 htpc vdr[7367]: SpinUpDisk took 0.53 seconds
Oct 20 23:25:03 htpc vdr[7367]: recording to 
'/media/video/Enterprise_HD/2004-10-20.23.25.99.99.rec/001.vdr'
Oct 20 23:25:03 htpc vdr[1267]: recording thread started (pid=1267, 
tid=1425423)
Oct 20 23:25:03 htpc vdr[1267]: ERROR: PES packet length overflow in 
remuxer (stream corruption)
Oct 20 23:25:04 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 20 23:25:04 htpc vdr[1267]: ERROR: PES packet length overflow in 
remuxer (stream corruption)
Oct 20 23:25:07 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 20 23:25:07 htpc vdr[1255]: ERROR: unknown picture type '4'
Oct 20 23:25:19 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 20 23:25:39 htpc vdr[1255]: ERROR: unknown picture type '4'
Oct 20 23:25:40 htpc vdr[1267]: ERROR: unknown picture type '5'
Oct 20 23:25:57 htpc vdr[1255]: ERROR: unknown picture type '7'
Oct 20 23:25:57 htpc vdr[1255]: ERROR: unknown picture type '6'
Oct 20 23:25:58 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 20 23:26:01 htpc vdr[1267]: ERROR: unknown picture type '5'
Oct 20 23:26:07 htpc vdr[1267]: ERROR: unknown picture type '4'
Oct 20 23:26:21 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 20 23:26:31 htpc vdr[1267]: ERROR: unknown picture type '5'
Oct 20 23:26:31 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 20 23:26:32 htpc vdr[1267]: ERROR: unknown picture type '6'
Oct 20 23:26:32 htpc vdr[1267]: initiating emergency exit
Oct 20 23:26:33 htpc vdr[7367]: emergency exit requested - shutting down
Oct 20 23:26:40 htpc last message repeated 69937 times
Oct 20 23:26:40 htpc vdr[1267]: ERROR: unknown picture type '4'
Oct 20 23:26:40 htpc vdr[7367]: emergency exit requested - shutting down
Oct 20 23:26:40 htpc vdr[1267]: initiating emergency exit
Oct 20 23:26:40 htpc vdr[7367]: emergency exit requested - shutting down
Oct 20 23:26:42 htpc last message repeated 13786 times
Oct 20 23:26:42 htpc vdr[1255]: ERROR: unknown picture type '5'
Oct 20 23:26:42 htpc vdr[7367]: emergency exit requested - shutting down
Oct 20 23:26:44 htpc last message repeated 19251 times
Oct 20 23:26:44 htpc vdr[1255]: ERROR: unknown picture type '5'
Oct 20 23:26:44 htpc vdr[7367]: emergency exit requested - shutting down
... This went on ad infinitum until a slight variation below
Oct 21 02:12:12 htpc last message repeated 263593 times
Oct 21 02:12:12 htpc vdr[1257]: buffer usage: 80% (tid=1376268)
Oct 21 02:12:12 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:12:42 htpc last message repeated 455385 times
Oct 21 02:13:32 htpc last message repeated 742616 times
Oct 21 02:13:32 htpc vdr[1257]: buffer usage: 85% (tid=1376268)
Oct 21 02:13:32 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:13:32 htpc last message repeated 791 times
Oct 21 02:13:32 htpc vdr[1257]: buffer usage: 90% (tid=1376268)
Oct 21 02:13:32 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:13:32 htpc last message repeated 781 times
Oct 21 02:13:32 htpc vdr[1257]: buffer usage: 95% (tid=1376268)
Oct 21 02:13:32 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:13:32 htpc last message repeated 814 times
Oct 21 02:13:32 htpc vdr[1257]: buffer usage: 100% (tid=1376268)
Oct 21 02:13:32 htpc vdr[1257]: ERROR: 1 ring buffer overflow (89 bytes 
dropped)
Oct 21 02:13:32 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:13:34 htpc last message repeated 31882 times
Oct 21 02:13:34 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 02:13:34 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:13:38 htpc last message repeated 43965 times
Oct 21 02:13:38 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 02:13:38 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:14:08 htpc last message repeated 464900 times
Oct 21 02:14:38 htpc last message repeated 442928 times
Oct 21 02:14:38 htpc vdr[1257]: ERROR: 7394 ring buffer overflows 
(1389874 bytes dropped)
Oct 21 02:14:38 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:14:43 htpc last message repeated 72965 times
Oct 21 02:14:43 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 02:14:43 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:15:13 htpc last message repeated 457025 times
Oct 21 02:16:14 htpc last message repeated 921956 times
Oct 21 02:17:16 htpc last message repeated 925071 times
Oct 21 02:17:49 htpc last message repeated 499539 times
Oct 21 02:17:49 htpc /USR/SBIN/CRON[3421]: (root) CMD (   run-parts 
--report /etc/cron.hourly)
Oct 21 02:17:49 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:18:19 htpc last message repeated 458745 times
Oct 21 02:19:21 htpc last message repeated 918313 times
Oct 21 02:20:21 htpc last message repeated 923643 times
Oct 21 02:21:22 htpc last message repeated 923666 times
Oct 21 02:22:23 htpc last message repeated 920490 times
Oct 21 02:23:24 htpc last message repeated 923919 times
Oct 21 02:24:25 htpc last message repeated 922176 times
Oct 21 02:25:26 htpc last message repeated 922832 times
Oct 21 02:26:27 htpc last message repeated 919257 times
Oct 21 02:27:13 htpc last message repeated 666273 times
Oct 21 02:27:13 htpc vdr[1267]: ERROR: unknown picture type '4'
Oct 21 02:27:13 htpc vdr[1267]: initiating emergency exit
Oct 21 02:27:13 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:27:43 htpc last message repeated 438619 times
Oct 21 02:28:45 htpc last message repeated 861366 times
Oct 21 02:29:45 htpc last message repeated 863597 times
Oct 21 02:30:46 htpc last message repeated 872610 times
Oct 21 02:31:47 htpc last message repeated 882357 times
Oct 21 02:32:21 htpc last message repeated 481830 times
Oct 21 02:32:21 htpc vdr[1257]: ERROR: 20484 ring buffer overflows 
(3850893 bytes dropped)
Oct 21 02:32:21 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:32:25 htpc last message repeated 50742 times
Oct 21 02:32:25 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 02:32:25 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:32:46 htpc last message repeated 297635 times
Oct 21 02:32:46 htpc vdr[7374]: channel 7 (7 Digital;Seven Network) 
event 02:30 'GUTHY-RENKER AUSTRALIA' status 4
Oct 21 02:32:46 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:32:51 htpc last message repeated 79695 times
Oct 21 02:32:51 htpc vdr[1257]: ERROR: 13610 ring buffer overflows 
(2558581 bytes dropped)
Oct 21 02:32:51 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:32:55 htpc last message repeated 51945 times
Oct 21 02:32:55 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 02:32:55 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:33:02 htpc last message repeated 88795 times
Oct 21 02:33:02 htpc vdr[1257]: ERROR: 13660 ring buffer overflows 
(2567981 bytes dropped)
Oct 21 02:33:02 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:33:05 htpc last message repeated 44849 times
Oct 21 02:33:05 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 02:33:05 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:33:33 htpc last message repeated 409901 times
Oct 21 02:33:33 htpc vdr[1267]: ERROR: unknown picture type '7'
Oct 21 02:33:33 htpc vdr[1267]: initiating emergency exit
Oct 21 02:33:33 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 02:34:03 htpc last message repeated 440278 times
Oct 21 02:35:04 htpc last message repeated 814581 times
Oct 21 02:36:06 htpc last message repeated 626420 times
...
Oct 21 03:04:43 htpc vdr[7374]: channel 7 (7 Digital;Seven Network) 
event 03:00 'GUTHY-RENKER AUSTRALIA' status 4
Oct 21 03:04:43 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 03:05:13 htpc last message repeated 329553 times
Oct 21 03:06:14 htpc last message repeated 664685 times
...
Oct 21 04:18:38 htpc last message repeated 69890 times
Oct 21 04:18:38 htpc vdr[1257]: ERROR: 11610 ring buffer overflows 
(2182581 bytes dropped)
Oct 21 04:18:38 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 04:18:41 htpc last message repeated 45099 times
Oct 21 04:18:41 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 04:18:41 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 04:19:11 htpc last message repeated 462983 times
Oct 21 04:20:13 htpc last message repeated 933053 times
...
Oct 21 05:14:03 htpc vdr[1257]: ERROR: 9950 ring buffer overflows 
(1870501 bytes dropped)
Oct 21 05:14:03 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 05:14:07 htpc last message repeated 55765 times
Oct 21 05:14:07 htpc vdr[1256]: ERROR: skipped 99 byte to sync on TS 
packet
Oct 21 05:14:07 htpc vdr[7367]: emergency exit requested - shutting down
Oct 21 05:14:38 htpc last message repeated 371324 times
...
... This is where I woke up and manually stopped VDR.
...
Oct 21 06:35:40 htpc vdr[7367]: caught signal 15
Oct 21 06:35:40 htpc vdr[1255]: recording thread ended (pid=1255, 
tid=1359882)
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 7649421 TS errors, 3152180 
TS continuity errors
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 399991 TS errors, 177945 TS 
continuity errors
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 315635 TS errors, 149131 TS 
continuity errors
Oct 21 06:35:41 htpc vdr[7367]: buffer stats: 32148 (0%) used
Oct 21 06:35:41 htpc vdr[7367]: timer 15 (9 2325-0040 'Enterprise') stop
Oct 21 06:35:41 htpc vdr[7367]: deleting timer 15
Oct 21 06:35:41 htpc vdr[1267]: recording thread ended (pid=1267, 
tid=1425423)
Oct 21 06:35:41 htpc vdr[32144]: receiver on device 1 thread ended 
(pid=32144, tid=1310731)
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 19445518 TS errors, 6531220 
TS continuity errors
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 596085 TS errors, 262507 TS 
continuity errors
Oct 21 06:35:41 htpc vdr[7367]: buffer stats: 67116 (1%) used
Oct 21 06:35:41 htpc vdr[7367]: timer 17 (102 2325-0040 'Enterprise 
HD') stop
Oct 21 06:35:41 htpc vdr[7367]: deleting timer 17
Oct 21 06:35:41 htpc vdr[1256]: transfer thread ended (pid=1256, 
tid=1376268)
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 8 TS errors, 33 TS 
continuity errors
Oct 21 06:35:41 htpc vdr[7367]: cTS2PES got 0 TS errors, 32 TS 
continuity errors
Oct 21 06:35:41 htpc vdr[7367]: buffer stats: 1048575 (100%) used
Oct 21 06:35:41 htpc vdr[7367]: saved setup to /var/lib/vdr/setup.conf
Oct 21 06:35:41 htpc vdr[7370]: tuner on device 1 thread ended 
(pid=7370, tid=16386)
Oct 21 06:35:41 htpc vdr[7371]: Section handler thread ended (pid=7371, 
tid=32771)
Oct 21 06:35:41 htpc vdr[7373]: tuner on device 2 thread ended 
(pid=7373, tid=49156)
Oct 21 06:35:41 htpc vdr[1257]: receiver on device 2 thread ended 
(pid=1257, tid=1392653)
Oct 21 06:35:42 htpc vdr[7374]: Section handler thread ended (pid=7374, 
tid=65541)
Oct 21 06:35:42 htpc vdr[7376]: tuner on device 3 thread ended 
(pid=7376, tid=81926)
Oct 21 06:35:42 htpc vdr[7377]: Section handler thread ended (pid=7377, 
tid=98311)
Oct 21 06:35:42 htpc vdr[7367]: stopping plugin: femon
Oct 21 06:35:42 htpc vdr[7367]: stopping plugin: tvonscreen
Oct 21 06:35:42 htpc vdr[7367]: stopping plugin: osdteletext
Oct 21 06:35:42 htpc vdr[7367]: buffer stats: 0 (0%) used
Oct 21 06:35:43 htpc vdr[7367]: stopping plugin: control
Oct 21 06:35:43 htpc vdr[7378]: [control] gateways thread ended 
(pid=7378)
Oct 21 06:35:43 htpc vdr[7367]: =====================
Oct 21 06:35:43 htpc vdr[7367]: EPG bugfix statistics
Oct 21 06:35:43 htpc vdr[7367]: =====================
Oct 21 06:35:43 htpc vdr[7367]: IF SOMEBODY WHO IS IN CHARGE OF THE EPG 
DATA FOR ONE OF THE LISTED
Oct 21 06:35:43 htpc vdr[7367]: CHANNELS READS THIS: PLEASE TAKE A LOOK 
AT THE FUNCTION cEvent::FixEpgBugs()
Oct 21 06:35:43 htpc vdr[7367]: IN VDR/epg.c TO LEARN WHAT'S WRONG WITH 
YOUR DATA, AND FIX IT!
Oct 21 06:35:43 htpc vdr[7367]: =====================
Oct 21 06:35:43 htpc vdr[7367]: Fix^IHits^IChannels
Oct 21 06:35:43 htpc vdr[7367]: 0^I1^ISBS DIGITAL 1;SBS
Oct 21 06:35:43 htpc vdr[7367]: 2^I25^ITEN Digital;Ten Brisbane, TEN 
Guide;Ten Brisbane, TEN HD;Ten Brisbane, TEN Guide;Ten Brisbane, ...
Oct 21 06:35:43 htpc vdr[7367]: 3^I70^INine Guide;Nine Network 
Australia, Nine High Definition;Nine Network Australia, ...
Oct 21 06:35:44 htpc vdr[7367]: 6^I245^ISBS DIGITAL 1;SBS, TEN 
Guide;Ten Brisbane, TEN Digital;Ten Brisbane, TEN Guide;Ten Brisbane, 
...
Oct 21 06:35:44 htpc vdr[7367]: 7^I2771^I7 HD Digital;Seven Network, 7 
Digital 1;Seven Network, 7 Digital 2;Seven Network, ...
Oct 21 06:35:44 htpc vdr[7367]: =====================
Oct 21 06:35:44 htpc vdr[7367]: max. latency time 30 seconds
Oct 21 06:35:44 htpc vdr[7367]: exiting
Oct 21 06:35:44 htpc vdr[7367]: emergency exit!