[vdr] Re: VDR deletes fresh recordings

To: <vdr@linuxtv.org>
Subject: [vdr] Re: VDR deletes fresh recordings
From: Rainer Zocholl <UseNet-Posting-Nospam-74308-@zocki.toppoint.de>
Date: 15 Jan 2005 14:34:00 +0100
>received: by zocki.toppoint.de (CrossPoint/FreeXP v3.40 RC3 (EMS) @ 3108030030 R/C6515);15 Jan 2005 14:34:27 +0100
Content-transfer-encoding: 8bit
Content-type: text/plain; charset=ISO-8859-1
In-reply-to: <41E8E74C.10100@cadsoft.de>
List-help: <mailto:ecartis@linuxtv.org?subject=help>
List-id: vdr <vdr.mail>
List-owner: <mailto:listmaster@convergence.de>
List-post: <mailto:vdr@linuxtv.org>
List-software: Ecartis version 1.0.0
List-subscribe: <mailto:ecartis@linuxtv.org?subject=subscribe%20vdr>
List-unsubscribe: <mailto:ecartis@linuxtv.org?subject=unsubscribe%20vdr>
Organization: http://www.toppoint.de
References: <41E8E74C.10100@cadsoft.de>
Reply-to: vdr@linuxtv.org
Sender: vdr-bounce@linuxtv.org
Klaus.Schmidinger@cadsoft.de(Klaus Schmidinger)  15.01.05 10:50

>Rainer Zocholl wrote:
>> Hello
>>
>> recorded the stream of ESA yesterdas.
>> While the recording was in progress i could replay it.
>> Now it has finished.
>> If i "replay" it i got only a black screen.

>Are there any log file entries regarding these files?
>Usually VDR logs the reason why it deletes a file.


grep "ESA" gives:

