Failing recordings

[macgyver]

My system has been running smoothly for a while now but over the last three days I've started to have failures, with logs showing the following:

Code:

2008-06-16 11:57:00.078    INFO    [5]    Found new programme requiring recording: Without a Trace    (16388)
2008-06-16 11:57:00.078    VERBOSE    [5]    Changing to channel (GBPVR.Backend.Common.NullTunerController): 124
2008-06-16 11:57:00.078    VERBOSE    [5]    Requesting IRecordingSource to start recording: F:\Without a Trace\Without a Trace_20080616_12001300.mpg
2008-06-16 11:57:00.078    VERBOSE    [5]    BDARecordingSource about to start recording  oid:2 channel:
2008-06-16 11:57:00.140    ERROR    [5]    Error starting recording: System.ArgumentOutOfRangeException: Length cannot be less than zero.
Parameter name: length
   at System.String.InternalSubStringWithChecks(Int32 startIndex, Int32 length, Boolean fAlwaysCopy)
   at NativeUtilities.BDARecorder.StartRecording(String cardType, Int32 board, String filename, IntPtr hwnd, ChannelTuning channelTuning, Int32 lnbLOF1, Int32 lnbSwitch, Int32 lnbLOF2, Int32 diseqc, Boolean powered, Boolean previewMode)
   at NativeUtilities.BDARecorder.StartRecording(String cardType, Int32 board, String filename, IntPtr hwnd, ChannelTuning channelTuning, Int32 lnbLOF1, Int32 lnbSwitch, Int32 lnbLOF2, Int32 diseqc, Boolean powered)
   at GBPVR.Backend.Common.BDARecordingSource.startRecording(IRecordingSourceConfiguration config, Int32 qualitySetting, String filename, ChannelTuning channelTuning, Int32 minutes, Boolean useStreamingEngineIfAvailable)
   at GBPVR.RecordingService.x93f60a55016d55c4.xcba1f05fb5a46d5f()
2008-06-16 11:57:00.140    VERBOSE    [5]    RecordingFactory.getExtrasForGroup(661)
2008-06-16 11:57:00.140    VERBOSE    [5]    ScheduledRecording is configured to keep a maximum of 2 file(s). Checking if we have too many...
2008-06-16 11:57:00.140    VERBOSE    [5]    RecordingFactory.loadSchedule()
2008-06-16 11:57:00.343    VERBOSE    [5]    Checking signal status on Hauppauge Nova-T (90002):1
2008-06-16 11:57:00.343    VERBOSE    [5]    RecordingStatus is now RecordingStatus.RECORDING_COMPETED
2008-06-16 11:57:00.343    VERBOSE    [5]    RecordingFactory.save()

I've been trying to think what I've changed recently and not come up with anything other than changing some channel numbers. I did accidently remove a channel from a tuner so had to re-add it but other than that there have been no major changes.

Anyone have any idea what might be causing this?
Logs for the last couple of days attached also.
Many thanks in advance for any help you can give.
[ATTACHMENT NOT FOUND]

[martint123]

Comparing two recordings on the same tuner makes me think that one channel isn't mapped properly. It looks like it the channel is enabled but doesn't have tuning data. Don't forget, watching uses a different source to recording with dual tuners when testing.

Martin


2008-06-15 22:26:20.937 INFO [5] Found new programme requiring recording: Commercial Breakdown (16500)
2008-06-15 22:26:20.937 VERBOSE [5] Changing to channel (GBPVR.Backend.Common.NullTunerController): 141
2008-06-15 22:26:20.968 VERBOSE [5] Requesting IRecordingSource to start recording: F:\Commercial Breakdown\Commercial Breakdown_20080615_22202300.mpg
2008-06-15 22:26:20.984 VERBOSE [5] BDARecordingSource about to start recording oid:2 channel:T:C42,641834,0,0,8~NID:12290~ONID:9018~TSID:4109~P:4173~V:600~A:601~L:eng~PMT:4173~LCN:1~N:>BBC ONE
2008-06-15 22:26:24.375 VERBOSE [5] Programme is now recording
2008-06-15 22:26:24.406 VERBOSE [5] RecordingFactory.save()
2008-06-15 22:26:24.406 VERBOSE [5] Save(): Updated existing scheduled recording
2008-06-15 22:26:24.406 VERBOSE [5] getValue() loading new key/value into cache: /settings/ProcessCommercialsDuringRecording
2008-06-15 22:26:24.406 VERBOSE [5] getValue: /settings/ProcessCommercialsDuringRecording : false
2008-06-15 22:26:24.406 VERBOSE [5] Adding F:\Commercial Breakdown\Commercial Breakdown_20080615_22202300.mpg to parallel processing queue
2008-06-15 22:26:24.421 VERBOSE [3] getValue() loading new key/value into cache: /settings/CommercialDetectionScheme
2008-06-15 22:26:24.421 VERBOSE [3] getValue: /settings/CommercialDetectionScheme : 0
2008-06-15 22:26:24.421 INFO [3] Parallel Processing thread starting...
2008-06-15 22:26:54.468 VERBOSE [5] Checking signal status on Hauppauge Nova-T (90002):1


