Hello,
using the newest version 1.4.1-1 VDR starts a VPS recording at the wrong time. As you can see in the log the timer 8 is a timer using VPS time 1900.
Jun 29 18:50:05 fs1 vdr: [12738] timer 8 (2 1900-1919 VPS 'Eltern~heute') entered VPS margin
At 18:54 the running status of the event changes to 2 (StartsInAFewSeconds) Jun 29 18:54:59 fs1 vdr: [12747] channel 2 (ZDF) event Don 29.06.2006 19:00-19:19 (VPS: 29.06 19:00) 'heute' status 2
Immediatelly the recording is started at 18:55 Jun 29 18:55:00 fs1 vdr: [12738] recording to '/video/Eltern/heute/2006-06-29.19.00.50.99.rec/001.vdr'
At 19:00:001 the running status of the event changes to 4 (Running) IMHO this would be the right time to start the recording Jun 29 19:00:01 fs1 vdr: [12747] channel 2 (ZDF) event Don 29.06.2006 19:00-19:19 (VPS: 29.06 19:00) 'heute' status 4
With previous versions of VDR I had no problems with recording this program. It is also possible that ZDF did not send running status 2 in the past.
Complete log:
Jun 29 16:07:09 fs1 dvb: Starting the DVB drivers Jun 29 16:07:10 fs1 vdr: [12738] VDR version 1.4.1-1 started ... Jun 29 18:50:05 fs1 vdr: [12738] timer 8 (2 1900-1919 VPS 'Eltern~heute') entered VPS margin Jun 29 18:50:25 fs1 vdr: [12747] channel 13 (KiKa) event Don 29.06.2006 18:50-19:00 (VPS: 29.06 18:50) 'Unser Sandmännchen' status 4 Jun 29 18:50:26 fs1 vdr: [15723] recording thread ended (pid=15723, tid=15723) Jun 29 18:50:26 fs1 vdr: [15725] TS buffer on device 2 thread ended (pid=15725, tid=15725) Jun 29 18:50:26 fs1 vdr: [15724] buffer stats: 84036 (4%) used Jun 29 18:50:26 fs1 vdr: [15724] receiver on device 2 thread ended (pid=15724, tid=15724) Jun 29 18:50:26 fs1 vdr: [15722] file writer thread ended (pid=15722, tid=15722) Jun 29 18:50:26 fs1 vdr: [12738] buffer stats: 84412 (1%) used Jun 29 18:50:26 fs1 vdr: [12738] timer 63 (13 1840-1850 VPS 'Andrej~Jasper, der Pinguin~Jasper und der Geburtstag / Verboten!') stop Jun 29 18:50:26 fs1 vdr: [12738] executing '/usr/local/bin/vdrrecord after "/video/Andrej/Jasper,_der_Pinguin/Jasper_und_der_Geburtstag_#2F_Verboten!/2006-06-29.18.40.50.99.rec"' Jun 29 18:50:26 fs1 vdr: [12738] switching device 2 to channel 13 Jun 29 18:50:26 fs1 vdr: [12738] timer 1 (13 1850-1900 VPS 'Sandmann~Unser Sandmännchen~Piggeldy & Federick: Das Seiltanzen') start Jun 29 18:50:26 fs1 vdr: [12738] Title: 'Unser Sandmännchen' Subtitle: 'Piggeldy & Federick: Das Seiltanzen' Jun 29 18:50:26 fs1 vdr: [12738] executing '/usr/local/bin/vdrrecord before "/video/Sandmann/Unser_Sandmännchen/Piggeldy_&_Federick#3A_Das_Seiltanzen/2006-06-29.18.50.50.99.rec"' Jun 29 18:50:26 fs1 vdr: [12738] record /video/Sandmann/Unser_Sandmännchen/Piggeldy_&_Federick#3A_Das_Seiltanzen/2006-06-29.18.50.50.99.rec Jun 29 18:50:26 fs1 vdr: [12738] creating directory /video/Sandmann Jun 29 18:50:26 fs1 vdr: [12738] creating directory /video/Sandmann/Unser_Sandmännchen Jun 29 18:50:26 fs1 vdr: [12738] creating directory /video/Sandmann/Unser_Sandmännchen/Piggeldy_&_Federick#3A_Das_Seiltanzen Jun 29 18:50:26 fs1 vdr: [12738] creating directory /video/Sandmann/Unser_Sandmännchen/Piggeldy_&_Federick#3A_Das_Seiltanzen/2006-06-29.18.50.50.99.rec Jun 29 18:50:26 fs1 vdr: [12738] recording to '/video/Sandmann/Unser_Sandmännchen/Piggeldy_&_Federick#3A_Das_Seiltanzen/2006-06-29.18.50.50.99.rec/001.vdr' Jun 29 18:50:26 fs1 vdr: [15957] file writer thread started (pid=15957, tid=15957) Jun 29 18:50:26 fs1 vdr: [15958] recording thread started (pid=15958, tid=15958) Jun 29 18:50:26 fs1 vdr: [15959] receiver on device 2 thread started (pid=15959, tid=15959) Jun 29 18:50:26 fs1 vdr: [15960] TS buffer on device 2 thread started (pid=15960, tid=15960) Jun 29 18:50:31 fs1 vdr: [12738] timer 63 (13 1840-1850 VPS 'Andrej~Jasper, der Pinguin~Jasper und der Geburtstag / Verboten!') set to no event Jun 29 18:51:22 fs1 vdr: [12738] deleting timer 63 (13 1840-1850 VPS 'Andrej~Jasper, der Pinguin~Jasper und der Geburtstag / Verboten!') Jun 29 18:53:11 fs1 vdr: [12738] connect from 127.0.0.1, port 2584 - accepted Jun 29 18:53:11 fs1 vdr: [12738] closing SVDRP connection Jun 29 18:54:58 fs1 vdr: [12747] channel 2 (ZDF) event Don 29.06.2006 17:55-19:00 (VPS: 29.06 17:50) 'Ein Fall für zwei' status 1 Jun 29 18:54:59 fs1 vdr: [12747] channel 2 (ZDF) event Don 29.06.2006 19:00-19:19 (VPS: 29.06 19:00) 'heute' status 2 Jun 29 18:55:00 fs1 vdr: [12738] switching device 2 to channel 2 Jun 29 18:55:00 fs1 vdr: [12738] timer 8 (2 1900-1919 VPS 'Eltern~heute') start Jun 29 18:55:00 fs1 vdr: [12738] Title: 'heute' Subtitle: '' Jun 29 18:55:00 fs1 vdr: [12738] executing '/usr/local/bin/vdrrecord before "/video/Eltern/heute/2006-06-29.19.00.50.99.rec"' Jun 29 18:55:00 fs1 vdr: [12738] record /video/Eltern/heute/2006-06-29.19.00.50.99.rec Jun 29 18:55:00 fs1 vdr: [12738] creating directory /video/Eltern/heute Jun 29 18:55:00 fs1 vdr: [12738] creating directory /video/Eltern/heute/2006-06-29.19.00.50.99.rec Jun 29 18:55:00 fs1 vdr: [12738] recording to '/video/Eltern/heute/2006-06-29.19.00.50.99.rec/001.vdr' Jun 29 18:55:00 fs1 vdr: [16060] file writer thread started (pid=16060, tid=16060) Jun 29 18:55:00 fs1 vdr: [16061] recording thread started (pid=16061, tid=16061) Jun 29 18:55:16 fs1 vdr: [12738] connect from 127.0.0.1, port 2597 - accepted Jun 29 18:55:20 fs1 vdr: [12738] closing SVDRP connection Jun 29 18:57:53 fs1 vdr: [12738] connect from 127.0.0.1, port 1529 - accepted Jun 29 18:57:53 fs1 vdr: [12738] closing SVDRP connection Jun 29 18:57:53 fs1 vdr: [12738] connect from 127.0.0.1, port 1530 - accepted Jun 29 18:57:53 fs1 vdr: [12738] closing SVDRP connection Jun 29 18:58:13 fs1 vdr: [12738] connect from 127.0.0.1, port 1531 - accepted Jun 29 18:58:13 fs1 vdr: [12738] closing SVDRP connection Jun 29 18:59:29 fs1 vdr: [12747] channel 13 (KiKa) event Don 29.06.2006 18:50-19:00 (VPS: 29.06 18:50) 'Unser Sandmännchen' status 1 Jun 29 18:59:29 fs1 vdr: [15958] recording thread ended (pid=15958, tid=15958) Jun 29 18:59:29 fs1 vdr: [15957] file writer thread ended (pid=15957, tid=15957) Jun 29 18:59:29 fs1 vdr: [12738] buffer stats: 90992 (1%) used Jun 29 18:59:29 fs1 vdr: [12738] timer 1 (13 1850-1900 VPS 'Sandmann~Unser Sandmännchen~Piggeldy & Federick: Das Seiltanzen') stop Jun 29 18:59:29 fs1 vdr: [12738] executing '/usr/local/bin/vdrrecord after "/video/Sandmann/Unser_Sandmännchen/Piggeldy_&_Federick#3A_Das_Seiltanzen/2006-06-29.18.50.50.99.rec"' Jun 29 18:59:29 fs1 vdr: [12747] channel 13 (KiKa) event Don 29.06.2006 19:00-19:25 (VPS: 29.06 19:00) 'Der Zauberer von OZ' status 2 Jun 29 19:00:01 fs1 vdr: [12747] channel 2 (ZDF) event Don 29.06.2006 19:00-19:19 (VPS: 29.06 19:00) 'heute' status 4 Jun 29 19:01:06 fs1 vdr: [12747] channel 13 (KiKa) event Don 29.06.2006 19:00-19:25 (VPS: 29.06 19:00) 'Der Zauberer von OZ' status 4 Jun 29 19:01:06 fs1 vdr: [12738] timer 1 (13 1850-1900 VPS 'Sandmann~Unser Sandmännchen~Piggeldy & Federick: Das Seiltanzen') set to no event Jun 29 19:01:22 fs1 vdr: [12738] deleting timer 1 (13 1850-1900 VPS 'Sandmann~Unser Sandmännchen~Piggeldy & Federick: Das Seiltanzen') Jun 29 19:03:14 fs1 vdr: [12738] connect from 127.0.0.1, port 1952 - accepted Jun 29 19:03:14 fs1 vdr: [12738] closing SVDRP connection