[mythtv-users] Debugging recording failure

Carsten Aulbert carsten at welcomes-you.com
Sat Jul 10 09:34:06 UTC 2010


Hi all,

after fighting quite a bit with my combo DVB-T/DVB-S myth backend getting two 
satellites working. Thanks to "justinh" from the IRC channel I can now get TV 
and recordings from both satellite positions, however, a couple of channels 
are not working. I've increased the verbosity of the backend a bit (recording 
and general IIRC), but I don't really get a real error message:

2010-07-10 09:50:01.378 TVRec(12): StartRecording(Zos Zauberschrank)
2010-07-10 09:50:01.396 TVRec(12): Checking input group recorders - begin
2010-07-10 09:50:01.413 TVRec(12): Checking input group recorders - done
2010-07-10 09:50:01.482 ProgramInfo(): Updated pathname '':'' -> 
'31008_20100710095000.mpg'
2010-07-10 09:50:01.599 TVRec(12): StartedRecording(0x7f49a8015270) 
fn(/home/mythtv/recordings/31008_20100710095000.mpg)
2010-07-10 09:50:01.621 TVRec(12): ClearFlags(CancelNextRecording,) -> 
RunMainLoop,
2010-07-10 09:50:01.645 TVRec(12): Changing from None to RecordingOnly
2010-07-10 09:50:01.662 TVRec(12): 
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2010-07-10 09:50:01.678 TVRec(12): Request: Program(yes) channel() input() 
flags(Recording,)
2010-07-10 09:50:01.696 TVRec(12): HW Tuner: 12->12
2010-07-10 09:50:01.720 TVRec(12): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
2010-07-10 09:50:01.736 TVRec(12): No recorder yet, calling TuningFrequency
2010-07-10 09:50:02.686 TVRec(12): Starting Signal Monitor
2010-07-10 09:50:02.707 TVRec(12): SetupSignalMonitor(1, 0)
2010-07-10 09:50:02.752 TVRec(12): Signal monitor successfully created
2010-07-10 09:50:02.773 TVRec(12): Setting up table monitoring.
2010-07-10 09:50:02.829 Using profile 'Live TV' to record
2010-07-10 09:50:02.848 TVRec(12): DVB service_id 28008 on net_id 0 tsid 1079
2010-07-10 09:50:02.873 TVRec(12): Successfully set up DVB table monitoring.
2010-07-10 09:50:02.889 TVRec(12): SetFlags(SignalMonitorRunning,) -> 
RunMainLoop,SignalMonitorRunning,
2010-07-10 09:50:02.914 TVRec(12): ClearFlags(WaitingForSignal,) -> 
RunMainLoop,SignalMonitorRunning,
2010-07-10 09:50:02.931 TVRec(12): SetFlags(WaitingForSignal,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2010-07-10 09:50:02.947 TVRec(12): ClearFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2010-07-10 09:50:02.964 TVRec(12): SetFlags(NeedToStartRecorder,) -> 
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2010-07-10 09:50:02.912 DVBSH(/dev/dvb/adapter0/frontend0): 
AddListener(0x7f49a800c978) -- begin
2010-07-10 09:50:02.997 DVBSH(/dev/dvb/adapter0/frontend0): 
AddListener(0x7f49a800c978) -- locked
2010-07-10 09:50:03.025 AutoExpire: CalcParams(): Max required Free Space: 3.0 
GB w/freq: 14 min
2010-07-10 09:50:03.026 DVBSH(/dev/dvb/adapter0/frontend0): 
AddListener(0x7f49a800c978) -- end
2010-07-10 09:50:03.026 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x0
2010-07-10 09:50:03.048 Started recording: Zos Zauberschrank "Spuren im Sand / 
Schweres leicht gemacht": channel 31008 on cardid 12, sourceid 3
2010-07-10 09:50:03.080 PIDInfo(/dev/dvb/adapter0/frontend0): Closing filter 
for pid 0x0
2010-07-10 09:50:03.130 ProgramInfo(): Updated pathname '':'' -> 
'31008_20100710095000.mpg'
2010-07-10 09:50:03.276 DVBSH(/dev/dvb/adapter0/frontend0): RunTS(): begin
2010-07-10 09:50:03.296 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x0
2010-07-10 09:50:03.312 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x10
2010-07-10 09:50:03.329 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x11
2010-07-10 09:50:03.345 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x14
2010-07-10 09:50:03.411 CreatePATSingleProgram()
2010-07-10 09:50:03.428 PAT in input stream
2010-07-10 09:50:03.445 Program Association Table
 PSIP tableID(0x0) length(45) extension(0x437)
      version(22) current(1) section(0) last_section(0)
         tsid: 1079
 programCount: 9
  program number 28006 has PID 0x  64   data  0x6d 0x66 0xe0 0x64
  program number 28011 has PID 0x 258   data  0x6d 0x6b 0xe2 0x58
  program number 28014 has PID 0x 28a   data  0x6d 0x6e 0xe2 0x8a
  program number 28016 has PID 0x 44c   data  0x6d 0x70 0xe4 0x4c
  program number 28007 has PID 0x  c8   data  0x6d 0x67 0xe0 0xc8
  program number 28008 has PID 0x 12c   data  0x6d 0x68 0xe1 0x2c
  program number 28017 has PID 0x 19b   data  0x6d 0x71 0xe1 0x9b
  program number 28012 has PID 0x 2bc   data  0x6d 0x6c 0xe2 0xbc
  program number 28013 has PID 0x 320   data  0x6d 0x6d 0xe3 0x20

2010-07-10 09:50:03.462 desired_program(28008) pid(0x12c)
2010-07-10 09:50:03.482 pmt_pid(0x12c)
2010-07-10 09:50:03.503 PAT for output stream
2010-07-10 09:50:03.520 Program Association Table
 PSIP tableID(0x0) length(13) extension(0x437)
      version(22) current(1) section(0) last_section(0)
         tsid: 1079
 programCount: 1
  program number     1 has PID 0x 12c   data  0x 0 0x 1 0xe1 0x2c

2010-07-10 09:50:03.536 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x12c
2010-07-10 09:50:03.608 CreatePMTSingleProgram()
2010-07-10 09:50:03.627 PMT in input stream
2010-07-10 09:50:03.644 Program Map Table ver(1) pid(0x12c) pnum(28008) 
len(81)

 Stream #0 pid(0x136) type(video-mpeg2  0x2)
  Stream Identifier Descriptor (0x52): ComponentTag=0x1
 Stream #1 pid(0x140) type(audio-mp1-layer[1,2,3]  0x3)
  Stream Identifier Descriptor (0x52): ComponentTag=0x2
  ISO-639 Language: code(deu) canonical(ger) eng(German)
 Stream #2 pid(0x14a) type(private-data  0x6)
  Stream Identifier Descriptor (0x52): ComponentTag=0x3
  Teletext Descriptor (0x56) length(5)
  VBI Data Descriptor (0x45) length(29)

2010-07-10 09:50:03.661 PMT for output stream
2010-07-10 09:50:03.702 Program Map Table ver(1) pid(0x12c) pnum(1) len(81)

 Stream #0 pid(0x136) type(video-mpeg2  0x2)
  Stream Identifier Descriptor (0x52): ComponentTag=0x1
 Stream #1 pid(0x140) type(audio-mp1-layer[1,2,3]  0x3)
  Stream Identifier Descriptor (0x52): ComponentTag=0x2
  ISO-639 Language: code(deu) canonical(ger) eng(German)
 Stream #2 pid(0x14a) type(private-data  0x6)
  Stream Identifier Descriptor (0x52): ComponentTag=0x3
  Teletext Descriptor (0x56) length(5)
  VBI Data Descriptor (0x45) length(29)

2010-07-10 09:50:03.727 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x136
2010-07-10 09:50:03.744 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x140
2010-07-10 09:50:03.760 PIDInfo(/dev/dvb/adapter0/frontend0): Opening filter 
for pid 0x14a
2010-07-10 09:50:29.995 TVRec(13): Deleting stale pending recording 12 'Zos 
Zauberschrank'
2010-07-10 09:54:25.536 UPnpMedia: BuildMediaMap VIDEO scan starting in 
:/home/mythtv/video:
2010-07-10 09:54:25.564 UPnpMedia: BuildMediaMap Done. Found 27 objects
2010-07-10 10:03:17.997 AutoExpire: CalcParams(): Max required Free Space: 3.0 
GB w/freq: 14 min


IMHO all of this looks ok-ish except that the "magic bits" like

Found Payload Start

are missing.

Any idea how to debug this any further?

cheers

Carsten


More information about the mythtv-users mailing list