2008-06-16 11:57:00.078 INFO [5] Found new programme requiring recording: Without a Trace (16388)
2008-06-16 11:57:00.078 VERBOSE [5] Changing to channel (GBPVR.Backend.Common.NullTunerController): 124
2008-06-16 11:57:00.078 VERBOSE [5] Requesting IRecordingSource to start recording: F:\Without a Trace\Without a Trace_20080616_12001300.mpg
2008-06-16 11:57:00.078 VERBOSE [5] BDARecordingSource about to start recording oid:2 channel:
2008-06-16 11:57:00.140 ERROR [5] Error starting recording: System.ArgumentOutOfRangeException: Length cannot be less than zero.
Parameter name: length
at System.String.InternalSubStringWithChecks(Int32 startIndex, Int32 length, Boolean fAlwaysCopy)
at NativeUtilities.BDARecorder.StartRecording(String cardType, Int32 board, String filename, IntPtr hwnd, ChannelTuning channelTuning, Int32 lnbLOF1, Int32 lnbSwitch, Int32 lnbLOF2, Int32 diseqc, Boolean powered, Boolean previewMode)
at NativeUtilities.BDARecorder.StartRecording(String cardType, Int32 board, String filename, IntPtr hwnd, ChannelTuning channelTuning, Int32 lnbLOF1, Int32 lnbSwitch, Int32 lnbLOF2, Int32 diseqc, Boolean powered)
at GBPVR.Backend.Common.BDARecordingSource.startRecording(IRecordingSourceConfiguration config, Int32 qualitySetting, String filename, ChannelTuning channelTuning, Int32 minutes, Boolean useStreamingEngineIfAvailable)
at GBPVR.RecordingService.x93f60a55016d55c4.xcba1f05fb5a46d5f()
2008-06-16 11:57:00.140 VERBOSE [5] RecordingFactory.getExtrasForGroup(661)
2008-06-16 11:57:00.140 VERBOSE [5] ScheduledRecording is configured to keep a maximum of 2 file(s). Checking if we have too many...
2008-06-16 11:57:00.140 VERBOSE [5] RecordingFactory.loadSchedule()
2008-06-16 11:57:00.343 VERBOSE [5] Checking signal status on Hauppauge Nova-T (90002):1
2008-06-16 11:57:00.343 VERBOSE [5] RecordingStatus is now RecordingStatus.RECORDING_COMPETED
2008-06-16 11:57:00.343 VERBOSE [5] RecordingFactory.save()
2008-06-16 11:57:00.343 VERBOSE [5] Save(): Updated existing scheduled recording
2008-06-16 11:57:00.343 VERBOSE [5] RecordingFactory.loadSchedule()
2008-06-16 11:57:00.343 VERBOSE [5] getValue cached value: /settings/AutoRemoveMissingRecordings : true
2008-06-16 11:57:00.343 VERBOSE [5] Purging any stale logically deleted recordings
2008-06-16 11:57:00.562 VERBOSE [5] getValue() loading new key/value into cache: /settings/CheckForRenamedRecordings
2008-06-16 11:57:00.562 VERBOSE [5] getValue: /settings/CheckForRenamedRecordings : true
2008-06-16 11:57:00.593 VERBOSE [5] addPreviousEpisodes()

[macgyver]

Ahh, beginning to see now, I think.
It's recording athletics at present so would be beaten to a pulp if I touch it now but I think that what may have happened is that some programs have been scheduled to record on a channel which has since been disabled. Once the athletics is safely recorded I'll check.
Many thanks for pointing me in the right direction, I had visions of having to embark on a major rebuild, but I think I'm close to solving it.

[martint123]

Ah, yes. I've seen similar when I mess around on my test machine - ISTR pending recordings seem to keep their oid pointer to the line in the database table. I thought that the full epg reload re-scheduled pending recordings, but I don't know enough about the workings to be sure.

[macgyver]

Sorted. Channel4 + 1 had been disabled on one tuner, not sure why but re-enabling it sorted it out.
Thanks