[mythtv] Empty and missing recording files when making multiple simultaneous dvb recordings

Roger James roger at beardandsandals.co.uk
Wed Jun 27 12:52:20 UTC 2012


Hi,

I am trying to track down a problem with missing or empty recording 
files. These seem to occur when multiple DVB recordings are starting at 
the same time and may be related to recording different channels on the 
same multiplex. My backend is running a fairly recent version of 
0.25-fixes (Sun May 27). It has two dvb-t cards and one dvb-s card each 
is which is set up to allow simultaneous recordings (3 on the dvb-t 
cards 2 on the dvb-s card). I have captured a log which shows the 
problem. This resulted in one recording with a no file (should be 
/myth2/1009_20120626210000.mpg) and one recording with a empty file 
(/myth4/1002_20120626210000.mpg 376 bytes). These recordings where on 
different channels on the same terrestrial multiplex. The channels also 
available via the DVB-S tuner. The only error message I see in the logs is


2012-06-26 21:00:02.655217 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:114 (run) - DVBSH(/dev/dvb/adapter201/frontend0): 
run(): end
2012-06-26 21:00:02.655519 W [2501/2511] TVRecEvent 
streamhandler.cpp:186 (Stop) - SH(/dev/dvb/adapter201/frontend0): 
Programmer Error: Start called before Stop finished
2012-06-26 21:00:02.655697 I [2501/2707] DVBRead 
dvbstreamhandler.cpp:107 (run) - DVBSH(/dev/dvb/adapter201/frontend0): 
run(): begin

I presume this is because the start and stop are happening on different 
threads.

I am not familiar enough with the code to track down what the real 
problem is. Any help will be gratefully received!

I have attached a full log and other data below. With some of my 
comments/guesses interspersed.

Thanks,

Roger

=============================================================
capturecard table
cardid,videodevice,audiodevice,vbidevice,cardtype,defaultinput,audioratelimit,hostname,dvb_swfilter,dvb_sat_type,dvb_wait_for_seqstart,skipbtaudio,dvb_on_demand,dvb_diseqc_type,firewire_speed,firewire_model,firewire_connection,signal_timeout,channel_timeout,dvb_tuning_delay,contrast,brightness,colour,hue,diseqcid,dvb_eitscan
13,/dev/dvb/adapter100/frontend0,NULL,NULL,DVB,DVBInput,NULL,myth,0,0,1,0,0,0,0,NULL,0,7000,20000,0,0,0,0,0,2,1
12,/dev/dvb/adapter100/frontend0,NULL,NULL,DVB,DVBInput,NULL,myth,0,0,1,0,0,NULL,0,NULL,0,7000,20000,0,0,0,0,0,2,0
3,/dev/dvb/adapter201/frontend0,,,DVB,DVBInput,NULL,myth,0,0,1,0,0,NULL,0,NULL,0,500,3000,0,0,0,0,0,0,0
4,/dev/dvb/adapter201/frontend0,NULL,NULL,DVB,DVBInput,NULL,myth,0,0,1,0,0,0,0,NULL,0,500,3000,0,0,0,0,0,0,1
5,/dev/dvb/adapter202/frontend0,,,DVB,DVBInput,NULL,myth,0,0,1,0,0,NULL,0,NULL,0,500,3000,0,0,0,0,0,0,0
6,/dev/dvb/adapter202/frontend0,NULL,NULL,DVB,DVBInput,NULL,myth,0,0,1,0,0,0,0,NULL,0,500,3000,0,0,0,0,0,0,1
10,/dev/dvb/adapter201/frontend0,NULL,NULL,DVB,DVBInput,NULL,myth,0,0,1,0,0,0,0,NULL,0,500,3000,0,0,0,0,0,0,1
11,/dev/dvb/adapter202/frontend0,NULL,NULL,DVB,DVBInput,NULL,myth,0,0,1,0,0,0,0,NULL,0,500,3000,0,0,0,0,0,0,1

=============================================================
cardinput table
cardinputid,cardid,sourceid,inputname,externalcommand,changer_device,changer_model,tunechan,startchan,displayname,dishnet_eit,recpriority,quicktune,schedorder,livetvorder
4,4,1,DVBInput,,NULL,NULL,NULL,9,"DVB-T card 1",0,10,1,4,10
3,3,1,DVBInput,NULL,NULL,NULL,NULL,9,"DVB-T card 1",0,10,1,3,11
5,5,1,DVBInput,NULL,NULL,NULL,NULL,3,"DVB-T Card 2",0,10,1,5,9
6,6,1,DVBInput,,NULL,NULL,NULL,2,"DVB-T Card 2",0,10,1,6,8
17,13,5,DVBInput,,NULL,NULL,NULL,1,S2-3200,0,0,1,17,1
16,12,5,DVBInput,NULL,NULL,NULL,NULL,33,S2-3200,0,0,1,16,2
14,10,1,DVBInput,,NULL,NULL,NULL,1,"DVB-T card 1",0,10,1,14,4
15,11,1,DVBInput,,NULL,NULL,NULL,1,"DVB-T Card 2",0,10,1,15,3

=======================================================
log
2012-06-26 20:59:36.340756 I [2501/2528] Scheduler tv_rec.cpp:298 
(RecordPending) - TVRec(3): RecordPending on inputid 3
2012-06-26 20:59:36.341874 I [2501/2528] Scheduler cardutil.cpp:1672 
(GetConflictingCards) - CardUtil:   Group ID 2
2012-06-26 20:59:36.342709 I [2501/2528] Scheduler cardutil.cpp:1692 
(GetConflictingCards) - CardUtil:   Card ID 4
2012-06-26 20:59:36.342728 I [2501/2528] Scheduler cardutil.cpp:1692 
(GetConflictingCards) - CardUtil:   Card ID 10
2012-06-26 20:59:36.342775 I [2501/2528] Scheduler tv_rec.cpp:298 
(RecordPending) - TVRec(4): RecordPending on inputid 3
2012-06-26 20:59:36.342841 I [2501/2528] Scheduler tv_rec.cpp:298 
(RecordPending) - TVRec(10): RecordPending on inputid 3
2012-06-26 20:59:36.346287 I [2501/2528] Scheduler tv_rec.cpp:298 
(RecordPending) - TVRec(4): RecordPending on inputid 4
2012-06-26 20:59:36.347310 I [2501/2528] Scheduler cardutil.cpp:1672 
(GetConflictingCards) - CardUtil:   Group ID 2
2012-06-26 20:59:36.348095 I [2501/2528] Scheduler cardutil.cpp:1692 
(GetConflictingCards) - CardUtil:   Card ID 3
2012-06-26 20:59:36.348112 I [2501/2528] Scheduler cardutil.cpp:1692 
(GetConflictingCards) - CardUtil:   Card ID 10
2012-06-26 20:59:36.348148 I [2501/2528] Scheduler tv_rec.cpp:298 
(RecordPending) - TVRec(3): RecordPending on inputid 4
2012-06-26 20:59:36.348210 I [2501/2528] Scheduler tv_rec.cpp:298 
(RecordPending) - TVRec(10): RecordPending on inputid 4

