[mythtv-commits] Ticket #343: 'Signal Lock' and no video with dvb-s, problems still remaining in rev 7266

MythTV mythtv at cvs.mythtv.org
Fri Sep 16 20:21:27 UTC 2005 

#343: 'Signal Lock' and no video with dvb-s, problems still remaining in rev 7266
-----------------------------------+----------------------------------------
 Reporter:  daniel.danner at gmx.net  |       Owner:  ijr 
     Type:  defect                 |      Status:  new 
 Priority:  major                  |   Milestone:      
Component:  mythtv                 |     Version:  head
 Severity:  medium                 |  -----------------------------------+----------------------------------------
 Although all problems with dvb and black screens were reported as solved
 in newest revisions, I'm still experiencing them.

 My frontend runs rev 7266 fine, and it also works in combination with rev
 7026 on the backend machine. Upgrading the backend to rev 7266 leads to
 black screens in LiveTV with "Signal Lock 100% (l ) No Lock" written on
 the OSD.

 Here's the output of mythbackend -v record,channel,siparser:
 {{{
 $ mythbackend -v record,channel,siparser
 2005-09-16 22:17:54.066 Using runtime prefix = /usr/local
 2005-09-16 22:17:54.090 New DB connection, total: 1
 Starting up as the master server.
 2005-09-16 22:17:54.111 New DB connection, total: 2
 2005-09-16 22:17:54.115 New DB connection, total: 3
 2005-09-16 22:17:54.126 DVB#0 Device Name: 'ST STV0299 DVB-S'
 2005-09-16 22:17:54.126 DVB#0 Opening DVB channel
 2005-09-16 22:17:54.127 DVB#0 Using DVB card 0, with frontend ST STV0299
 DVB-S.
 2005-09-16 22:17:54.567 DVB#0 Trying to tune to channel 26.
 2005-09-16 22:17:54.570 DVB#0 Frequency: 12187500 Symbol Rate: 27500000
 Pol: H/L Inv: Auto
 2005-09-16 22:17:54.571 DVB#0 Setting LNB: Tone ON 18V
 2005-09-16 22:17:54.588 DVB#0 Old Params: freq(0) type(QPSK)
 2005-09-16 22:17:54.588 DVB#0 New Params: freq(12187500) type(QPSK)
 2005-09-16 22:17:54.589 dvbchannel.cpp:wait_for_backend: Status:
 Signal,Carrier,
 2005-09-16 22:17:54.589 DVB#0 DVBChannel::Tune: Frequency tuning
 successful.
 2005-09-16 22:17:54.589 DVB#0 Tuned to frequency for channel 26.
 2005-09-16 22:17:54.589 SetSignalMonitoringRate(50, 0)
 2005-09-16 22:17:54.589 SetupSignalMonitor()
 2005-09-16 22:17:54.589 DVB#0 Opening DVB channel
 2005-09-16 22:17:54.589 SetupSignalMonitor() -- DVB hack begin
 2005-09-16 22:17:54.589 SetupSignalMonitor() -- DVB hack end
 2005-09-16 22:17:54.592 SM(0)::AddFlags: Seen() Match()
 Wait(Sig,SNR,BER,UB,)
 2005-09-16 22:17:54.592 DVBSM(0)::constructor(): initial flags 0x7400000
 2005-09-16 22:17:54.593 signal monitor successfully created
 2005-09-16 22:17:54.593 Setting up table monitoring.
 2005-09-16 22:17:54.593 Not ATSC channel: major(-1) minor(-1).
 2005-09-16 22:17:54.593 mpeg program number: 12020
 2005-09-16 22:17:54.594 DTVSM(0)::SetProgramNumber(12020):
 2005-09-16 22:17:54.594 SM(0)::RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
 2005-09-16 22:17:54.594 SM(0)::AddFlags: Seen() Match() Wait(PMT,)
 2005-09-16 22:17:54.595 SM(0)::AddFlags: Seen() Match() Wait(PAT,PMT,)
 2005-09-16 22:17:54.595 Successfully set up MPEG table monitoring.
 2005-09-16 22:17:54.595 SM(0)::Start: begin
 2005-09-16 22:17:54.597 SM(0)::Start: end
 2005-09-16 22:17:54.597 DTVSM(0)::GetStatusList: WaitForPMT seen(0)
 matching(0)
 2005-09-16 22:17:54.599 DVBSM(0)::UpdateValues: Signal Locked
 2005-09-16 22:17:54.599 DVBSM(0)::UpdateValues: Waiting for table monitor
 to start
 2005-09-16 22:17:54.600 DVBSM(0)::UpdateValues: Table monitor started
 2005-09-16 22:17:54.615 DVBSM(0)::RunTableMonitor(): begin (# of pids 2)
 2005-09-16 22:17:54.616 DVBSM(0)::AddPIDFilter(0x0):
 2005-09-16 22:17:54.619 DVBSM(0)::AddPIDFilter(0x1ffb):
 2005-09-16 22:17:54.625 SM(0)::AddFlags: Seen(PAT,) Match() Wait()
 2005-09-16 22:17:54.626 SM(0)::AddFlags: Seen() Match(PAT,) Wait()
 2005-09-16 22:17:54.626 CreatePATSingleProgram()
 2005-09-16 22:17:54.626 PAT in input stream
 2005-09-16 22:17:54.626 Program Association Table
  PSIP prefix(0x0) tableID(0x0) length(41) extension(0x441)
       version(21) current(1) section(0) last_section(0)
          tsid: 1089
  programCount: 8
   program number 0 has PID 0x  10   data  0x0 0x0 0x224 0x16
   program number 12003 has PID 0x  2c   data  0x46 0x227 0x224 0x44
   program number 12020 has PID 0x  2e   data  0x46 0x244 0x224 0x46
   program number 12040 has PID 0x  2d   data  0x47 0x8 0x224 0x45
   program number 12060 has PID 0x  2f   data  0x47 0x28 0x224 0x47
   program number 12090 has PID 0x  30   data  0x47 0x58 0x224 0x48
   program number 12080 has PID 0x  29   data  0x47 0x48 0x224 0x41
   program number 12095 has PID 0x  31   data  0x47 0x63 0x224 0x49

 2005-09-16 22:17:54.626 desired_program(12020) pid(0x2e)
 2005-09-16 22:17:54.626 pmt_pid(0x2e)
 2005-09-16 22:17:54.626 PAT for output stream
 2005-09-16 22:17:54.626 Program Association Table
  PSIP prefix(0x0) tableID(0x0) length(13) extension(0x441)
       version(21) current(1) section(0) last_section(0)
          tsid: 1089
  programCount: 1
   program number 1 has PID 0x  2e   data  0x0 0x1 0x224 0x46

 2005-09-16 22:17:54.627 DVBSM(0)::AddPIDFilter(0x2e):
 2005-09-16 22:17:54.648 SM(0)::AddFlags: Seen(PMT,) Match() Wait()
 2005-09-16 22:17:54.649 SM(0)::AddFlags: Seen() Match(PMT,) Wait()
 2005-09-16 22:17:54.649 CreatePMTSingleProgram()
 2005-09-16 22:17:54.649 PMT in input stream
 2005-09-16 22:17:54.649 Program Map Table ver(18) pid(0x2e) pnum(12020)

  Stream #0 pid(0xa6) type(video-mpeg2  0x2)
  Stream #1 pid(0x80) type(audio-mp1-layer[1,2,3]  0x3)
      ISO-639 Language Descriptor (0xa) length(4)
  Stream #2 pid(0x457) type(private-sec  0x5)
      Application Signalling Descriptor (0x6f) length(3)
  Stream #3 pid(0x44) type(private-data  0x6)
      Teletext Descriptor (0x56) length(5)
  Stream #4 pid(0x44c) type(dsmcc-b std data  0x11)
      Unknown Descriptor (0x14) length(13)
      Unknown Descriptor (0x13) length(5)
      Stream Identifier Descriptor (0x52) length(1)
      Data Broadcast Identifier Descriptor (0x66) length(4)

 2005-09-16 22:17:54.649 PMT for output stream
 2005-09-16 22:17:54.649 Program Map Table ver(18) pid(0x2e) pnum(1)

  Stream #0 pid(0xa6) type(video-mpeg2  0x2)
  Stream #1 pid(0x80) type(audio-mp1-layer[1,2,3]  0x3)

 2005-09-16 22:17:54.664 DTVSM(0)::GetStatusList: WaitForPMT seen(1)
 matching(1)
 2005-09-16 22:17:54.665 SetSignalMonitoringRate(0, 0)
 2005-09-16 22:17:54.665 TeardownSignalMonitor() -- begin
 2005-09-16 22:17:54.665 DVBSM(0)::Stop: begin
 2005-09-16 22:17:54.665 SM(0)::Stop: begin
 2005-09-16 22:17:54.704 SM(0)::Stop: end
 2005-09-16 22:17:54.705 DVBSM(0)::RunTableMonitor(): shutdown
 2005-09-16 22:17:54.705 DVBSM(0)::RemovePIDFilter(0x0):
 2005-09-16 22:17:54.705 DVBSM(0)::RemovePIDFilter(0x2e):
 2005-09-16 22:17:54.706 DVBSM(0)::RemovePIDFilter(0x1ffb):
 2005-09-16 22:17:54.710 DVBSM(0)::RunTableMonitor(): end
 2005-09-16 22:17:54.711 DVBSM(0)::Stop: end
 2005-09-16 22:17:54.712 DVBSM(0)::Stop: begin
 2005-09-16 22:17:54.712 SM(0)::Stop: begin
 2005-09-16 22:17:54.712 SM(0)::Stop: end
 2005-09-16 22:17:54.712 DVBSM(0)::Stop: end
 2005-09-16 22:17:54.712 SM(0)::Stop: begin
 2005-09-16 22:17:54.712 SM(0)::Stop: end
 2005-09-16 22:17:54.712 TeardownSignalMonitor() -- end
 2005-09-16 22:17:54.717 DVB#0 Closing DVB channel
 2005-09-16 22:17:54.753 New DB scheduler connection
 2005-09-16 22:17:54.759 mythbackend version: 0.19.20050712-1
 www.mythtv.org
 2005-09-16 22:17:54.760 Enabled verbose msgs : important general record
 channel siparser
 2005-09-16 22:17:54.767 New DB connection, total: 4
 2005-09-16 22:17:54.770 AutoExpire: Found 1 recorders w/max rate of 138
 MiB/min
 2005-09-16 22:17:54.772 AutoExpire: space: 3.0 GB w/freq: 10 min
 2005-09-16 22:17:56.790 Reschedule requested for id -1.
 2005-09-16 22:17:57.230 Scheduled 38 items in 0.4 = 0.26 match + 0.17
 place
 2005-09-16 22:17:57.246 Recording starts soon, AUTO-Startup assumed
 2005-09-16 22:18:30.755 MainServer::HandleAnnounce Playback
 2005-09-16 22:18:30.755 adding: mickey as a client (events: 0)
 2005-09-16 22:18:30.763 Getting next free recorder after : -1
 2005-09-16 22:18:30.765 Checking card 1. Best card so far 1
 2005-09-16 22:18:30.776 MainServer::HandleAnnounce Playback
 2005-09-16 22:18:30.776 adding: mickey as a client (events: 1)
 2005-09-16 22:18:30.787 MainServer::HandleAnnounce Playback
 2005-09-16 22:18:30.787 adding: mickey as a client (events: 0)
 2005-09-16 22:18:30.805 MainServer::HandleAnnounce Playback
 2005-09-16 22:18:30.805 adding: mickey as a client (events: 0)
 2005-09-16 22:18:30.813 adding: mickey as a remote ringbuffer
 2005-09-16 22:18:30.821 Changing from None to WatchingLiveTV
 2005-09-16 22:18:30.844 Using profile 'Live TV' to record
 2005-09-16 22:18:30.867 DummyDTVRecorder::StartRecording -- begin
 2005-09-16 22:18:30.869 SetRecording(0x0)
 2005-09-16 22:18:30.869 SetSignalMonitoringRate(50, 1)
 2005-09-16 22:18:30.869 SetupSignalMonitor()
 2005-09-16 22:18:30.869 DVB#0 Opening DVB channel
 2005-09-16 22:18:30.877 DVB#0 Using DVB card 0, with frontend ST STV0299
 DVB-S.
 2005-09-16 22:18:31.027 DummyRec: Restart! Frames seen 11
 2005-09-16 22:18:31.143 DummyRec: Restart! Frames seen 22
 2005-09-16 22:18:31.191 DummyRec: Restart! Frames seen 33
 2005-09-16 22:18:31.243 DummyRec: Restart! Frames seen 44
 2005-09-16 22:18:31.292 DummyRec: Restart! Frames seen 55
 2005-09-16 22:18:31.328 SetupSignalMonitor() -- DVB hack begin
 2005-09-16 22:18:31.328 SetupSignalMonitor() -- DVB hack end
 2005-09-16 22:18:31.329 SM(0)::AddFlags: Seen() Match()
 Wait(Sig,SNR,BER,UB,)
 2005-09-16 22:18:31.329 DVBSM(0)::constructor(): initial flags 0x7400000
 2005-09-16 22:18:31.331 signal monitor successfully created
 2005-09-16 22:18:31.331 Setting up table monitoring.
 2005-09-16 22:18:31.331 Not ATSC channel: major(-1) minor(-1).
 2005-09-16 22:18:31.331 mpeg program number: 12020
 2005-09-16 22:18:31.331 DTVSM(0)::SetProgramNumber(12020):
 2005-09-16 22:18:31.331 SM(0)::RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
 2005-09-16 22:18:31.331 SM(0)::AddFlags: Seen() Match() Wait(PMT,)
 2005-09-16 22:18:31.336 SM(0)::AddFlags: Seen() Match() Wait(PAT,PMT,)
 2005-09-16 22:18:31.337 Successfully set up MPEG table monitoring.
 2005-09-16 22:18:31.337 SM(0)::Start: begin
 2005-09-16 22:18:31.341 SM(0)::Start: end
 2005-09-16 22:18:31.342 DTVSM(0)::GetStatusList: WaitForPMT seen(0)
 matching(0)
 2005-09-16 22:18:31.344 DummyRec: Restart! Frames seen 66
 2005-09-16 22:18:31.460 DummyRec: Restart! Frames seen 77
 2005-09-16 22:18:31.513 DummyRec: Restart! Frames seen 88
 2005-09-16 22:18:31.788 DummyRec: Restart! Frames seen 99
 2005-09-16 22:18:32.066 DummyRec: Restart! Frames seen 110
 2005-09-16 22:18:32.349 DummyRec: Restart! Frames seen 121
 2005-09-16 22:18:32.626 DummyRec: Restart! Frames seen 132
 2005-09-16 22:18:32.889 DummyRec: Restart! Frames seen 143
 2005-09-16 22:18:33.149 DummyRec: Restart! Frames seen 154
 2005-09-16 22:18:33.394 DummyRec: Restart! Frames seen 165
 2005-09-16 22:18:33.658 DummyRec: Restart! Frames seen 176
 2005-09-16 22:18:33.924 DummyRec: Restart! Frames seen 187
 2005-09-16 22:18:34.181 DummyRec: Restart! Frames seen 198
 2005-09-16 22:18:34.446 DummyRec: Restart! Frames seen 209
 2005-09-16 22:18:34.721 DummyRec: Restart! Frames seen 220
 2005-09-16 22:18:35.094 DummyRec: Restart! Frames seen 231
 2005-09-16 22:18:35.369 DummyRec: Restart! Frames seen 242
 2005-09-16 22:18:35.636 DummyRec: Restart! Frames seen 253
 2005-09-16 22:18:35.914 DummyRec: Restart! Frames seen 264
 2005-09-16 22:18:36.164 DummyRec: Restart! Frames seen 275
 2005-09-16 22:18:36.450 DummyRec: Restart! Frames seen 286
 2005-09-16 22:18:36.727 DummyRec: Restart! Frames seen 297
 2005-09-16 22:18:36.797 TVRec::HandleStateChange() Abort starting
 recording -- begin
 2005-09-16 22:18:36.811 SigMon Flags are: Seen() Match()
 Wait(PAT,PMT,Sig,SNR,BER,UB,)
 2005-09-16 22:18:36.812 SML[0]: Name(slock) Val(0) thr(>=1) range(0,1)
 timeout(3000 ms) is set. Is NOT good.
 2005-09-16 22:18:36.812 SML[1]: Name(signal) Val(32767) thr(>=-32768)
 range(-32768,32767) timeout(3000 ms) is set. Is good.
 2005-09-16 22:18:36.812 SML[2]: Name(seen_pat) Val(0) thr(>=1) range(0,1)
 timeout(0 ms) is set. Is NOT good.
 2005-09-16 22:18:36.812 SML[3]: Name(matching_pat) Val(0) thr(>=1)
 range(0,1) timeout(0 ms) is set. Is NOT good.
 2005-09-16 22:18:36.812 SML[4]: Name(seen_pmt) Val(0) thr(>=1) range(0,1)
 timeout(0 ms) is set. Is NOT good.
 2005-09-16 22:18:36.812 SML[5]: Name(matching_pmt) Val(0) thr(>=1)
 range(0,1) timeout(0 ms) is set. Is NOT good.
 2005-09-16 22:18:36.812 SML[6]: Name(snr) Val(32767) thr(>=-32768)
 range(-32768,32767) timeout(0 ms) is set. Is good.
 2005-09-16 22:18:36.812 SML[7]: Name(ber) Val(8744) thr(<=65535)
 range(0,65535) timeout(0 ms) is set. Is good.
 2005-09-16 22:18:36.812 SML[8]: Name(ucb) Val(0) thr(<=65535)
 range(0,65535) timeout(0 ms) is set. Is good.
 2005-09-16 22:18:36.813 TVRec: StartChannel() -- canceled
 2005-09-16 22:18:36.813 SetSignalMonitoringRate(0, 0)
 2005-09-16 22:18:36.813 TeardownSignalMonitor() -- begin
 2005-09-16 22:18:36.813 DVBSM(0)::Stop: begin
 2005-09-16 22:18:36.813 SM(0)::Stop: begin
 2005-09-16 22:18:36.845 SM(0)::Stop: end
 2005-09-16 22:18:36.845 DVBSM(0)::Stop: end
 2005-09-16 22:18:36.845 DVBSM(0)::Stop: begin
 2005-09-16 22:18:36.845 SM(0)::Stop: begin
 2005-09-16 22:18:36.845 SM(0)::Stop: end
 2005-09-16 22:18:36.845 DVBSM(0)::Stop: end
 2005-09-16 22:18:36.846 SM(0)::Stop: begin
 2005-09-16 22:18:36.846 SM(0)::Stop: end
 2005-09-16 22:18:36.846 TeardownSignalMonitor() -- end
 2005-09-16 22:18:36.846 DummyDTVRecorder::StopRecordingThread(void)
 2005-09-16 22:18:36.850 DummyDTVRecorder::FinishRecording()
 2005-09-16 22:18:36.854 DummyDTVRecorder::StartRecording -- end
 2005-09-16 22:18:36.856 TVRec: StartRecorderPost(): canceled
 2005-09-16 22:18:36.856 StartRecorderPost()::closeRecorder -- begin
 2005-09-16 22:18:36.856 DummyDTVRecorder::StopRecordingThread(void)
 2005-09-16 22:18:36.877 DVB#0 Closing DVB channel
 2005-09-16 22:18:36.877 StartRecorderPost()::closeRecorder -- end
 2005-09-16 22:18:36.877 TVRec::HandleStateChange() Abort starting
 recording -- end
 2005-09-16 22:18:36.877 TVRec::HandleStateChange(): Null transition None
 to None
 2005-09-16 22:18:36.878 StopLiveTV()::closeRecorder -- begin
 2005-09-16 22:18:36.878 StopLiveTV()::closeRecorder -- end rbuffer(0)
 }}}

 If it's any important: I discovered revision 7026 to be the last one, NOT
 refusing to work because of such errors.

 Greetings,
 Daniel Danner

-- 
Ticket URL: <http://cvs.mythtv.org/trac/ticket/343>
MythTV <http://www.mythtv.org/>
MythTV

More information about the mythtv-commits mailing list