[mythtv] Re: [mythtv-commits] mythtv commit: r7133 by danielk

Allan Stirling Dibblahmythml0015 at pendor.org
Thu Aug 25 19:52:03 UTC 2005


mythtv at cvs.mythtv.org wrote:
>       Author: danielk
>         Date: 2005-08-25 19:20:31 +0000 (Thu, 25 Aug 2005)
> New Revision: 7133
>    Changeset: http://cvs.mythtv.org/trac/changeset/7133
> 
> Modified:
> 
>    trunk/mythtv/libs/libmythtv/signalmonitor.h
>    trunk/mythtv/libs/libmythtv/tv_rec.cpp
> 
> Log:
> 
> This adds some debugging to AddFlags()/RemoveFlags() in
> the SignalMonitor class. This should tell us why some 
> tunes, that people expect to succeed, fail.
> 
> Also made the debugging output in the table monitoring 
> setup rutines clearer. In particular it won't print out
> the "atsc channel: -1_-1" message for non-ATSC tunes.
Debugging with this version makes it pretty obvious that something's 
awry, but I don't yet see what. Still looking...:

2005-08-25 20:42:07.833 Using runtime prefix = /usr/local
2005-08-25 20:42:07.919 New DB connection, total: 1
Starting up as the master server.
2005-08-25 20:42:07.963 New DB connection, total: 2
2005-08-25 20:42:07.965 mythbackend: MythBackend started as master server
2005-08-25 20:42:07.979 Start channel '
2005-08-25 20:42:07.980 DVB#0 Opening DVB channel
2005-08-25 20:42:07.987 DVB#0 Using DVB card 0, with frontend ST STV0299 
DVB-S.
2005-08-25 20:42:08.294 DVB#0 Trying to tune to channel 51001.
2005-08-25 20:42:08.297 New DB connection, total: 3
2005-08-25 20:42:08.300 DVB#0 Frequency: 11661540 Symbol Rate: 27500000 
Pol: H/L Inv: Auto
2005-08-25 20:42:08.606 DVB#0 DiSEqC 1.0 Switch - Port 0
2005-08-25 20:42:08.623 DVB#0 DiSEqC Sending 1.0 Command: e0 10 38 f2
2005-08-25 20:42:08.711 DVB#0 Event Status() frequency(1911540 Hz)
2005-08-25 20:42:08.711 DVB#0 Waiting for frontend event after tune.
2005-08-25 20:42:08.711 DVB#0 Frequency tuning successful.
2005-08-25 20:42:08.711 DVB#0 Tuned to frequency for channel 51001.
2005-08-25 20:42:08.711 SetSignalMonitoringRate(50, 0)
2005-08-25 20:42:08.711 SetupSignalMonitor()
2005-08-25 20:42:08.711 DVB#0 Opening DVB channel
2005-08-25 20:42:08.711 SetupSignalMonitor() -- DVB hack begin
2005-08-25 20:42:08.712 SetupSignalMonitor() -- DVB hack end
2005-08-25 20:42:08.920 SM:    AddFlags: Seen() Match() 
Wait(Sig,SNR,BER,UB,)
2005-08-25 20:42:08.921 DVBSM(0)::constructor(): initial flags 0x7400000
2005-08-25 20:42:08.921 signal monitor successfully created
2005-08-25 20:42:08.921 Setting up table monitoring.
2005-08-25 20:42:08.921 Not ATSC channel: major(-1) minor(-1).
2005-08-25 20:42:08.921 mpeg program number: 51001
2005-08-25 20:42:08.921 DTVSM(0)::SetProgramNumber(51001):


2005-08-25 20:42:08.921 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-08-25 20:42:08.921 SM:    AddFlags: Seen() Match() Wait(PMT,)
2005-08-25 20:42:08.923 SM:    AddFlags: Seen() Match() Wait(PAT,PMT,)
-----------------------------------------------------------