07:00:00[1740]: timer 132 (272 0700-2345 'ESA') start
07:00:04[1740]: record /video0/ESA/2005-01-14.07:00.50.07.rec
07:00:04[1740]: creating directory /video0/ESA
07:00:04[1740]: creating directory /video0/ESA/2005-01-14.07:00.50.07.rec
07:00:04[1740]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
07:00:04[1740]: creating directory /video1/ESA
07:00:04[1740]: creating directory /video1/ESA/2005-01-14.07:00.50.07.rec
08:17:03[1740]: timer 132 (272 0700-2345 'ESA') stop
08:17:26[28852]: timer 132 (272 0700-2345 'ESA') start
08:17:30[28852]: record /video0/ESA/2005-01-14.07:00.50.07.rec
08:17:32[28852]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/002.vdr'
08:18:03[28852]: timer 132 (272 0700-2345 'ESA') stop
08:18:22[28992]: timer 132 (272 0700-2345 'ESA') start
08:18:26[28992]: record /video0/ESA/2005-01-14.07:00.50.07.rec
08:18:28[28992]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/003.vdr'
08:18:59[28992]: timer 132 (272 0700-2345 'ESA') stop
08:19:21[29128]: timer 132 (272 0700-2345 'ESA') start
08:19:25[29128]: record /video0/ESA/2005-01-14.07:00.50.07.rec
08:19:26[29128]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/004.vdr'
08:19:59[29128]: timer 132 (272 0700-2345 'ESA') stop
08:20:18[29270]: timer 132 (272 0700-2345 'ESA') start
08:20:22[29270]: record /video0/ESA/2005-01-14.07:00.50.07.rec
08:20:24[29270]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/005.vdr'
08:21:15[29270]: timer 132 (272 0700-2345 'ESA') stop
08:22:01[29270]: deleting recording /video0/ESA/2005-01-14.07:00.50.07.rec
08:22:30[29270]: timer 132 (272 0700-2345 'ESA') start
08:22:34[29270]: record /video0/ESA/2005-01-14.07:00.50.07.rec
08:22:34[29270]: creating directory /video0/ESA/2005-01-14.07:00.50.07.rec
08:22:34[29270]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
09:09:58[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
09:09:58[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
09:37:45[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/002.vdr'
10:36:51[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/003.vdr'
11:37:44[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/004.vdr'
12:37:46[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/005.vdr'
13:41:10[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/006.vdr'
14:42:49[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/007.vdr'
15:42:35[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/008.vdr'
15:59:32[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
15:59:32[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
16:26:10[9925]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/002.vdr'
16:41:02[9925]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/003.vdr'
16:44:15[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/009.vdr'
17:05:26[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
17:05:26[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
17:05:26[16397]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/003.vdr'
17:17:24[16397]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/004.vdr'
17:45:23[16397]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/005.vdr'
17:46:35[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/010.vdr'
17:54:21[16397]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/006.vdr'
18:11:05[16397]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/007.vdr'
19:08:27[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/011.vdr'
20:20:07[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/012.vdr'
21:43:59[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/013.vdr'
21:59:28[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
21:59:28[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
21:59:28[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
21:59:28[12343]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/007.vdr'
22:20:29[12343]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/008.vdr'
22:36:48[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
22:36:48[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
22:36:48[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
22:36:49[15989]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/008.vdr'
22:39:07[15989]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/009.vdr'
23:14:32[29530]: recording to '/video0/ESA/2005-01-14.07:00.50.07.rec/014.vdr'
23:25:24[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
23:25:24[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
23:25:24[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
23:25:24[20830]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/009.vdr'
23:26:51[29270]: timer 132 (272 1051-1200 'ESA') stop
23:28:34[29270]: removing recording /video0/ESA/2005-01-14.07:00.50.07.del
23:28:34[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/001.vdr
23:28:38[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/001.vdr
23:28:38[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/index.vdr
23:28:38[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/002.vdr
23:28:44[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/002.vdr
23:28:44[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/003.vdr
23:28:49[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/003.vdr
23:28:49[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/004.vdr
23:28:54[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/004.vdr
23:28:54[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/005.vdr
23:28:59[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/005.vdr
23:28:59[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del
23:28:59[29270]: ERROR: /video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr: No such file or directory
23:58:50[29305]: changing name of channel 272 from 'ESA,;ASTRA' to 'ASTRA Vision 2,;ASTRA'
00:43:44[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
00:43:44[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
00:44:04[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
00:44:04[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
00:44:22[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
00:44:22[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
00:44:55[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
00:44:55[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
02:05:05[29270]: executing '/video/pwroff 1105782660 31555 272 "ESA" 0'
02:40:07[29270]: executing '/video/pwroff 1105782660 29453 272 "ESA" 0'
03:15:09[29270]: executing '/video/pwroff 1105782660 27351 272 "ESA" 0'
03:50:11[29270]: executing '/video/pwroff 1105782660 25249 272 "ESA" 0'
04:25:13[29270]: executing '/video/pwroff 1105782660 23147 272 "ESA" 0'
05:00:17[29270]: executing '/video/pwroff 1105782660 21043 272 "ESA" 0'
05:50:39[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
05:50:39[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
06:32:12[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
06:32:12[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr


VDR seems to have deleted the first recordings, or?
Why?

When had VDR made the .del?
Why?

Why only the first some but not all?

Why is it removing the files, but not the links?
Why does vdr simply display a blackscreen, 
if it can't open the file?


Jan 14 23:25:01 msi /USR/SBIN/CRON[20789]: (root) CMD
(/usr/local/sbin/concheck > /tmp/cron.xxx 2&>1)
23:25:24[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
23:25:24[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
23:25:24[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
23:25:24[20830]: dvbplayer thread started (pid=20830,tid=1916939)
23:25:24[20830]: playing'/video0/ESA/2005-01-14.07:00.50.07.rec/009.vdr'
23:25:24[20830]: resuming replay at index 824505 (9:09:40.06)
23:25:24[20831]: non blocking file reader thread started (pid=20831, tid=1933328)
23:25:25[20830]: SetBrokenLink: no GOP header found in videopacket
23:25:40[20831]: non blocking file reader thread ended(pid=20831, tid=1933328)
23:25:40[20830]: dvbplayer thread ended (pid=20830,tid=1916939)
23:25:41[29270]: switching to channel 272
23:25:46[29270]: timer 132 deactivated
23:25:54[29270]: editing timer 132
23:26:41[29270]: timer 132 modified (inactive)
23:26:44[29270]: timer 132 activated
23:26:47[29270]: timer 132 deactivated
23:26:49[29270]: editing timer 132
23:26:50[29531]: recording thread ended (pid=29531, tid=327693)
23:26:51[29533]: TS buffer on device 2 thread ended (pid=29533, tid=360463)
23:26:51[29532]: buffer stats: 111108 (5%) used
23:26:51[29532]: receiver on device 2 thread ended (pid=29532, tid=344078)
23:26:51[29530]: file writer thread ended (pid=29530, tid=311308)
23:26:51[29270]: buffer stats: 142504 (2%) used
23:26:51[29270]: timer 132 (272 1051-1200 'ESA') stop
23:26:57[29270]: timer 132 activated
23:27:03[29270]: switching to channel 1
23:27:03[29270]: creating directory /vtx/S19.2E-1-1101-28106
23:27:04[20975]: receiver on device 1 thread started (pid=20975, tid=1949707)
23:27:04[20976]: TS buffer on device 1 thread started (pid=20976, tid=1966092)
23:27:04[29316]: channel 16 (BR-alpha) event 23:00 'Endlich Schnee!' status 4
23:27:04[29308]: channel 22 (Phoenix) event 22:30 'Der Glanz von Berlin' status 4
23:27:05[29316]: channel 11 (SR Fernsehen Südwest) event 22:00 'Nachtcaf' status 4
23:27:05[29308]: channel 14 (Bayerisches FS) event 22:30 'Grünwald Freitagscomedy' status 4
23:27:05[29316]: channel 10 (SÜDWEST BW) event 22:00


The critial areal fully:


23:25:01 msi /USR/SBIN/CRON[20789]: (root) CMD (/usr/local/sbin/concheck > /tmp/cron.xxx 2&>1)
23:25:24[29270]: replay /video0/ESA/2005-01-14.07:00.50.07.rec
23:25:24[29270]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/001.vdr'
23:25:24[29270]: loading /video0/ESA/2005-01-14.07:00.50.07.rec//marks.vdr
23:25:24[20830]: dvbplayer thread started (pid=20830, tid=1916939)
23:25:24[20830]: playing '/video0/ESA/2005-01-14.07:00.50.07.rec/009.vdr'
23:25:24[20830]: resuming replay at index 824505 (9:09:40.06)
23:25:24[20831]: non blocking file reader thread started (pid=20831, tid=1933328)
23:25:25[20830]: SetBrokenLink: no GOP header found in video packet
23:25:40[20831]: non blocking file reader thread ended (pid=20831, tid=1933328)
23:25:40[20830]: dvbplayer thread ended (pid=20830, tid=1916939)
23:25:41[29270]: switching to channel 272
23:25:46[29270]: timer 132 deactivated
23:25:54[29270]: editing timer 132
23:26:41[29270]: timer 132 modified (inactive)
23:26:44[29270]: timer 132 activated
23:26:47[29270]: timer 132 deactivated
23:26:49[29270]: editing timer 132
23:26:50[29531]: recording thread ended (pid=29531, tid=327693)
23:26:51[29533]: TS buffer on device 2 thread ended (pid=29533, tid=360463)
23:26:51[29532]: buffer stats: 111108 (5%) used
23:26:51[29532]: receiver on device 2 thread ended (pid=29532, tid=344078)
23:26:51[29530]: file writer thread ended (pid=29530, tid=311308)
23:26:51[29270]: buffer stats: 142504 (2%) used
23:26:51[29270]: timer 132 (272 1051-1200 'ESA') stop
23:26:57[29270]: timer 132 activated
23:27:03[29270]: switching to channel 1
23:27:03[29270]: creating directory /vtx/S19.2E-1-1101-28106
23:27:04[20975]: receiver on device 1 thread started (pid=20975, tid=1949707)
23:27:04[20976]: TS buffer on device 1 thread started (pid=20976, tid=1966092)
23:27:04[29316]: channel 16 (BR-alpha) event 23:00 'Endlich Schnee!' status 4
23:27:04[29308]: channel 22 (Phoenix) event 22:30 'Der Glanz von Berlin' status 4
23:27:05[29316]: channel 11 (SR Fernsehen Südwest) event 22:00 'Nachtcafe' status 4
23:27:05[29308]: channel 14 (Bayerisches FS) event 22:30 'Grünwald Freitagscomedy' status 4
23:27:05[29316]: channel 10 (SÜDWEST BW) event 22:00 'Nachtcaf' status 4
23:27:05[29316]: channel 13 (WDR Köln) event 22:00 'Donna Leon - Sanft entschlafen' status 4
23:27:05[29308]: channel 12 (hr-fernsehen) event 22:15 'NDR Talk Show' status 4
23:27:05[29316]: channel 18 (arte) event 23:10 'Die Nacht der offenen Türen' status 4
23:27:05[29316]: channel 1 (Das Erste) event 22:45 'Sexy Beast - Bankraub wider Willen' status 4
23:27:08[29316]: changing pids of channel 156 from 201+201:202:204 to 701+701:702:204
23:27:14[29270]: switching to channel 74
23:27:15[20976]: TS buffer on device 1 thread ended (pid=20976, tid=1966092)
23:27:15[20975]: buffer stats: 4136 (0%) used
23:27:15[20975]: receiver on device 1 thread ended (pid=20975, tid=1949707)
23:27:15[29270]: buffer stats: 0 (0%) used
23:27:16 msi kernel: setting parameters for 771
23:27:16[20994]: transfer thread started (pid=20994, tid=1998859)
23:27:16[20995]: receiver on device 4 thread started (pid=20995, tid=2015244)
23:27:16[20996]: TS buffer on device 4 thread started (pid=20996, tid=2031629)
23:27:16 msi kernel: setting parameters for 771
23:27:16 msi last message repeated 2 times
...only zapping arround...
23:28:07[29316]: changing pids of channel 156 from 701+701:702:204 to 901+901:902:204
23:28:34[29270]: removing recording /video0/ESA/2005-01-14.07:00.50.07.del
23:28:34[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/001.vdr
23:28:38[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/001.vdr
23:28:38[29270]: removing /video0/ESA/2005-01-14.07:00.50.07.del/index.vdr
23:28:38[29270]: removing /video1/ESA/2005-01-14.07:00.50.07.rec/002.vdr
Follow-Ups:
- [vdr] Re: VDR deletes fresh recordings
  - From: Klaus Schmidinger <Klaus.Schmidinger@cadsoft.de>
References:
- [vdr] Re: VDR deletes fresh recordings
  - From: Klaus Schmidinger <Klaus.Schmidinger@cadsoft.de>
Prev by Date: [vdr] Re: Default video dir
Next by Date: [vdr] Re: Default video dir
Previous by thread: [vdr] Re: VDR deletes fresh recordings
Next by thread: [vdr] Re: VDR deletes fresh recordings
Index(es):
- Date
- Thread
Home | Main Index | Thread Index