Failed Recording

[Satori]

The service was set to automatic. I set it to restart upon failure on the "recovery" tab.

[Satori]

I set the service to restart upon failure and still had a problem. The logs are attached. This looks like the part where there was an issue (at 10am):

2007-10-08 08:58:53.019 VERBOSE [3] GETTING DATABASE CMD: SELECT * FROM RECORDING_SCHEDULE WHERE (status = 1 and manual_end_time > ?) or (status = 0 and manual_start_time > ? and manual_start_time < ?) or (status = 0 and manual_end_time > ? and manual_start_time < ?) order by manual_start_time
2007-10-08 08:58:53.019 VERBOSE [3] Checking for other recordings using device: DEVICE:Hauppauge PVR150MCE:2
2007-10-08 08:58:53.019 VERBOSE [3] getValue cached value: /settings/LiveTVDirectory : f:\Live
2007-10-08 08:58:53.019 VERBOSE [3] Getting current programme from cache
2007-10-08 08:58:53.019 VERBOSE [3] RenewLease finished
2007-10-08 08:59:23.033 VERBOSE [3] RenewLease 4
2007-10-08 08:59:23.033 VERBOSE [3] forcing refresh of next 2 hour events for all capture sources
2007-10-08 08:59:23.033 VERBOSE [3] GETTING DATABASE CMD: SELECT * FROM RECORDING_SCHEDULE WHERE (status = 1 and manual_end_time > ?) or (status = 0 and manual_start_time > ? and manual_start_time < ?) or (status = 0 and manual_end_time > ? and manual_start_time < ?) order by manual_start_time
2007-10-08 08:59:23.043 VERBOSE [3] Checking for other recordings using device: DEVICE:Hauppauge PVR150MCE:2
2007-10-08 08:59:23.043 VERBOSE [3] getValue cached value: /settings/LiveTVDirectory : f:\Live
2007-10-08 08:59:23.043 VERBOSE [3] Getting current programme from cache
2007-10-08 08:59:23.043 VERBOSE [3] RenewLease finished
2007-10-08 08:59:53.046 VERBOSE [3] RenewLease 4
2007-10-08 08:59:53.046 VERBOSE [3] forcing refresh of next 2 hour events for all capture sources
2007-10-08 08:59:53.046 VERBOSE [3] GETTING DATABASE CMD: SELECT * FROM RECORDING_SCHEDULE WHERE (status = 1 and manual_end_time > ?) or (status = 0 and manual_start_time > ? and manual_start_time < ?) or (status = 0 and manual_end_time > ? and manual_start_time < ?) order by manual_start_time
2007-10-08 08:59:53.046 VERBOSE [3] Checking for other recordings using device: DEVICE:Hauppauge PVR150MCE:2
2007-10-08 08:59:53.046 VERBOSE [3] getValue cached value: /settings/LiveTVDirectory : f:\Live
2007-10-08 08:59:53.046 VERBOSE [3] Getting current programme from cache
2007-10-08 08:59:53.046 VERBOSE [3] RenewLease finished
2007-10-08 09:00:07.977 VERBOSE [3] Received request to stop livetv: 4
2007-10-08 09:00:07.977 VERBOSE [3] Stopping live tv recording...
2007-10-08 09:00:07.977 VERBOSE [3] Stopping recording oid:4
2007-10-08 09:00:08.057 VERBOSE [7] Received request to stop livetv: 4
2007-10-08 09:00:08.057 VERBOSE [7] Stopping live tv recording...
2007-10-08 09:00:08.057 VERBOSE [7] Stopping recording oid:4
2007-10-08 09:00:38.782 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:00:38.782 VERBOSE [10] Checking if GB-PVR can remove: f:\Live\Sesame Street _4_20071008_08000900.mpg
2007-10-08 09:00:38.912 VERBOSE [10] LiveTV file removed: f:\Live\Sesame Street _4_20071008_08000900.mpg
2007-10-08 09:00:38.912 VERBOSE [10] GETTING DATABASE CMD: delete from LIVETV_FILE where filename = ?
2007-10-08 09:07:39.927 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:14:39.931 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:19:41.605 VERBOSE [11] Hour is currently 9. Conversions will happen between 1:00 and 6:00
2007-10-08 09:21:39.935 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:28:39.939 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:35:39.943 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:42:39.947 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:49:39.951 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 09:49:41.793 VERBOSE [11] Hour is currently 9. Conversions will happen between 1:00 and 6:00
2007-10-08 09:56:39.955 VERBOSE [10] GETTING DATABASE CMD: select * from LIVETV_FILE order by creation_date
2007-10-08 10:00:00.133 INFO [5] Found new programme requiring recording: Super Why!
2007-10-08 10:00:00.133 VERBOSE [5] GETTING DATABASE CMD: select source_channel_number from SOURCE_CHANNEL_MAPPING where capture_source_oid=3 and channel_oid=45
2007-10-08 10:00:00.133 VERBOSE [5] Changing to channel (GBPVR.Backend.Common.NullTunerController): 13
2007-10-08 10:00:00.133 VERBOSE [5] Requesting IRecordingSource to start recording: f:\Video\Super Why!_20071008_10001030.mpg
2007-10-08 10:00:00.133 VERBOSE [5] GETTING DATABASE CMD: select * from SOURCE_CHANNEL_MAPPING where capture_source_oid=3 and channel_oid=45
2007-10-08 10:00:00.133 VERBOSE [5] DirectRecordingSource about to start recording oid:3 channel:13
2007-10-08 10:00:00.413 ERROR [5] Error starting recording: System.AccessViolationException: Attempted to read or write protected memory. This is often an indication that other memory is corrupt.
at ATL.CComPtr<ICreateDevEnum>.{dtor}(CComPtr<ICreateDevEnum>* )
at ___CxxCallUnwindDtor(IntPtr pDtor, Void* pThis)
at FindFilterByName(_GUID* refCLSID, Char* decoderName, Int32 instanceRequired)
at NativeUtilities.ConexantRecorder.StartRecording(String cardType, ConexantRecordSource recordingSource, Int32 qualitySetting, Int32 board, String filename, Int32 channel, Int32 countryCode, Int32 tuningType, Boolean useStreamingEngineIfAvailable, Boolean previewMode, Boolean fmRadioMode, Boolean fmRadioPreviewMode)
at GBPVR.Backend.Common.DirectRecordingSource.startRecording(IRecordingSourceConfiguration config, Int32 qualitySetting, String filename, ChannelTuning channelTuning, Int32 minutes, Boolean useStreamingEngineIfAvailable)
at GBPVR.RecordingService.x93f60a55016d55c4.xcba1f05fb5a46d5f()
2007-10-08 10:00:00.413 VERBOSE [5] RecordingFactory.getExtrasForGroup(39)
2007-10-08 10:00:00.413 VERBOSE [5] GETTING DATABASE CMD: select * from REOCCURING_EXTRAS where recording_group=39
2007-10-08 10:00:00.613 VERBOSE [5] RecordingStatus is now RecordingStatus.RECORDING_COMPETED
2007-10-08 10:00:00.613 VERBOSE [5] RecordingFactory.save()
2007-10-08 10:00:00.613 VERBOSE [5] GETTING DATABASE CMD: update RECORDING_SCHEDULE set STATUS = 2, filename = ?, pre_pad_minutes = ?, post_pad_minutes = ?, quality_level = ?, manual_channel_oid = ?, manual_start_time = ?, manual_end_time = ?, capture_source_oid = ?, priority = ?, conversion_profile = ? where OID = 720
2007-10-08 10:00:00.613 VERBOSE [5] Save(): Updated existing scheduled recording
2007-10-08 10:00:00.613 VERBOSE [5] RecordingFactory.loadSchedule()
2007-10-08 10:00:00.613 VERBOSE [5] getValue cached value: /settings/AutoRemoveMissingRecordings : true
2007-10-08 10:00:00.613 VERBOSE [5] Purging any stale logically deleted recordings
2007-10-08 10:00:00.613 VERBOSE [5] GETTING DATABASE CMD: delete FROM RECORDING_SCHEDULE where status=6 and manual_start_time < ?
2007-10-08 10:00:00.613 VERBOSE [5] GETTING DATABASE CMD: SELECT * FROM RECORDING_SCHEDULE order by manual_start_time
2007-10-08 10:00:00.623 VERBOSE [5] GETTING DATABASE CMD: SELECT * from PROGRAMME where oid = 539105
2007-10-08 10:00:00.623 VERBOSE [5] GETTING DATABASE CMD: SELECT * from PROGRAMME where oid = 539165
2007-10-08 10:00:00.623 VERBOSE [5] GETTING DATABASE CMD: SELECT * from PROGRAMME where oid = 539099
2007-10-08 10:00:00.623 VERBOSE [5] GETTING DATABASE CMD: SELECT * from PROGRAMME where oid = 539195

