2005-10-02, 03:04 AM
When you have the GBPVRConvert set to run ASAP and you have back to back recordings scheduled the process doesn't work because it tries to run GBPVRConvert on the second recording instead of the first. Log file details these events
10/1/2005 6:59:00 PM.062 INFO [23] Found new programme requiring recording: Cops
10/1/2005 6:59:00 PM.109 VERBOSE [23] Changing to channel (GBPVR.Backend.Common.NullTunerController): 3
10/1/2005 6:59:00 PM.125 VERBOSE [23] Requesting IRecordingSource to start recording: D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 6:59:00 PM.125 VERBOSE [23] DirectRecordingSource about to start recording oid:1 channel:3
10/1/2005 6:59:01 PM.812 VERBOSE [23] recording started
10/1/2005 6:59:01 PM.812 VERBOSE [23] Programme is now recording
10/1/2005 6:59:01 PM.812 VERBOSE [23] RecordingFactory.save()
10/1/2005 6:59:01 PM.875 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 6:59:01 PM.921 VERBOSE [23] getValue() loading new key/value into cache: /settings/ProcessCommercialsDuringRecording
10/1/2005 6:59:01 PM.921 VERBOSE [23] getValue: /settings/ProcessCommercialsDuringRecording : false
10/1/2005 6:59:01 PM.921 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg to parallel processing queue
10/1/2005 6:59:01 PM.921 VERBOSE [184] getValue() loading new key/value into cache: /settings/CommercialDetectionScheme
10/1/2005 6:59:01 PM.921 VERBOSE [184] getValue: /settings/CommercialDetectionScheme : 0
10/1/2005 6:59:01 PM.921 INFO [184] Parallel Processing thread starting...
10/1/2005 6:59:01 PM.968 INFO [184] Running ParallelProcessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 6:59:02 PM.000 VERBOSE [23] RecordingFactory.getExtrasForGroup(1)
10/1/2005 6:59:02 PM.000 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 7:30:00 PM.125 INFO [23] Found new programme requiring recording: Cops
10/1/2005 7:30:00 PM.125 INFO [23] Stopping existing recording on this capture source. (removing from currentlyRecording)
10/1/2005 7:30:00 PM.125 VERBOSE [23] Stopping recording oid:1
10/1/2005 7:30:00 PM.375 VERBOSE [23] RecordingFactory.save()
10/1/2005 7:30:00 PM.375 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 7:30:00 PM.437 VERBOSE [23] getValue cached value: /settings/ProcessCommercialsDuringRecording : false
10/1/2005 7:30:00 PM.437 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg to commercial detection queue
10/1/2005 7:30:00 PM.437 VERBOSE [23] Adding Cops to commercial detection queue
10/1/2005 7:30:00 PM.437 VERBOSE [131] getValue cached value: /settings/CommercialDetectionScheme : 0
10/1/2005 7:30:00 PM.437 INFO [131] Post Processing thread starting...
10/1/2005 7:30:00 PM.437 INFO [131] Running postprocessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 7:30:00 PM.703 VERBOSE [23] Changing to channel (GBPVR.Backend.Common.NullTunerController): 3
10/1/2005 7:30:00 PM.703 VERBOSE [23] Requesting IRecordingSource to start recording: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 7:30:00 PM.750 VERBOSE [23] DirectRecordingSource about to start recording oid:1 channel:3
10/1/2005 7:30:01 PM.953 VERBOSE [23] recording started
10/1/2005 7:30:01 PM.953 VERBOSE [23] Programme is now recording
10/1/2005 7:30:01 PM.953 VERBOSE [23] RecordingFactory.save()
10/1/2005 7:30:01 PM.953 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 7:30:01 PM.953 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 7:30:02 PM.000 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg to parallel processing queue
10/1/2005 7:30:02 PM.000 VERBOSE [23] RecordingFactory.getExtrasForGroup(2)
10/1/2005 7:30:02 PM.000 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 7:30:02 PM.187 INFO [184] Running ParallelProcessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 7:30:05 PM.515 INFO [131] Commercial Detection has completed.
10/1/2005 7:30:11 PM.312 VERBOSE [17] RecordingFactory.loadScheduleRecording()
10/1/2005 7:30:11 PM.312 VERBOSE [17] getDatabaseConnection() creating new connection
10/1/2005 7:30:11 PM.312 VERBOSE [17] About to start transcode of: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 7:30:11 PM.312 VERBOSE [17] getValue cached value: /settings/AutoConvertMpgToFormat : XviD MPEG-4 Codec
10/1/2005 7:30:11 PM.312 VERBOSE [17] About to run transcode command: GBPVRConvert.exe "D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg" "D:\PVR\Recordings\Cops\Cops_20051001_19302000.avi" "XviD MPEG-4 Codec" 32
10/1/2005 7:31:11 PM.437 INFO [17] File transcode has completed. Updating database with new name...
10/1/2005 7:31:11 PM.437 VERBOSE [17] RecordingFactory.save()
10/1/2005 7:31:11 PM.437 VERBOSE [17] Save(): Updated existing scheduled recording
10/1/2005 7:31:11 PM.437 VERBOSE [17] getValue() loading new key/value into cache: /settings/AutoDeleteConvertedFiles
10/1/2005 7:31:11 PM.437 VERBOSE [17] getValue: /settings/AutoDeleteConvertedFiles : false
10/1/2005 8:01:00 PM.031 VERBOSE [23] Recording has passed endtime+padding. Stopping.
10/1/2005 8:01:00 PM.031 VERBOSE [23] Stopping recording oid:1
10/1/2005 8:01:00 PM.171 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg to commercial detection queue
10/1/2005 8:01:00 PM.171 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg to commercial detection queue
10/1/2005 8:01:00 PM.203 VERBOSE [23] RecordingStatus is now RecordingStatus.RECORDING_COMPETED
10/1/2005 8:01:00 PM.203 VERBOSE [23] RecordingFactory.save()
10/1/2005 8:01:00 PM.203 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 8:01:00 PM.203 VERBOSE [23] RecordingFactory.loadSchedule()
10/1/2005 8:01:00 PM.203 VERBOSE [23] getValue cached value: /settings/AutoRemoveMissingRecordings : true
10/1/2005 8:01:00 PM.250 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 8:01:00 PM.281 VERBOSE [23] checking pending recordings
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Cops
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Cops
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Simpsons
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Family Guy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Grey's Anatomy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Kitchen Confidential
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Prison Break
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Ultimate Fighter
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Bones
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking House
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Real World
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Lost
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The O.C.
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Reunion
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\Without a Trace\Without a Trace_20050929_21002200.mpg
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Without a Trace
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\The Girls Next Door\The Girls Next Door_20051001_14001430.avi
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\Cops\Cops_20051001_19302000.avi
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Simpsons
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: The Simpsons
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Family Guy
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Family Guy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Grey's Anatomy
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Grey's Anatomy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Kitchen Confidential
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Kitchen Confidential
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Prison Break
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Prison Break
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Real World
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: The Real World
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Lost
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Lost
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Without a Trace
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Without a Trace
10/1/2005 8:01:00 PM.484 VERBOSE [23] RecordingFactory.save()
10/1/2005 8:01:00 PM.515 INFO [131] Running postprocessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 8:01:00 PM.734 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 8:01:05 PM.734 INFO [131] Commercial Detection has completed.
10/1/2005 8:01:11 PM.500 VERBOSE [17] getDatabaseConnection() creating new connection
10/1/2005 8:01:11 PM.500 VERBOSE [17] RecordingFactory.loadScheduleRecording()
10/1/2005 8:01:11 PM.500 VERBOSE [17] getDatabaseConnection() creating new connection
10/1/2005 8:01:11 PM.500 VERBOSE [17] About to start transcode of: D:\PVR\Recordings\Cops\Cops_20051001_19302000.avi
10/1/2005 6:59:00 PM.062 INFO [23] Found new programme requiring recording: Cops
10/1/2005 6:59:00 PM.109 VERBOSE [23] Changing to channel (GBPVR.Backend.Common.NullTunerController): 3
10/1/2005 6:59:00 PM.125 VERBOSE [23] Requesting IRecordingSource to start recording: D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 6:59:00 PM.125 VERBOSE [23] DirectRecordingSource about to start recording oid:1 channel:3
10/1/2005 6:59:01 PM.812 VERBOSE [23] recording started
10/1/2005 6:59:01 PM.812 VERBOSE [23] Programme is now recording
10/1/2005 6:59:01 PM.812 VERBOSE [23] RecordingFactory.save()
10/1/2005 6:59:01 PM.875 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 6:59:01 PM.921 VERBOSE [23] getValue() loading new key/value into cache: /settings/ProcessCommercialsDuringRecording
10/1/2005 6:59:01 PM.921 VERBOSE [23] getValue: /settings/ProcessCommercialsDuringRecording : false
10/1/2005 6:59:01 PM.921 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg to parallel processing queue
10/1/2005 6:59:01 PM.921 VERBOSE [184] getValue() loading new key/value into cache: /settings/CommercialDetectionScheme
10/1/2005 6:59:01 PM.921 VERBOSE [184] getValue: /settings/CommercialDetectionScheme : 0
10/1/2005 6:59:01 PM.921 INFO [184] Parallel Processing thread starting...
10/1/2005 6:59:01 PM.968 INFO [184] Running ParallelProcessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 6:59:02 PM.000 VERBOSE [23] RecordingFactory.getExtrasForGroup(1)
10/1/2005 6:59:02 PM.000 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 7:30:00 PM.125 INFO [23] Found new programme requiring recording: Cops
10/1/2005 7:30:00 PM.125 INFO [23] Stopping existing recording on this capture source. (removing from currentlyRecording)
10/1/2005 7:30:00 PM.125 VERBOSE [23] Stopping recording oid:1
10/1/2005 7:30:00 PM.375 VERBOSE [23] RecordingFactory.save()
10/1/2005 7:30:00 PM.375 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 7:30:00 PM.437 VERBOSE [23] getValue cached value: /settings/ProcessCommercialsDuringRecording : false
10/1/2005 7:30:00 PM.437 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg to commercial detection queue
10/1/2005 7:30:00 PM.437 VERBOSE [23] Adding Cops to commercial detection queue
10/1/2005 7:30:00 PM.437 VERBOSE [131] getValue cached value: /settings/CommercialDetectionScheme : 0
10/1/2005 7:30:00 PM.437 INFO [131] Post Processing thread starting...
10/1/2005 7:30:00 PM.437 INFO [131] Running postprocessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 7:30:00 PM.703 VERBOSE [23] Changing to channel (GBPVR.Backend.Common.NullTunerController): 3
10/1/2005 7:30:00 PM.703 VERBOSE [23] Requesting IRecordingSource to start recording: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 7:30:00 PM.750 VERBOSE [23] DirectRecordingSource about to start recording oid:1 channel:3
10/1/2005 7:30:01 PM.953 VERBOSE [23] recording started
10/1/2005 7:30:01 PM.953 VERBOSE [23] Programme is now recording
10/1/2005 7:30:01 PM.953 VERBOSE [23] RecordingFactory.save()
10/1/2005 7:30:01 PM.953 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 7:30:01 PM.953 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 7:30:02 PM.000 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg to parallel processing queue
10/1/2005 7:30:02 PM.000 VERBOSE [23] RecordingFactory.getExtrasForGroup(2)
10/1/2005 7:30:02 PM.000 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 7:30:02 PM.187 INFO [184] Running ParallelProcessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 7:30:05 PM.515 INFO [131] Commercial Detection has completed.
10/1/2005 7:30:11 PM.312 VERBOSE [17] RecordingFactory.loadScheduleRecording()
10/1/2005 7:30:11 PM.312 VERBOSE [17] getDatabaseConnection() creating new connection
10/1/2005 7:30:11 PM.312 VERBOSE [17] About to start transcode of: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 7:30:11 PM.312 VERBOSE [17] getValue cached value: /settings/AutoConvertMpgToFormat : XviD MPEG-4 Codec
10/1/2005 7:30:11 PM.312 VERBOSE [17] About to run transcode command: GBPVRConvert.exe "D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg" "D:\PVR\Recordings\Cops\Cops_20051001_19302000.avi" "XviD MPEG-4 Codec" 32
10/1/2005 7:31:11 PM.437 INFO [17] File transcode has completed. Updating database with new name...
10/1/2005 7:31:11 PM.437 VERBOSE [17] RecordingFactory.save()
10/1/2005 7:31:11 PM.437 VERBOSE [17] Save(): Updated existing scheduled recording
10/1/2005 7:31:11 PM.437 VERBOSE [17] getValue() loading new key/value into cache: /settings/AutoDeleteConvertedFiles
10/1/2005 7:31:11 PM.437 VERBOSE [17] getValue: /settings/AutoDeleteConvertedFiles : false
10/1/2005 8:01:00 PM.031 VERBOSE [23] Recording has passed endtime+padding. Stopping.
10/1/2005 8:01:00 PM.031 VERBOSE [23] Stopping recording oid:1
10/1/2005 8:01:00 PM.171 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg to commercial detection queue
10/1/2005 8:01:00 PM.171 VERBOSE [23] Adding D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg to commercial detection queue
10/1/2005 8:01:00 PM.203 VERBOSE [23] RecordingStatus is now RecordingStatus.RECORDING_COMPETED
10/1/2005 8:01:00 PM.203 VERBOSE [23] RecordingFactory.save()
10/1/2005 8:01:00 PM.203 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 8:01:00 PM.203 VERBOSE [23] RecordingFactory.loadSchedule()
10/1/2005 8:01:00 PM.203 VERBOSE [23] getValue cached value: /settings/AutoRemoveMissingRecordings : true
10/1/2005 8:01:00 PM.250 VERBOSE [23] getDatabaseConnection() creating new connection
10/1/2005 8:01:00 PM.281 VERBOSE [23] checking pending recordings
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Cops
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Cops
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Simpsons
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Family Guy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Grey's Anatomy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Kitchen Confidential
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Prison Break
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Ultimate Fighter
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Bones
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking House
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Real World
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Lost
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The O.C.
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Reunion
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\Without a Trace\Without a Trace_20050929_21002200.mpg
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Without a Trace
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\The Girls Next Door\The Girls Next Door_20051001_14001430.avi
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\Cops\Cops_20051001_19001930.mpg
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking D:\PVR\Recordings\Cops\Cops_20051001_19302000.avi
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Simpsons
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: The Simpsons
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Family Guy
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Family Guy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Grey's Anatomy
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Grey's Anatomy
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Kitchen Confidential
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Kitchen Confidential
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Prison Break
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Prison Break
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking The Real World
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: The Real World
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Lost
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Lost
10/1/2005 8:01:00 PM.281 VERBOSE [23] - checking Without a Trace
10/1/2005 8:01:00 PM.281 VERBOSE [23] pending: Without a Trace
10/1/2005 8:01:00 PM.484 VERBOSE [23] RecordingFactory.save()
10/1/2005 8:01:00 PM.515 INFO [131] Running postprocessing.bat on: D:\PVR\Recordings\Cops\Cops_20051001_19302000.mpg
10/1/2005 8:01:00 PM.734 VERBOSE [23] Save(): Updated existing scheduled recording
10/1/2005 8:01:05 PM.734 INFO [131] Commercial Detection has completed.
10/1/2005 8:01:11 PM.500 VERBOSE [17] getDatabaseConnection() creating new connection
10/1/2005 8:01:11 PM.500 VERBOSE [17] RecordingFactory.loadScheduleRecording()
10/1/2005 8:01:11 PM.500 VERBOSE [17] getDatabaseConnection() creating new connection
10/1/2005 8:01:11 PM.500 VERBOSE [17] About to start transcode of: D:\PVR\Recordings\Cops\Cops_20051001_19302000.avi
