[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