This also looks odd:

2007-10-08 09:00:08.168 ERROR Unexpected error stopping recording. No details available.
2007-10-08 09:00:08.168 VERBOSE ConexantRecorder:one()
2007-10-08 09:00:08.208 ERROR Unexpected error stopping recording. No details available.
2007-10-08 09:00:08.208 VERBOSE ConexantRecorder:one()
2007-10-08 10:00:00.133 VERBOSE ConexantRecorder::StartRecording(1, 1, 13, 1)
2007-10-08 10:00:00.133 VERBOSE target filename: f:\Video\Super Why!_20071008_10001030.mpg
2007-10-08 10:00:00.133 VERBOSE Capture Device is based on BlackBird architecture
2007-10-08 10:00:00.133 VERBOSE Capture Device is using Conexant (derived) drivers
2007-10-08 10:00:00.133 VERBOSE Loading filter names
2007-10-08 10:00:00.133 VERBOSE Found names:
2007-10-08 10:00:00.133 VERBOSE Hauppauge WinTV PVR PCI II Crossbar
2007-10-08 10:00:00.143 VERBOSE Hauppauge WinTV PVR PCI II Capture
2007-10-08 10:00:00.143 VERBOSE Hauppauge WinTV PVR PCI II TvTuner
2007-10-08 10:00:00.143 VERBOSE Hauppauge WinTV PVR PCI II TvAudio
2007-10-08 10:00:00.223 VERBOSE FindFilterByName failed to locate filter: Hauppauge WinTV PVR PCI II Crossbar

[sub]

Based on the log sections above, it looks like the "Hauppauge WinTV PVR PCI II Crossbar" filter had gone awol - and was missing from your machine at the time GB-PVR needed to use it.

[Satori]

Is this a driver issue then? Any idea why it decided to take a break? Am I working it too hard? Did it strike for better working conditions? LOL

[sub]

I'd say it is a driver issue. I dont think I've ever seen one of these driver filters spontaneously disappear, but that appears to be what has happened here.