I assume that the both pending recordings are being found on all three 
cards giving six candidates in all.


2012-06-26 20:59:36.348980 I [2501/2528] Scheduler scheduler.cpp:2015 
(HandleReschedule) - Reschedule requested for id 0.
2012-06-26 20:59:36.349003 I [2501/2528] Scheduler scheduler.cpp:2015 
(HandleReschedule) - Reschedule requested for id 0.
2012-06-26 20:59:36.944862 I [2501/2511] TVRecEvent tv_rec.cpp:1521 
(HandlePendingRecordings) - TVRec(4): ASK_RECORDING 4 22 0 0
2012-06-26 20:59:36.945132 I [2501/2511] TVRecEvent tv_rec.cpp:1521 
(HandlePendingRecordings) - TVRec(4): ASK_RECORDING 4 22 0 0
2012-06-26 20:59:37.039517 I [2501/2508] TVRecEvent tv_rec.cpp:1521 
(HandlePendingRecordings) - TVRec(3): ASK_RECORDING 3 22 0 0
2012-06-26 20:59:37.039790 I [2501/2508] TVRecEvent tv_rec.cpp:1521 
(HandlePendingRecordings) - TVRec(3): ASK_RECORDING 3 22 0 0
2012-06-26 20:59:37.089666 I [2501/2517] TVRecEvent tv_rec.cpp:1521 
(HandlePendingRecordings) - TVRec(10): ASK_RECORDING 10 22 0 0
2012-06-26 20:59:37.089878 I [2501/2517] TVRecEvent tv_rec.cpp:1521 
(HandlePendingRecordings) - TVRec(10): ASK_RECORDING 10 22 0 0
2012-06-26 20:59:37.091592 I [2501/2528] Scheduler scheduler.cpp:2073 
(HandleReschedule) - Scheduled 124 items in 0.7 = 0.00 match + 0.74 place

The recording of "Line of Duty" is started on TVRec(3)

2012-06-26 21:00:00.001681 I [2501/2528] Scheduler tv_rec.cpp:407 
(StartRecording) - TVRec(3): StartRecording("Line of Duty")
2012-06-26 21:00:00.001913 I [2501/2528] Scheduler tv_rec.cpp:476 
(StartRecording) - TVRec(3): Checking input group recorders - begin
2012-06-26 21:00:00.006730 I [2501/2528] Scheduler tv_rec.cpp:553 
(StartRecording) - TVRec(3): Checking input group recorders - done
2012-06-26 21:00:00.298119 I [2501/2528] Scheduler tv_rec.cpp:782 
(StartedRecording) - TVRec(3): 
StartedRecording(1002_2012-06-26T21:00:00) 
fn(/myth4/1002_20120626210000.mpg)
2012-06-26 21:00:00.299620 I [2501/2528] Scheduler tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(CancelNextRecording,) -> RunMainLoop,
2012-06-26 21:00:00.299822 I [2501/2508] TVRecEvent tv_rec.cpp:1014 
(HandleStateChange) - TVRec(3): Changing from None to RecordingOnly
2012-06-26 21:00:00.299894 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) 
-> RunMainLoop,
2012-06-26 21:00:00.300103 I [2501/2508] TVRecEvent tv_rec.cpp:3385 
(HandleTuning) - TVRec(3): HandleTuning Request: 
Program(ProgramInfo(1002_20120626210000.mpg): channame(BBC 2) 
startts(Tue Jun 26 21:00:00 2012) endts(Tue Jun 26 22:00:00 2012)
              recstartts(Tue Jun 26 21:00:00 2012) recendts(Tue Jun 26 
22:00:00 2012)
              title(Line of Duty)) channel() input() flags(Recording,)
