Deleting Favourites (set to Keep all, manual delete)

[redman]

Hi
I am having a problem with my sage.
I have been running it successfulyl for comeing up on 3 years now, so not a newbie question.

I have favourites set to "keep all, no auto delete"
And the have recently started to be deleted just after they have been recorded. there are NO further airings, etc so its not an IR decision.

the log file shows the following for each of the segments of a show:
Sat 10/14 16:00:03.492 Removing MediaFile because it failed verification:MediaFile[id=7042265 A[7028030,7028029,"Star Trek:Enterprise IV",48457@1014.15:00,60] host=pvr encodedBy=Hauppauge WinTV PVR PCI II Capture format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 25.0 fps 720x576 4:3 3800 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] \\pvr\videog\StarTrekEnterpriseIV-TheAenar-7028030-11.mpg, Seg0[Sat 10/14 15:55:00.172-Sat 10/14 16:00:00.038]]
Sat 10/14 16:00:03.591 Deleted media segment file \\pvr\videog\StarTrekEnterpriseIV-TheAenar-7028030-11.mpg dtime=98 len=0

Then it will show:
Sat 10/14 16:00:03.592 BAD Remove of non-existent MediaFile[id=7042265 A[7028030,7028029,"Star Trek:Enterprise IV",48457@1014.15:00,60] host=pvr encodedBy=Hauppauge WinTV PVR PCI II Capture format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 25.0 fps 720x576 4:3 3800 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]]] idxCode=12
Sat 10/14 16:00:03.595 Enforcing keep at most limits for the files...
Sat 10/14 16:00:03.606 needFreeSize = 0.7533 GB currFreeSize=15.950282752 GB

then later in the log it says:
Sat 10/14 16:00:03.989 Removing from schedule cause expired A[7028030,7028029,"Star Trek:Enterprise IV",48457@1014.15:00,60]
Sat 10/14 16:00:03.995 # Airs=438


Another program in the log file which has the same effect (i.e. deletes from recordsings) has the same favourites settings:
Sat 10/14 16:00:06.542 MARK 1 currRecord=A[7028032,7028031,"Prison Break Yr 1",48457@1014.16:00,60] enc=Hauppauge WinTV PVR PCI II Capture clients=[] ir=true
Sat 10/14 16:00:06.543 Seeker in AUTOMATIC mode nextRecord=A[7028036,7028035,"Invasion",48457@1014.18:00,60] nextTTA=7193458
Sat 10/14 16:00:06.544 newRecord=A[7028032,7028031,"Prison Break Yr 1",48457@1014.16:00,60]
Sat 10/14 16:00:06.544 Keeping record just the way it is.
Sat 10/14 16:00:06.546 Cleaning up PM
Sat 10/14 16:00:06.556 RootFile=\\pvr\videog fstype=null fileLength=0
Sat 10/14 16:00:06.557 VF processing job null nPlayin=false
Sat 10/14 16:00:06.558 Checking video directories for new files
Sat 10/14 16:00:06.559 isRec=false rd=3599280 base=381960
Sat 10/14 16:00:06.559 VF thread is now waiting for 0:00:00.000
Sat 10/14 16:00:06.563 MiniUI ServerSocket died from:java.net.SocketException: socket closed
java.net.SocketException: socket closed
java.net.SocketException: socket closed
java.net.SocketException: socket closed
Sat 10/14 16:00:06.598 Diskspace checking is running
Sat 10/14 16:00:06.599 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Sat 10/14 16:00:06.713 Error w/SageTV client connection:java.net.SocketException: socket closed
Sat 10/14 16:00:07.989 Enforcing keep at most limits for the files...
Sat 10/14 16:00:07.999 needFreeSize = 0.7533 GB currFreeSize=15.950282752 GB
Sat 10/14 16:00:08.000 Seeker waiting for 59 mins.
Sat 10/14 16:00:08.168 Killed Carny.
Sat 10/14 16:00:08.170 Killed Scheduler.
Sat 10/14 16:00:08.171 Error calling finishWatch from VF goodbye:java.lang.NullPointerException
Sat 10/14 16:00:08.172 Ministry is shutting down....destroying the converts in progress
Sat 10/14 16:00:08.174 Seeker.endRecord(Sat 10/14 16:00:08.174) currRecord=A[7028032,7028031,"Prison Break Yr 1",48457@1014.16:00,60]
Sat 10/14 16:00:08.175 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=\\pvr\videog\PrisonBreakYr1-CutePoisonEncoreChineseAndMalaySubtitles-7028032-0.mpg])
Sat 10/14 16:00:08.175 stopEncoding0 called
Sat 10/14 16:00:08.176 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=\\pvr\videog\PrisonBreakYr1-CutePoisonEncoreChineseAndMalaySubtitles-7028032-0.mpg] nPlayin=false
Sat 10/14 16:00:08.177 VF processing on UI Thread VFJob[InactiveFile r=0.0 t=0 file=null ifn=\\pvr\videog\PrisonBreakYr1-CutePoisonEncoreChineseAndMalaySubtitles-7028032-0.mpg]
Sat 10/14 16:00:08.177 VF processing job null nPlayin=false
Sat 10/14 16:00:08.180 isRec=false rd=3599280 base=381960

I was running 5.0.4 with Sage MCE. I use XMLTV epg, which looks fine.
I upgraded to 6.0 Beta to see if it would make any difference, and it didnt'.

Any ideas? WAF is very very low right about now.

[Opus4]

The first file listed show a file name ending with "-11.mpg". Getting up to the 11th segment for the recording could indicate that there are problems with the tuner & the resulting recordings are corrupt, so SageTV deletes them.

You could submit the logs to SageTV for their opinion on what is happening.

- Andy