2005-08-25 20:42:08.923 Successfully set up MPEG table monitoring.
2005-08-25 20:42:08.923 SM(0)::Start: begin
2005-08-25 20:42:08.925 SM(0)::Start: end
2005-08-25 20:42:08.925 DTVSM(0)::GetStatusList: WaitForPMT seen(0) 
matching(0)
2005-08-25 20:42:09.126 DVBSM(0)::UpdateValues(): Signal Lock
2005-08-25 20:42:09.136 DVBSM(0)::RunTableMonitor(): begin (# of pids 2)
2005-08-25 20:42:09.137 DVBSM(0)::AddPIDFilter(0x0):
2005-08-25 20:42:09.139 DVBSM(0)::AddPIDFilter(0x1ffb):


2005-08-25 20:42:09.266 SM:    AddFlags: Seen(PAT,) Match() Wait()
2005-08-25 20:42:09.266 SM:    AddFlags: Seen() Match(PAT,) Wait()
------------------------------------------------------------

2005-08-25 20:42:09.266 CreatePATSingleProgram()
2005-08-25 20:42:09.267 PAT in input stream
2005-08-25 20:42:09.267 Program Association Table
  PSIP prefix(0x0) tableID(0x0) length(53) extension(0x96b)
       version(18) current(1) section(0) last_section(0)
          tsid: 2411
  programCount: 11
   program number 0 has PID 0x  10   data  0x0 0x0 0x224 0x16
   program number 51001 has PID 0x 104   data  0x199 0x57 0x225 0x4
   program number 51006 has PID 0x 103   data  0x199 0x62 0x225 0x3
   program number 51008 has PID 0x 107   data  0x199 0x64 0x225 0x7
   program number 51009 has PID 0x 108   data  0x199 0x65 0x225 0x8
   program number 51010 has PID 0x 100   data  0x199 0x66 0x225 0x0
   program number 51011 has PID 0x 10c   data  0x199 0x67 0x225 0x12
   program number 51014 has PID 0x 106   data  0x199 0x70 0x225 0x6
   program number 51019 has PID 0x 101   data  0x199 0x75 0x225 0x1
   program number 51020 has PID 0x 109   data  0x199 0x76 0x225 0x9
   program number 51022 has PID 0x 10b   data  0x199 0x78 0x225 0x11

2005-08-25 20:42:09.269 desired_program(51001) pid(0x104)
2005-08-25 20:42:09.269 pmt_pid(0x104)
2005-08-25 20:42:09.269 PAT for output stream
2005-08-25 20:42:09.269 Program Association Table
  PSIP prefix(0x0) tableID(0x0) length(13) extension(0x96b)
       version(18) current(1) section(0) last_section(0)
          tsid: 2411
  programCount: 1
   program number 1 has PID 0x 104   data  0x0 0x1 0x225 0x4

2005-08-25 20:42:09.270 DVBSM(0)::AddPIDFilter(0x104):
2005-08-25 20:42:09.279 PSIP packet failed CRC check
2005-08-25 20:42:09.405 PSIP packet failed CRC check
2005-08-25 20:42:09.539 PSIP packet failed CRC check
2005-08-25 20:42:09.659 PSIP packet failed CRC check
2005-08-25 20:42:09.779 PSIP packet failed CRC check
2005-08-25 20:42:09.919 PSIP packet failed CRC check
2005-08-25 20:42:10.039 PSIP packet failed CRC check
2005-08-25 20:42:10.159 PSIP packet failed CRC check
2005-08-25 20:42:10.281 PSIP packet failed CRC check
2005-08-25 20:42:10.419 PSIP packet failed CRC check
2005-08-25 20:42:10.538 PSIP packet failed CRC check
2005-08-25 20:42:10.662 PSIP packet failed CRC check
2005-08-25 20:42:10.798 PSIP packet failed CRC check
2005-08-25 20:42:10.918 PSIP packet failed CRC check
2005-08-25 20:42:11.040 PSIP packet failed CRC check
2005-08-25 20:42:11.162 PSIP packet failed CRC check
2005-08-25 20:42:11.298 PSIP packet failed CRC check
2005-08-25 20:42:11.420 PSIP packet failed CRC check
2005-08-25 20:42:11.558 PSIP packet failed CRC check
2005-08-25 20:42:11.678 PSIP packet failed CRC check
2005-08-25 20:42:11.798 PSIP packet failed CRC check
2005-08-25 20:42:11.920 PSIP packet failed CRC check
2005-08-25 20:42:11.939 TVRec: Timed out waiting for lock -- aborting 
recording


2005-08-25 20:42:11.939 SigMon Flags are: Seen(PAT,) Match(PAT,) 
Wait(PAT,PMT,Sig,SNR,BER,UB,)
-----------------------------------------------------------------


2005-08-25 20:42:11.939 SetSignalMonitoringRate(0, 0)
2005-08-25 20:42:11.940 TeardownSignalMonitor() -- begin
2005-08-25 20:42:11.940 DVBSM(0)::Stop: begin
2005-08-25 20:42:11.940 SM(0)::Stop: begin
2005-08-25 20:42:11.988 SM(0)::Stop: end
2005-08-25 20:42:11.990 DVBSM(0)::RunTableMonitor(): shutdown
2005-08-25 20:42:11.990 DVBSM(0)::RemovePIDFilter(0x0):
2005-08-25 20:42:11.992 DVBSM(0)::RemovePIDFilter(0x104):
2005-08-25 20:42:11.994 DVBSM(0)::RemovePIDFilter(0x1ffb):
2005-08-25 20:42:11.997 DVBSM(0)::RunTableMonitor(): end
2005-08-25 20:42:11.997 DVBSM(0)::Stop: end
2005-08-25 20:42:11.997 DVBSM(0)::Stop: begin
2005-08-25 20:42:11.997 SM(0)::Stop: begin
2005-08-25 20:42:11.997 SM(0)::Stop: end
2005-08-25 20:42:11.997 DVBSM(0)::Stop: end
2005-08-25 20:42:11.997 SM(0)::Stop: begin
2005-08-25 20:42:11.997 SM(0)::Stop: end
2005-08-25 20:42:11.998 TeardownSignalMonitor() -- end
2005-08-25 20:42:12.016 DVB#1 Opening DVB channel
2005-08-25 20:42:12.023 DVB#1 Using DVB card 1, with frontend VLSI 
VES1x93 DVB-S.
2005-08-25 20:42:12.329 DVB#1 CA: CI handler successfully initialized!
2005-08-25 20:42:12.331 DVB#1 CA: CI handler thread running
2005-08-25 20:42:12.331 DVB#1 Trying to tune to channel 7301.
2005-08-25 20:42:12.335 DVB#1 Frequency: 12129000 Symbol Rate: 27500000 
Pol: V/R Inv: Auto
2005-08-25 20:42:12.335 DVB#1 Setting LNB: Tone ON 13V
2005-08-25 20:42:12.351 DVB#1 Event Status() frequency(1529000 Hz)
2005-08-25 20:42:12.351 DVB#1 Waiting for frontend event after tune.
2005-08-25 20:42:12.352 DVB#1 Frequency tuning successful.
2005-08-25 20:42:12.352 DVB#1 Tuned to frequency for channel 7301.
2005-08-25 20:42:12.352 SetSignalMonitoringRate(50, 0)
2005-08-25 20:42:12.352 SetupSignalMonitor()
2005-08-25 20:42:12.352 DVB#1 Opening DVB channel
2005-08-25 20:42:12.352 SetupSignalMonitor() -- DVB hack begin
2005-08-25 20:42:12.352 SetupSignalMonitor() -- DVB hack end
2005-08-25 20:42:12.357 SM:    AddFlags: Seen() Match() 
Wait(Sig,SNR,BER,UB,)
2005-08-25 20:42:12.357 DVBSM(1)::constructor(): initial flags 0x7400000
2005-08-25 20:42:12.358 signal monitor successfully created
2005-08-25 20:42:12.358 Setting up table monitoring.
2005-08-25 20:42:12.358 Not ATSC channel: major(-1) minor(-1).
2005-08-25 20:42:12.358 mpeg program number: 7301
2005-08-25 20:42:12.358 DTVSM(1)::SetProgramNumber(7301):
2005-08-25 20:42:12.359 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-08-25 20:42:12.359 SM:    AddFlags: Seen() Match() Wait(PMT,)
2005-08-25 20:42:12.360 SM:    AddFlags: Seen() Match() Wait(PAT,PMT,)
2005-08-25 20:42:12.360 Successfully set up MPEG table monitoring.
2005-08-25 20:42:12.360 SM(1)::Start: begin
2005-08-25 20:42:12.363 SM(1)::Start: end
2005-08-25 20:42:12.428 DVBSM(1)::UpdateValues(): Signal Lock
2005-08-25 20:42:12.437 DVBSM(1)::RunTableMonitor(): begin (# of pids 2)
2005-08-25 20:42:12.438 DVBSM(1)::AddPIDFilter(0x0):
2005-08-25 20:42:12.440 DVBSM(1)::AddPIDFilter(0x1ffb):
2005-08-25 20:42:12.462 SM:    AddFlags: Seen(PAT,) Match() Wait()
2005-08-25 20:42:12.463 SM:    AddFlags: Seen() Match(PAT,) Wait()
2005-08-25 20:42:12.463 CreatePATSingleProgram()
2005-08-25 20:42:12.463 PAT in input stream
2005-08-25 20:42:12.463 Program Association Table
  PSIP prefix(0x0) tableID(0x0) length(37) extension(0x7e6)
       version(17) current(1) section(0) last_section(0)
          tsid: 2022
  programCount: 7
   program number 0 has PID 0x  10   data  0x0 0x0 0x224 0x16
   program number 7301 has PID 0x 102   data  0x28 0x133 0x225 0x2
   program number 7330 has PID 0x 103   data  0x28 0x162 0x225 0x3
   program number 7331 has PID 0x 101   data  0x28 0x163 0x225 0x1
   program number 7340 has PID 0x 106   data  0x28 0x172 0x225 0x6
   program number 7341 has PID 0x 107   data  0x28 0x173 0x225 0x7
   program number 7360 has PID 0x 104   data  0x28 0x192 0x225 0x4

2005-08-25 20:42:12.465 desired_program(7301) pid(0x102)
2005-08-25 20:42:12.465 pmt_pid(0x102)
2005-08-25 20:42:12.465 PAT for output stream
2005-08-25 20:42:12.465 Program Association Table
  PSIP prefix(0x0) tableID(0x0) length(13) extension(0x7e6)
       version(17) current(1) section(0) last_section(0)
          tsid: 2022
  programCount: 1
   program number 1 has PID 0x 102   data  0x0 0x1 0x225 0x2

2005-08-25 20:42:12.466 DVBSM(1)::AddPIDFilter(0x102):
2005-08-25 20:42:12.476 SM:    AddFlags: Seen(PMT,) Match() Wait()
2005-08-25 20:42:12.477 SM:    AddFlags: Seen() Match(PMT,) Wait()
2005-08-25 20:42:12.477 CreatePMTSingleProgram()
2005-08-25 20:42:12.477 PMT in input stream
2005-08-25 20:42:12.477 Program Map Table ver(18) pid(0x102) pnum(7301)
      Unknown Descriptor (0xb) length(2)
      Unknown Descriptor (0xe) length(3)
      Unknown Descriptor (0x10) length(6)

  Stream #0 pid(0x900) type(video-mp2  0x2)
      Unknown Descriptor (0xe) length(3)
      Unknown Descriptor (0x2) length(3)
      Unknown Descriptor (0x6) length(1)
      Stream Identifier Descriptor (0x52) length(1)
  Stream #1 pid(0x902) type(audio-mp2-layer[1,2,3]  0x4)
      Unknown Descriptor (0xe) length(3)
      Unknown Descriptor (0x3) length(1)
      ISO-639 Language Descriptor (0xa) length(4)
      Stream Identifier Descriptor (0x52) length(1)
  Stream #2 pid(0x903) type(audio-mp2-layer[1,2,3]  0x4)
      Unknown Descriptor (0xe) length(3)
      Unknown Descriptor (0x3) length(1)
      ISO-639 Language Descriptor (0xa) length(4)
      Stream Identifier Descriptor (0x52) length(1)
  Stream #3 pid(0x904) type(audio-mp2-layer[1,2,3]  0x4)
      Unknown Descriptor (0xe) length(3)
      Unknown Descriptor (0x3) length(1)
      ISO-639 Language Descriptor (0xa) length(4)
      Stream Identifier Descriptor (0x52) length(1)
  Stream #4 pid(0x901) type(unknown  0x6)
      Unknown Descriptor (0xe) length(3)
      Teletext Descriptor (0x56) length(15)
      Stream Identifier Descriptor (0x52) length(1)

2005-08-25 20:42:12.480 PMT for output stream
2005-08-25 20:42:12.481 Program Map Table ver(18) pid(0x102) pnum(1)

  Stream #0 pid(0x900) type(video-mp2  0x2)
  Stream #1 pid(0x902) type(audio-mp2-layer[1,2,3]  0x4)
  Stream #2 pid(0x903) type(audio-mp2-layer[1,2,3]  0x4)
  Stream #3 pid(0x904) type(audio-mp2-layer[1,2,3]  0x4)

2005-08-25 20:42:12.495 DTVSM(1)::GetStatusList: WaitForPMT seen(1) 
matching(1)
2005-08-25 20:42:15.377 TVRec: Timed out waiting for lock -- aborting 
recording


2005-08-25 20:42:15.377 SigMon Flags are: Seen(PAT,PMT,) Match(PAT,PMT,) 
Wait(PAT,PMT,Sig,SNR,BER,UB,)
----------------------------------------------------




2005-08-25 20:42:15.377 SetSignalMonitoringRate(0, 0)
2005-08-25 20:42:15.377 TeardownSignalMonitor() -- begin
2005-08-25 20:42:15.377 DVBSM(1)::Stop: begin
2005-08-25 20:42:15.377 SM(1)::Stop: begin
2005-08-25 20:42:15.394 SM(1)::Stop: end
2005-08-25 20:42:15.396 DVBSM(1)::RunTableMonitor(): shutdown
2005-08-25 20:42:15.396 DVBSM(1)::RemovePIDFilter(0x0):
2005-08-25 20:42:15.398 DVBSM(1)::RemovePIDFilter(0x102):
2005-08-25 20:42:15.400 DVBSM(1)::RemovePIDFilter(0x1ffb):
2005-08-25 20:42:15.402 DVBSM(1)::RunTableMonitor(): end
2005-08-25 20:42:15.402 DVBSM(1)::Stop: end
2005-08-25 20:42:15.402 DVBSM(1)::Stop: begin
2005-08-25 20:42:15.402 SM(1)::Stop: begin
2005-08-25 20:42:15.402 SM(1)::Stop: end
2005-08-25 20:42:15.402 DVBSM(1)::Stop: end
2005-08-25 20:42:15.402 SM(1)::Stop: begin
2005-08-25 20:42:15.403 SM(1)::Stop: end
2005-08-25 20:42:15.403 TeardownSignalMonitor() -- end
2005-08-25 20:42:15.419 New DB scheduler connection
2005-08-25 20:42:15.427 mythbackend version: 0.19.20050712-1 www.mythtv.org
2005-08-25 20:42:15.427 Enabled verbose msgs : important general channel 
record
2005-08-25 20:42:15.435 AutoExpire: Found 2 recorders w/max rate of 277 
MiB/min
2005-08-25 20:42:15.438 AutoExpire: space: 2.2 GB w/freq: 5 min
2005-08-25 20:42:17.429 Reschedule requested for id -1.
2005-08-25 20:42:17.443 Scheduled 0 items in 0.0 = 0.01 match + 0.01 place
2005-08-25 20:42:17.448 scheduler: Scheduled items
2005-08-25 20:42:17.453 Seem to be woken up by USER
2005-08-25 20:42:25.428 mythbackend: Running housekeeping thread




More information about the mythtv-dev mailing list