2012-06-26 21:00:00.300221 I [2501/2508] TVRecEvent mythdbcon.cpp:395 
(PurgeIdleConnections) - New DB connection, total: 15
2012-06-26 21:00:00.304478 I [2501/2508] TVRecEvent tv_rec.cpp:3474 
(TuningCheckForHWChange) - TVRec(3): HW Tuner: 3->3
2012-06-26 21:00:00.304531 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
2012-06-26 21:00:00.304554 I [2501/2508] TVRecEvent tv_rec.cpp:3407 
(HandleTuning) - TVRec(3): No recorder yet, calling TuningFrequency
2012-06-26 21:00:00.321749 I [2501/2508] TVRecEvent tv_rec.cpp:3709 
(TuningFrequency) - TVRec(3): Starting Signal Monitor
2012-06-26 21:00:00.321786 I [2501/2508] TVRecEvent tv_rec.cpp:1936 
(SetupSignalMonitor) - TVRec(3): SetupSignalMonitor(1, 0)
2012-06-26 21:00:00.329090 I [2501/2508] TVRecEvent tv_rec.cpp:1958 
(SetupSignalMonitor) - TVRec(3): Signal monitor successfully created
2012-06-26 21:00:00.329115 I [2501/2508] TVRecEvent tv_rec.cpp:1756 
(SetupDTVSignalMonitor) - TVRec(3): Setting up table monitoring.
2012-06-26 21:00:00.357758 I [2501/2508] TVRecEvent tv_rec.cpp:3919 
(load_profile) - Using profile 'Live TV' to record
2012-06-26 21:00:00.376701 I [2501/2508] TVRecEvent tv_rec.cpp:1833 
(SetupDTVSignalMonitor) - TVRec(3): DVB service_id 4287 on net_id 9018 
tsid 4168
2012-06-26 21:00:00.379407 I [2501/2508] TVRecEvent tv_rec.cpp:1854 
(SetupDTVSignalMonitor) - TVRec(3): Successfully set up DVB table 
monitoring.
2012-06-26 21:00:00.379847 I [2501/2508] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(3): SetFlags(SignalMonitorRunning,) -> 
RunMainLoop,SignalMonitorRunning,
2012-06-26 21:00:00.379880 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,SignalMonitorRunning,
2012-06-26 21:00:00.379908 I [2501/2508] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(3): SetFlags(WaitingForSignal,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2012-06-26 21:00:00.379935 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2012-06-26 21:00:00.379963 I [2501/2508] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(3): SetFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2012-06-26 21:00:00.386795 I [2501/2699] SignalMonitor 
streamhandler.cpp:45 (AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0x972e448) -- begin
2012-06-26 21:00:00.386822 I [2501/2699] SignalMonitor 
streamhandler.cpp:57 (AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0x972e448) -- locked
2012-06-26 21:00:00.387220 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:107 (run) - DVBSH(/dev/dvb/adapter201/frontend0): 
run(): begin
2012-06-26 21:00:00.387627 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x0
2012-06-26 21:00:00.387902 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x0
2012-06-26 21:00:00.407563 N [2501/2528] Scheduler autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 14 min
2012-06-26 21:00:00.407650 I [2501/2528] Scheduler scheduler.cpp:2464 
(HandleRecordingStatusChange) - Tuning recording: "Line of Duty": 
channel 1002 on cardid 3, sourceid 1

The recording of "Ethan Hawke on Macbeth" is started on TVRec(4) this is 
using the same card as the previous recording

2012-06-26 21:00:00.414152 I [2501/2528] Scheduler tv_rec.cpp:407 
(StartRecording) - TVRec(4): StartRecording("Ethan Hawke on Macbeth")
2012-06-26 21:00:00.414377 I [2501/2528] Scheduler tv_rec.cpp:476 
(StartRecording) - TVRec(4): Checking input group recorders - begin
2012-06-26 21:00:00.417701 I [2501/2528] Scheduler tv_rec.cpp:553 
(StartRecording) - TVRec(4): Checking input group recorders - done
2012-06-26 21:00:00.451463 I [2501/2528] Scheduler tv_rec.cpp:782 
(StartedRecording) - TVRec(4): 
StartedRecording(1009_2012-06-26T21:00:00) 
fn(/myth2/1009_20120626210000.mpg)
2012-06-26 21:00:00.452948 I [2501/2528] Scheduler tv_rec.cpp:4173 
(ClearFlags) - TVRec(4): ClearFlags(CancelNextRecording,) -> RunMainLoop,
2012-06-26 21:00:00.453110 I [2501/2511] TVRecEvent tv_rec.cpp:1014 
(HandleStateChange) - TVRec(4): Changing from None to RecordingOnly
2012-06-26 21:00:00.453219 I [2501/2511] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(4): ClearFlags(FrontendReady,CancelNextRecording,) 
-> RunMainLoop,
2012-06-26 21:00:00.453433 I [2501/2511] TVRecEvent tv_rec.cpp:3385 
(HandleTuning) - TVRec(4): HandleTuning Request: 
Program(ProgramInfo(1009_20120626210000.mpg): channame(BBC 4) 
startts(Tue Jun 26 21:00:00 2012) endts(Tue Jun 26 22:00:00 2012)
              recstartts(Tue Jun 26 21:00:00 2012) recendts(Tue Jun 26 
22:00:00 2012)
              title(Ethan Hawke on Macbeth)) channel() input() 
flags(Recording,)
2012-06-26 21:00:00.457565 I [2501/2511] TVRecEvent tv_rec.cpp:3474 
(TuningCheckForHWChange) - TVRec(4): HW Tuner: 4->4
2012-06-26 21:00:00.457599 I [2501/2511] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(4): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
2012-06-26 21:00:00.457623 I [2501/2511] TVRecEvent tv_rec.cpp:3407 
(HandleTuning) - TVRec(4): No recorder yet, calling TuningFrequency
2012-06-26 21:00:00.475642 I [2501/2511] TVRecEvent tv_rec.cpp:3709 
(TuningFrequency) - TVRec(4): Starting Signal Monitor
2012-06-26 21:00:00.475671 I [2501/2511] TVRecEvent tv_rec.cpp:1936 
(SetupSignalMonitor) - TVRec(4): SetupSignalMonitor(1, 0)
2012-06-26 21:00:00.482865 I [2501/2511] TVRecEvent tv_rec.cpp:1958 
(SetupSignalMonitor) - TVRec(4): Signal monitor successfully created
2012-06-26 21:00:00.482884 I [2501/2511] TVRecEvent tv_rec.cpp:1756 
(SetupDTVSignalMonitor) - TVRec(4): Setting up table monitoring.
2012-06-26 21:00:00.488408 I [2501/2511] TVRecEvent tv_rec.cpp:3919 
(load_profile) - Using profile 'Live TV' to record
2012-06-26 21:00:00.488499 I [2501/2511] TVRecEvent tv_rec.cpp:1833 
(SetupDTVSignalMonitor) - TVRec(4): DVB service_id 4544 on net_id 9018 
tsid 4168
2012-06-26 21:00:00.488569 I [2501/2511] TVRecEvent tv_rec.cpp:1854 
(SetupDTVSignalMonitor) - TVRec(4): Successfully set up DVB table 
monitoring.
2012-06-26 21:00:00.489029 I [2501/2511] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(4): SetFlags(SignalMonitorRunning,) -> 
RunMainLoop,SignalMonitorRunning,
2012-06-26 21:00:00.489061 I [2501/2511] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(4): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,SignalMonitorRunning,
2012-06-26 21:00:00.489087 I [2501/2511] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(4): SetFlags(WaitingForSignal,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2012-06-26 21:00:00.489113 I [2501/2511] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(4): ClearFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2012-06-26 21:00:00.489171 I [2501/2511] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(4): SetFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2012-06-26 21:00:00.496057 I [2501/2702] SignalMonitor 
streamhandler.cpp:45 (AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0xffffffffa1031e80) -- begin
2012-06-26 21:00:00.496092 I [2501/2702] SignalMonitor 
streamhandler.cpp:57 (AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0xffffffffa1031e80) -- locked
2012-06-26 21:00:00.523990 N [2501/2528] Scheduler autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:00:00.524080 I [2501/2528] Scheduler scheduler.cpp:2464 
(HandleRecordingStatusChange) - Tuning recording: "Ethan Hawke on 
Macbeth": channel 1009 on cardid 4, sourceid 1
2012-06-26 21:00:00.649997 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:188 (RunTS) - DVBSH(/dev/dvb/adapter201/frontend0): 
RunTS(): begin
2012-06-26 21:00:00.650069 I [2501/2699] SignalMonitor 
streamhandler.cpp:91 (AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0x972e448) -- end
2012-06-26 21:00:00.650120 I [2501/2702] SignalMonitor 
streamhandler.cpp:91 (AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0xffffffffa1031e80) -- end
2012-06-26 21:00:00.650166 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x0
2012-06-26 21:00:00.650409 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1
2012-06-26 21:00:00.650526 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x10
2012-06-26 21:00:00.650594 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x11
2012-06-26 21:00:00.650666 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x14
2012-06-26 21:00:00.723683 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:402 (CreatePATSingleProgram) - 
CreatePATSingleProgram()
2012-06-26 21:00:00.723702 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:403 (CreatePATSingleProgram) - PAT in input stream
2012-06-26 21:00:00.723991 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:404 (CreatePATSingleProgram) - Program 
Association Section
  PSIP tableID(0x0) length(101) extension(0x1048)
       version(3) current(1) section(0) last_section(0)
       tsid(4168) programCount(23)
   program number     0 has PID 0x0010
   program number  4168 has PID 0x0064
   program number  4287 has PID 0x00c8
   program number  4288 has PID 0x012c
   program number  4352 has PID 0x01f4
   program number  4416 has PID 0x0a8c
   program number  4544 has PID 0x0190
   program number  4608 has PID 0x02bc
   program number  4672 has PID 0x0320
   program number  4736 has PID 0x0258
   program number  5632 has PID 0x0578
   program number  5696 has PID 0x05dc
   program number  5760 has PID 0x0640
   program number  5824 has PID 0x06a4
   program number  5888 has PID 0x0708
   program number  5952 has PID 0x076c
   program number  6016 has PID 0x07d0
   program number  6720 has PID 0x03e8
   program number  6784 has PID 0x044c
   program number  6848 has PID 0x04b0
   program number  6912 has PID 0x0514
   program number  7168 has PID 0x0384
   program number  7232 has PID 0x0b54

2012-06-26 21:00:00.724013 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:412 (CreatePATSingleProgram) - 
desired_program(4287) pid(0xc8)
2012-06-26 21:00:00.724085 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:453 (CreatePATSingleProgram) - pmt_pid(0xc8)
2012-06-26 21:00:00.724097 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:454 (CreatePATSingleProgram) - PAT for output stream
2012-06-26 21:00:00.724156 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:455 (CreatePATSingleProgram) - Program 
Association Section
  PSIP tableID(0x0) length(13) extension(0x1048)
       version(3) current(1) section(0) last_section(0)
       tsid(4168) programCount(1)
   program number     1 has PID 0x00c8

2012-06-26 21:00:00.724223 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:402 (CreatePATSingleProgram) - 
CreatePATSingleProgram()
2012-06-26 21:00:00.724232 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:403 (CreatePATSingleProgram) - PAT in input stream
2012-06-26 21:00:00.724469 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:404 (CreatePATSingleProgram) - Program 
Association Section
  PSIP tableID(0x0) length(101) extension(0x1048)
       version(3) current(1) section(0) last_section(0)
       tsid(4168) programCount(23)
   program number     0 has PID 0x0010
   program number  4168 has PID 0x0064
   program number  4287 has PID 0x00c8
   program number  4288 has PID 0x012c
   program number  4352 has PID 0x01f4
   program number  4416 has PID 0x0a8c
   program number  4544 has PID 0x0190
   program number  4608 has PID 0x02bc
   program number  4672 has PID 0x0320
   program number  4736 has PID 0x0258
   program number  5632 has PID 0x0578
   program number  5696 has PID 0x05dc
   program number  5760 has PID 0x0640
   program number  5824 has PID 0x06a4
   program number  5888 has PID 0x0708
   program number  5952 has PID 0x076c
   program number  6016 has PID 0x07d0
   program number  6720 has PID 0x03e8
   program number  6784 has PID 0x044c
   program number  6848 has PID 0x04b0
   program number  6912 has PID 0x0514
   program number  7168 has PID 0x0384
   program number  7232 has PID 0x0b54

2012-06-26 21:00:00.724489 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:412 (CreatePATSingleProgram) - 
desired_program(4544) pid(0x190)
2012-06-26 21:00:00.724506 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:453 (CreatePATSingleProgram) - pmt_pid(0x190)
2012-06-26 21:00:00.724515 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:454 (CreatePATSingleProgram) - PAT for output stream
2012-06-26 21:00:00.724567 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:455 (CreatePATSingleProgram) - Program 
Association Section
  PSIP tableID(0x0) length(13) extension(0x1048)
       version(3) current(1) section(0) last_section(0)
       tsid(4168) programCount(1)
   program number     1 has PID 0x0190

2012-06-26 21:00:00.724634 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xc8
2012-06-26 21:00:00.724765 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x190
2012-06-26 21:00:00.825536 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:509 (CreatePMTSingleProgram) - 
CreatePMTSingleProgram()
2012-06-26 21:00:00.825556 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:510 (CreatePMTSingleProgram) - PMT in input stream
2012-06-26 21:00:00.826127 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:511 (CreatePMTSingleProgram) - Program Map Section
  PSIP tableID(0x2) length(170) extension(0x10bf)
       version(0) current(1) section(0) last_section(0)
       pnum(4287) pid(0xc8)
   Stream #0 pid(0xc9) type(0x02 video-mpeg2)
     Stream Identifier Descriptor (0x52): ComponentTag=0x1
   Stream #1 pid(0xca) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x2
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #2 pid(0xce) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x6
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #3 pid(0xcd) type(0x06 private-data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x5
     Subtitling Descriptor (0x59) length(8)
   Stream #4 pid(0xfa) type(0x05 private-sec)
     Related Content Descriptor (0x74) length(0)
     Stream Identifier Descriptor (0x52): ComponentTag=0x96
   Stream #5 pid(0xd2) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6e
     DSM-CC Carousel Identifier Descriptor (0x13) length(5)
     Data Broadcast ID Descriptor (0x66) length(12)
   Stream #6 pid(0xd7) type(0x0d dsmcc-d data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x73
   Stream #7 pid(0x1ce9) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6d
   Stream #8 pid(0x1c21) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x65
   Stream #9 pid(0x1c22) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x66
   Stream #10 pid(0x1c23) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x67
   Stream #11 pid(0x1c24) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x68
   Stream #12 pid(0x1c25) type(0x0b dsmcc-b std data)

2012-06-26 21:00:00.826265 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:680 (CreatePMTSingleProgram) - PMT for output stream
2012-06-26 21:00:00.826720 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:681 (CreatePMTSingleProgram) - Program Map Section
  PSIP tableID(0x2) length(170) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       pnum(1) pid(0xc8)
   Stream #0 pid(0xc9) type(0x02 video-mpeg2)
     Stream Identifier Descriptor (0x52): ComponentTag=0x1
   Stream #1 pid(0xca) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x2
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #2 pid(0xce) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x6
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #3 pid(0xcd) type(0x06 private-data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x5
     Subtitling Descriptor (0x59) length(8)
   Stream #4 pid(0xfa) type(0x05 private-sec)
     Related Content Descriptor (0x74) length(0)
     Stream Identifier Descriptor (0x52): ComponentTag=0x96
   Stream #5 pid(0xd2) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6e
     DSM-CC Carousel Identifier Descriptor (0x13) length(5)
     Data Broadcast ID Descriptor (0x66) length(12)
   Stream #6 pid(0xd7) type(0x0d dsmcc-d data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x73
   Stream #7 pid(0x1ce9) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6d
   Stream #8 pid(0x1c21) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x65
   Stream #9 pid(0x1c22) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x66
   Stream #10 pid(0x1c23) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x67
   Stream #11 pid(0x1c24) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x68
   Stream #12 pid(0x1c25) type(0x0b dsmcc-b std data)
     Stre
2012-06-26 21:00:00.826891 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:509 (CreatePMTSingleProgram) - 
CreatePMTSingleProgram()
2012-06-26 21:00:00.826900 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:510 (CreatePMTSingleProgram) - PMT in input stream
2012-06-26 21:00:00.827370 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:511 (CreatePMTSingleProgram) - Program Map Section
  PSIP tableID(0x2) length(178) extension(0x11c0)
       version(5) current(1) section(0) last_section(0)
       pnum(4544) pid(0x190)
   Stream #0 pid(0x191) type(0x02 video-mpeg2)
     Stream Identifier Descriptor (0x52): ComponentTag=0x1
   Stream #1 pid(0x192) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x2
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #2 pid(0x196) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x6
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #3 pid(0x195) type(0x06 private-data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x5
     Subtitling Descriptor (0x59) length(8)
   Stream #4 pid(0x1c2) type(0x05 private-sec)
     Stream Identifier Descriptor (0x52): ComponentTag=0x96
     Related Content Descriptor (0x74) length(0)
   Stream #5 pid(0x19a) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6e
     DSM-CC Carousel Identifier Descriptor (0x13) length(5)
     Data Broadcast ID Descriptor (0x66) length(12)
   Stream #6 pid(0x19b) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6f
   Stream #7 pid(0x19f) type(0x0d dsmcc-d data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x73
   Stream #8 pid(0x1ce9) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6d
   Stream #9 pid(0x1c21) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x65
   Stream #10 pid(0x1c22) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x66
   Stream #11 pid(0x1c23) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x67
   Stream #12 pid(0x1c24) type(0x0b dsmcc-b std d
2012-06-26 21:00:00.827487 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:680 (CreatePMTSingleProgram) - PMT for output stream
2012-06-26 21:00:00.827950 I [2501/2700] DVBRead 
mpeg/mpegstreamdata.cpp:681 (CreatePMTSingleProgram) - Program Map Section
  PSIP tableID(0x2) length(178) extension(0x1)
       version(5) current(1) section(0) last_section(0)
       pnum(1) pid(0x190)
   Stream #0 pid(0x191) type(0x02 video-mpeg2)
     Stream Identifier Descriptor (0x52): ComponentTag=0x1
   Stream #1 pid(0x192) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x2
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #2 pid(0x196) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x6
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #3 pid(0x195) type(0x06 private-data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x5
     Subtitling Descriptor (0x59) length(8)
   Stream #4 pid(0x1c2) type(0x05 private-sec)
     Stream Identifier Descriptor (0x52): ComponentTag=0x96
     Related Content Descriptor (0x74) length(0)
   Stream #5 pid(0x19a) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6e
     DSM-CC Carousel Identifier Descriptor (0x13) length(5)
     Data Broadcast ID Descriptor (0x66) length(12)
   Stream #6 pid(0x19b) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6f
   Stream #7 pid(0x19f) type(0x0d dsmcc-d data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x73
   Stream #8 pid(0x1ce9) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6d
   Stream #9 pid(0x1c21) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x65
   Stream #10 pid(0x1c22) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x66
   Stream #11 pid(0x1c23) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x67
   Stream #12 pid(0x1c24) type(0x0b dsmcc-b std data)

2012-06-26 21:00:00.828074 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xc9
2012-06-26 21:00:00.828216 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xca
2012-06-26 21:00:00.828286 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xcd
2012-06-26 21:00:00.828353 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xce
2012-06-26 21:00:00.828420 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xd2
2012-06-26 21:00:00.828488 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xd7
2012-06-26 21:00:00.828556 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0xfa
2012-06-26 21:00:00.828624 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x191
2012-06-26 21:00:00.828694 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x192
2012-06-26 21:00:00.828766 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x195
2012-06-26 21:00:00.828837 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x196
2012-06-26 21:00:00.828910 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x19a
2012-06-26 21:00:00.828982 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x19b
2012-06-26 21:00:00.829055 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x19f
2012-06-26 21:00:00.829130 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1c2
2012-06-26 21:00:00.829258 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1c21
2012-06-26 21:00:00.829339 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1c22
2012-06-26 21:00:00.829429 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1c23
2012-06-26 21:00:00.829511 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1c24
2012-06-26 21:00:00.829591 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1c25
2012-06-26 21:00:00.829673 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:601 (Open) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Opening filter for pid 0x1ce9
2012-06-26 21:00:02.304078 I [2501/2508] TVRecEvent tv_rec.cpp:3779 
(TuningSignalCheck) - TVRec(3): Got good signal
2012-06-26 21:00:02.304103 I [2501/2511] TVRecEvent tv_rec.cpp:3779 
(TuningSignalCheck) - TVRec(4): Got good signal
2012-06-26 21:00:02.304281 I [2501/2508] TVRecEvent tv_rec.cpp:1989 
(TeardownSignalMonitor) - TVRec(3): TeardownSignalMonitor() -- begin
2012-06-26 21:00:02.304330 I [2501/2511] TVRecEvent tv_rec.cpp:1989 
(TeardownSignalMonitor) - TVRec(4): TeardownSignalMonitor() -- begin
2012-06-26 21:00:02.304535 I [2501/2501] CoreContext scheduler.cpp:635 
(UpdateRecStatus) - Updating status for "Line of Duty" on cardid 3 
(Tuning => Recording)
2012-06-26 21:00:02.306905 I [2501/2501] CoreContext scheduler.cpp:635 
(UpdateRecStatus) - Updating status for "Ethan Hawke on Macbeth" on 
cardid 4 (Tuning => Recording)
2012-06-26 21:00:02.354325 I [2501/2508] TVRecEvent streamhandler.cpp:97 
(RemoveListener) - SH(/dev/dvb/adapter201/frontend0): 
RemoveListener(0x972e448) -- begin
2012-06-26 21:00:02.354357 I [2501/2508] TVRecEvent 
streamhandler.cpp:109 (RemoveListener) - 
SH(/dev/dvb/adapter201/frontend0): RemoveListener(0x972e448) -- locked
2012-06-26 21:00:02.354383 I [2501/2511] TVRecEvent streamhandler.cpp:97 
(RemoveListener) - SH(/dev/dvb/adapter201/frontend0): 
RemoveListener(0xffffffffa1031e80) -- begin
2012-06-26 21:00:02.354418 I [2501/2508] TVRecEvent 
streamhandler.cpp:131 (RemoveListener) - 
SH(/dev/dvb/adapter201/frontend0): RemoveListener(0x972e448) -- end
2012-06-26 21:00:02.354429 I [2501/2511] TVRecEvent 
streamhandler.cpp:109 (RemoveListener) - 
SH(/dev/dvb/adapter201/frontend0): RemoveListener(0xffffffffa1031e80) -- 
locked
2012-06-26 21:00:02.354518 I [2501/2508] TVRecEvent tv_rec.cpp:2008 
(TeardownSignalMonitor) - TVRec(3): TeardownSignalMonitor() -- end
2012-06-26 21:00:02.354551 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(SignalMonitorRunning,) -> 
RunMainLoop,WaitingForSignal,NeedToStartRecorder,
2012-06-26 21:00:02.354583 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,NeedToStartRecorder,
2012-06-26 21:00:02.356980 I [2501/2508] TVRecEvent tv_rec.cpp:3929 
(TuningNewRecorder) - TVRec(3): Starting Recorder
2012-06-26 21:00:02.363310 I [2501/2508] TVRecEvent tv_rec.cpp:3919 
(load_profile) - Using profile 'Default' to record
2012-06-26 21:00:02.363339 I [2501/2508] TVRecEvent tv_rec.cpp:3968 
(TuningNewRecorder) - TVRec(3): rec->GetPathname(): 
'/myth4/1002_20120626210000.mpg'
2012-06-26 21:00:02.363852 I [2501/2508] TVRecEvent dtvrecorder.cpp:167 
(ResetForNewFile) - DTVRec(3): ResetForNewFile(void)
2012-06-26 21:00:02.365315 I [2501/2508] TVRecEvent recorderbase.cpp:77 
(SetRingBuffer) - RecBase(3:/dev/dvb/adapter201/frontend0): 
SetRingBuffer(0xffffffff9f11fa48) '/myth4/1002_20120626210000.mpg'
2012-06-26 21:00:02.365392 I [2501/2508] TVRecEvent dtvrecorder.cpp:1150 
(HandlePAT) - DTVRec(3): SetPAT(4287 on 0xc8)
2012-06-26 21:00:02.365433 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:402 (CreatePATSingleProgram) - 
CreatePATSingleProgram()
2012-06-26 21:00:02.365442 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:403 (CreatePATSingleProgram) - PAT in input stream
2012-06-26 21:00:02.365699 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:404 (CreatePATSingleProgram) - Program 
Association Section
  PSIP tableID(0x0) length(101) extension(0x1048)
       version(3) current(1) section(0) last_section(0)
       tsid(4168) programCount(23)
   program number     0 has PID 0x0010
   program number  4168 has PID 0x0064
   program number  4287 has PID 0x00c8
   program number  4288 has PID 0x012c
   program number  4352 has PID 0x01f4
   program number  4416 has PID 0x0a8c
   program number  4544 has PID 0x0190
   program number  4608 has PID 0x02bc
   program number  4672 has PID 0x0320
   program number  4736 has PID 0x0258
   program number  5632 has PID 0x0578
   program number  5696 has PID 0x05dc
   program number  5760 has PID 0x0640
   program number  5824 has PID 0x06a4
   program number  5888 has PID 0x0708
   program number  5952 has PID 0x076c
   program number  6016 has PID 0x07d0
   program number  6720 has PID 0x03e8
   program number  6784 has PID 0x044c
   program number  6848 has PID 0x04b0
   program number  6912 has PID 0x0514
   program number  7168 has PID 0x0384
   program number  7232 has PID 0x0b54

2012-06-26 21:00:02.365721 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:412 (CreatePATSingleProgram) - 
desired_program(4287) pid(0xc8)
2012-06-26 21:00:02.365749 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:453 (CreatePATSingleProgram) - pmt_pid(0xc8)
2012-06-26 21:00:02.365758 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:454 (CreatePATSingleProgram) - PAT for output stream
2012-06-26 21:00:02.365816 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:455 (CreatePATSingleProgram) - Program 
Association Section
  PSIP tableID(0x0) length(13) extension(0x1048)
       version(3) current(1) section(0) last_section(0)
       tsid(4168) programCount(1)
   program number     1 has PID 0x00c8

2012-06-26 21:00:02.365858 I [2501/2508] TVRecEvent dtvrecorder.cpp:1171 
(HandlePMT) - DTVRec(3): SetPMT(4287)
2012-06-26 21:00:02.365889 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:509 (CreatePMTSingleProgram) - 
CreatePMTSingleProgram()
2012-06-26 21:00:02.365898 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:510 (CreatePMTSingleProgram) - PMT in input stream
2012-06-26 21:00:02.366388 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:511 (CreatePMTSingleProgram) - Program Map Section
  PSIP tableID(0x2) length(170) extension(0x10bf)
       version(0) current(1) section(0) last_section(0)
       pnum(4287) pid(0xc8)
   Stream #0 pid(0xc9) type(0x02 video-mpeg2)
     Stream Identifier Descriptor (0x52): ComponentTag=0x1
   Stream #1 pid(0xca) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x2
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #2 pid(0xce) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x6
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #3 pid(0xcd) type(0x06 private-data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x5
     Subtitling Descriptor (0x59) length(8)
   Stream #4 pid(0xfa) type(0x05 private-sec)
     Related Content Descriptor (0x74) length(0)
     Stream Identifier Descriptor (0x52): ComponentTag=0x96
   Stream #5 pid(0xd2) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6e
     DSM-CC Carousel Identifier Descriptor (0x13) length(5)
     Data Broadcast ID Descriptor (0x66) length(12)
   Stream #6 pid(0xd7) type(0x0d dsmcc-d data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x73
   Stream #7 pid(0x1ce9) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6d
   Stream #8 pid(0x1c21) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x65
   Stream #9 pid(0x1c22) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x66
   Stream #10 pid(0x1c23) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x67
   Stream #11 pid(0x1c24) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x68
   Stream #12 pid(0x1c25) type(0x0b dsmcc-b std data)

2012-06-26 21:00:02.366510 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:680 (CreatePMTSingleProgram) - PMT for output stream
2012-06-26 21:00:02.366966 I [2501/2508] TVRecEvent 
mpeg/mpegstreamdata.cpp:681 (CreatePMTSingleProgram) - Program Map Section
  PSIP tableID(0x2) length(170) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       pnum(1) pid(0xc8)
   Stream #0 pid(0xc9) type(0x02 video-mpeg2)
     Stream Identifier Descriptor (0x52): ComponentTag=0x1
   Stream #1 pid(0xca) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x2
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #2 pid(0xce) type(0x03 audio-mp1-layer[1,2,3])
     Stream Identifier Descriptor (0x52): ComponentTag=0x6
     ISO-639 Language: code(eng) canonical(eng) eng(English)
     Unknown(127) Descriptor (0x7f) length(2)
   Stream #3 pid(0xcd) type(0x06 private-data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x5
     Subtitling Descriptor (0x59) length(8)
   Stream #4 pid(0xfa) type(0x05 private-sec)
     Related Content Descriptor (0x74) length(0)
     Stream Identifier Descriptor (0x52): ComponentTag=0x96
   Stream #5 pid(0xd2) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6e
     DSM-CC Carousel Identifier Descriptor (0x13) length(5)
     Data Broadcast ID Descriptor (0x66) length(12)
   Stream #6 pid(0xd7) type(0x0d dsmcc-d data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x73
   Stream #7 pid(0x1ce9) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x6d
   Stream #8 pid(0x1c21) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x65
   Stream #9 pid(0x1c22) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x66
   Stream #10 pid(0x1c23) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x67
   Stream #11 pid(0x1c24) type(0x0b dsmcc-b std data)
     Stream Identifier Descriptor (0x52): ComponentTag=0x68
   Stream #12 pid(0x1c25) type(0x0b dsmcc-b std data)
     Stre
2012-06-26 21:00:02.367023 I [2501/2508] TVRecEvent recorderbase.cpp:87 
(SetRecording) - RecBase(3:/dev/dvb/adapter201/frontend0): 
SetRecording(0xffffffffa83d05b0) title(Line of Duty)
2012-06-26 21:00:02.368834 I [2501/2706] RecThread dtvrecorder.cpp:167 
(ResetForNewFile) - DTVRec(3): ResetForNewFile(void)
2012-06-26 21:00:02.368881 I [2501/2706] RecThread dvbrecorder.cpp:56 
(Open) - DVBRec(3:/dev/dvb/adapter201/frontend0): Card opened successfully
2012-06-26 21:00:02.369020 I [2501/2706] RecThread streamhandler.cpp:45 
(AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0x972e448) -- begin
2012-06-26 21:00:02.369076 I [2501/2706] RecThread streamhandler.cpp:57 
(AddListener) - SH(/dev/dvb/adapter201/frontend0): 
AddListener(0x972e448) -- locked
2012-06-26 21:00:02.373665 I [2501/2508] TVRecEvent tv_rec.cpp:4164 
(SetFlags) - TVRec(3): SetFlags(RecorderRunning,RingBufferReady,) -> 
RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady,
2012-06-26 21:00:02.377221 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(NeedToStartRecorder,) -> 
RunMainLoop,RecorderRunning,RingBufferReady,

This is the start of the sequence of events which produce the only 
logged error.

2012-06-26 21:00:02.399733 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:265 (RunTS) - DVBSH(/dev/dvb/adapter201/frontend0): 
RunTS(): shutdown
2012-06-26 21:00:02.399772 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x0
2012-06-26 21:00:02.399895 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1
2012-06-26 21:00:02.399964 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x10
2012-06-26 21:00:02.400030 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x11
2012-06-26 21:00:02.400089 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x14
2012-06-26 21:00:02.400150 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xc8
2012-06-26 21:00:02.400211 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xc9
2012-06-26 21:00:02.400267 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xca
2012-06-26 21:00:02.400326 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xcd
2012-06-26 21:00:02.400380 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xce
2012-06-26 21:00:02.400436 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xd2
2012-06-26 21:00:02.400489 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xd7
2012-06-26 21:00:02.400540 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0xfa
2012-06-26 21:00:02.400596 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x190
2012-06-26 21:00:02.400647 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x191
2012-06-26 21:00:02.400702 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x192
2012-06-26 21:00:02.400755 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x195
2012-06-26 21:00:02.400802 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x196
2012-06-26 21:00:02.400849 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x19a
2012-06-26 21:00:02.400900 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x19b
2012-06-26 21:00:02.400946 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x19f
2012-06-26 21:00:02.400991 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1c2
2012-06-26 21:00:02.401039 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1c21
2012-06-26 21:00:02.401082 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1c22
2012-06-26 21:00:02.401123 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1c23
2012-06-26 21:00:02.401197 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1c24
2012-06-26 21:00:02.401253 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1c25
2012-06-26 21:00:02.401291 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:695 (Close) - 
PIDInfo(/dev/dvb/adapter201/frontend0): Closing filter for pid 0x1ce9
2012-06-26 21:00:02.655178 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:284 (RunTS) - DVBSH(/dev/dvb/adapter201/frontend0): 
RunTS(): end
2012-06-26 21:00:02.655217 I [2501/2700] DVBRead 
dvbstreamhandler.cpp:114 (run) - DVBSH(/dev/dvb/adapter201/frontend0): 
run(): end
2012-06-26 21:00:02.655519 W [2501/2511] TVRecEvent 
streamhandler.cpp:186 (Stop) - SH(/dev/dvb/adapter201/frontend0): 
Programmer Error: Start called before Stop finished
2012-06-26 21:00:02.655697 I [2501/2707] DVBRead 
dvbstreamhandler.cpp:107 (run) - DVBSH(/dev/dvb/adapter201/frontend0): 
run(): begin
2012-06-26 21:00:02.680242 I [2501/2707] DVBRead 
DeviceReadBuffer.cpp:121 (Setup) - 
DevRdB(/dev/dvb/adapter201/frontend0): buffer size 9400 KB
2012-06-26 21:00:02.680278 I [2501/2707] DVBRead 
DeviceReadBuffer.cpp:128 (Start) - 
DevRdB(/dev/dvb/adapter201/frontend0): Start() -- begin
2012-06-26 21:00:02.680354 I [2501/2707] DVBRead 
DeviceReadBuffer.cpp:146 (Start) - 
DevRdB(/dev/dvb/adapter201/frontend0): Start() -- middle
2012-06-26 21:00:02.680373 I [2501/2707] DVBRead 
DeviceReadBuffer.cpp:151 (Start) - 
DevRdB(/dev/dvb/adapter201/frontend0): Start() -- end
2012-06-26 21:00:03.153121 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:00:30.099797 I [2501/2517] TVRecEvent tv_rec.cpp:1483 
(HandlePendingRecordings) - TVRec(10): Deleting stale pending recording 
3 'Line of Duty'
2012-06-26 21:00:30.099867 I [2501/2517] TVRecEvent tv_rec.cpp:1483 
(HandlePendingRecordings) - TVRec(10): Deleting stale pending recording 
4 'Ethan Hawke on Macbeth'
2012-06-26 21:05:07.159247 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:10:13.165433 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:11:15.562175 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:15:17.171588 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:19:15.591174 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:20:18.177224 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:24:40.949475 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:24:40.949509 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: myth as a client (events: 0)
2012-06-26 21:24:40.950307 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Monitor
2012-06-26 21:24:40.950328 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: myth as a client (events: 1)
2012-06-26 21:25:08.823028 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:25:08.823052 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:25:08.824621 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Monitor
2012-06-26 21:25:08.824642 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 1)
2012-06-26 21:25:22.183740 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:25:38.831963 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:25:38.831990 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:25:38.875964 I [2501/2742] ProcessRequest 
mainserver.cpp:1361 (HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:25:38.876002 I [2501/2742] ProcessRequest 
mainserver.cpp:1363 (HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:25:38.884041 I [2501] ProcessRequest mainserver.cpp:1476 
(HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-06-26 21:25:38.884059 I [2501] ProcessRequest mainserver.cpp:1478 
(HandleAnnounce) - adding: hdfe as a remote file transfer
2012-06-26 21:26:15.625075 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:26:22.173511 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:26:22.173538 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:26:22.176571 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Monitor
2012-06-26 21:26:22.176594 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 1)
2012-06-26 21:26:56.598985 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:26:56.599014 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:26:56.754022 E [2501/2746] ProcessRequest 
programinfo.cpp:2278 (GetPlaybackURL) - 
ProgramInfo(1009_20120626210000.mpg): GetPlaybackURL: 
'1009_20120626210000.mpg' should be local, but it can not be found.
2012-06-26 21:27:01.130989 E [2501] ProcessRequest programinfo.cpp:2278 
(GetPlaybackURL) - ProgramInfo(1009_20120626210000.mpg): GetPlaybackURL: 
'1009_20120626210000.mpg' should be local, but it can not be found.
2012-06-26 21:27:01.195694 E [2501] ProcessRequest programinfo.cpp:2278 
(GetPlaybackURL) - ProgramInfo(1009_20120626210000.mpg): GetPlaybackURL: 
'1009_20120626210000.mpg' should be local, but it can not be found.
2012-06-26 21:27:02.944109 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:27:02.944133 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: myth as a client (events: 0)
2012-06-26 21:27:02.944901 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Monitor
2012-06-26 21:27:02.944921 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: myth as a client (events: 1)
2012-06-26 21:27:04.074015 E [2501/2748] PreviewGenerator 
previewgenerator.cpp:254 (Run) - Preview: Encountered problems running 
'/usr/bin/mythpreviewgen --size 0x0 --chanid 1002 --starttime 
20120626210000 --verbose general,record --logpath /var/log/mythtv 
--loglevel info --quiet' (128)
2012-06-26 21:27:47.017397 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:27:47.017420 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:27:47.019087 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Monitor
2012-06-26 21:27:47.019110 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 1)
2012-06-26 21:28:30.058678 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 21:28:30.058706 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: hdfe as a client (events: 0)
2012-06-26 21:28:30.179226 E [2501/2759] ProcessRequest 
programinfo.cpp:2278 (GetPlaybackURL) - 
ProgramInfo(1009_20120626210000.mpg): GetPlaybackURL: 
'1009_20120626210000.mpg' should be local, but it can not be found.
2012-06-26 21:30:24.189042 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:34:15.655190 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:35:29.194417 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:40:36.200059 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:41:15.689326 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:45:39.205952 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:49:15.719961 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 21:50:43.211641 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:55:43.217218 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 21:56:15.753344 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 7 min
2012-06-26 22:00:07.373538 I [2501/2911] Metadata_1882 jobqueue.cpp:2151 
(DoMetadataLookupThread) - JobQueue: Metadata Lookup Starting for "Line 
of Duty" recorded from channel 1002 at 2012-06-26T21:00:00
2012-06-26 22:00:10.447939 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Playback
2012-06-26 22:00:10.447966 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: myth as a client (events: 0)
2012-06-26 22:00:10.448884 I [2501] ProcessRequest mainserver.cpp:1361 
(HandleAnnounce) - MainServer::ANN Monitor
2012-06-26 22:00:10.448912 I [2501] ProcessRequest mainserver.cpp:1363 
(HandleAnnounce) - adding: myth as a client (events: 1)
2012-06-26 22:00:49.223520 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread

The following only gets called for TVRec(3) but there are only 326 bytes 
of data in the file. TVRec(4) appears to be left hanging in the 
RecordingOnly state until the backend is shut down. It looks like the 
file associated with TVRec(4) never gets created.

2012-06-26 22:03:00.116962 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(FinishRecording,) -> 
RunMainLoop,RecorderRunning,RingBufferReady,
2012-06-26 22:03:00.117063 I [2501/2508] TVRecEvent tv_rec.cpp:1014 
(HandleStateChange) - TVRec(3): Changing from RecordingOnly to None
2012-06-26 22:03:00.117120 I [2501/2508] TVRecEvent tv_rec.cpp:4173 
(ClearFlags) - TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) 
-> RunMainLoop,RecorderRunning,RingBufferReady,
2012-06-26 22:03:00.117193 I [2501/2508] TVRecEvent tv_rec.cpp:3385 
(HandleTuning) - TVRec(3): HandleTuning Request: Program(NULL) channel() 
input() flags(CloseRec,KillRingBuffer,)
2012-06-26 22:04:15.771508 N [2501/2531] Expire autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB 
w/freq: 14 min
2012-06-26 22:05:54.228984 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-06-26 22:10:57.234294 I [2501/2530] HouseKeeping 
housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread

=============================================================


More information about the mythtv-dev mailing list