[mythtv-commits] Re: Ticket #288: Myth DVB-T sometimes fails to get
lock when starting recording on second card
MythTV
mythtv at cvs.mythtv.org
Fri Sep 9 14:52:26 UTC 2005
#288: Myth DVB-T sometimes fails to get lock when starting recording on second
card
----------------------------------+-----------------------------------------
Reporter: mythtv at opencraft.org | Owner: danielk
Type: defect | Status: assigned
Priority: minor | Milestone: 0.19
Component: mythtv | Version: head
Severity: medium | Resolution:
Cc: |
----------------------------------+-----------------------------------------
Comment (by mythtv at opencraft.org):
No, not fixed yet. I'm not sure, but it may be better. It worked 4 times
before I got a 'failed' one, whereas with the previous version it was
failing 50% of the time.
If there's any extra logging you want me to put in, I'm happy to help as
much as I can.
David.
This log shows a successful record start on DVB0, then an unsuccessful one
on DVB1 straight afterwards.
2005-09-10 00:44:54.326 Prog title: Rage
2005-09-10 00:44:54.327 SetSignalMonitoringRate(50, 0)
2005-09-10 00:44:54.328 SetupSignalMonitor()
2005-09-10 00:44:54.329 DVB#0 Opening DVB channel
2005-09-10 00:44:54.331 SetupSignalMonitor() -- DVB hack begin
2005-09-10 00:44:54.332 SetupSignalMonitor() -- DVB hack end
2005-09-10 00:44:54.383 SM: AddFlags: Seen() Match()
Wait(Sig,SNR,BER,UB,)
2005-09-10 00:44:54.384 DVBSM(0)::constructor(): initial flags 0x7400000
2005-09-10 00:44:54.385 signal monitor successfully created
2005-09-10 00:44:54.386 Setting up table monitoring.
2005-09-10 00:44:54.387 Not ATSC channel: major(-1) minor(-1).
2005-09-10 00:44:54.388 mpeg program number: 561
2005-09-10 00:44:54.389 DTVSM(0)::SetProgramNumber(561):
2005-09-10 00:44:54.390 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-09-10 00:44:54.391 SM: AddFlags: Seen() Match() Wait(PMT,)
2005-09-10 00:44:54.393 SM: AddFlags: Seen() Match() Wait(PAT,PMT,)
2005-09-10 00:44:54.395 Successfully set up MPEG table monitoring.
2005-09-10 00:44:54.396 SM(0)::Start: begin
2005-09-10 00:44:54.400 SM(0)::Start: end
2005-09-10 00:44:54.401 DTVSM(0)::GetStatusList: WaitForPMT seen(0)
matching(0)
2005-09-10 00:44:54.448 DVBSM(0)::UpdateValues(): Signal Lock
2005-09-10 00:44:54.453 DVBSM(0)::RunTableMonitor(): begin (# of pids 2)
2005-09-10 00:44:54.454 DVBSM(0)::AddPIDFilter(0x0):
2005-09-10 00:44:54.466 DVBSM(0)::AddPIDFilter(0x1ffb):
2005-09-10 00:44:54.540 SM: AddFlags: Seen(PAT,) Match() Wait()
2005-09-10 00:44:54.541 SM: AddFlags: Seen() Match(PAT,) Wait()
2005-09-10 00:44:54.542 CreatePATSingleProgram()
2005-09-10 00:44:54.543 PAT in input stream
2005-09-10 00:44:54.544 Program Association Table
PSIP prefix(0x0) tableID(0x0) length(37) extension(0x231)
version(16) current(1) section(0) last_section(0)
tsid: 561
programCount: 7
program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16
program number 560 has PID 0x 101 data 0x2 0x48 0x225 0x1
program number 561 has PID 0x 100 data 0x2 0x49 0x225 0x0
program number 562 has PID 0x 102 data 0x2 0x50 0x225 0x2
program number 563 has PID 0x 103 data 0x2 0x51 0x225 0x3
program number 566 has PID 0x 105 data 0x2 0x54 0x225 0x5
program number 567 has PID 0x 106 data 0x2 0x55 0x225 0x6
2005-09-10 00:44:54.545 desired_program(561) pid(0x100)
2005-09-10 00:44:54.546 pmt_pid(0x100)
2005-09-10 00:44:54.547 PAT for output stream
2005-09-10 00:44:54.548 Program Association Table
PSIP prefix(0x0) tableID(0x0) length(13) extension(0x231)
version(16) current(1) section(0) last_section(0)
tsid: 561
programCount: 1
program number 1 has PID 0x 100 data 0x0 0x1 0x225 0x0
2005-09-10 00:44:54.549 DVBSM(0)::AddPIDFilter(0x100):
2005-09-10 00:44:54.675 SM: AddFlags: Seen(PMT,) Match() Wait()
2005-09-10 00:44:54.676 SM: AddFlags: Seen() Match(PMT,) Wait()
2005-09-10 00:44:54.677 CreatePMTSingleProgram()
2005-09-10 00:44:54.678 PMT in input stream
2005-09-10 00:44:54.679 Program Map Table ver(16) pid(0x100) pnum(561)
Unknown Descriptor (0x10) length(6)
Stream #0 pid(0x200) type(video-mp2 0x2)
Unknown Descriptor (0x2) length(3)
Stream Identifier Descriptor (0x52) length(1)
Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4)
Unknown Descriptor (0x3) length(1)
ISO-639 Language Descriptor (0xa) length(4)
Stream Identifier Descriptor (0x52) length(1)
Stream #2 pid(0x294) type(unknown 0x6)
AC-3 Descriptor (0x6a) length(3)
ISO-639 Language Descriptor (0xa) length(4)
Stream Identifier Descriptor (0x52) length(1)
Stream #3 pid(0x240) type(unknown 0x6)
Teletext Descriptor (0x56) length(5)
Stream Identifier Descriptor (0x52) length(1)
2005-09-10 00:44:54.680 PMT for output stream
2005-09-10 00:44:54.682 Program Map Table ver(16) pid(0x100) pnum(1)
Stream #0 pid(0x200) type(video-mp2 0x2)
Stream #1 pid(0x28a) type(audio-mp2-layer[1,2,3] 0x4)
Stream #2 pid(0x294) type(unknown 0x6)
2005-09-10 00:44:54.690 DTVSM(0)::GetStatusList: WaitForPMT seen(1)
matching(1)
2005-09-10 00:44:54.692 SetSignalMonitoringRate(0, 0)
2005-09-10 00:44:54.693 TeardownSignalMonitor() -- begin
2005-09-10 00:44:54.695 DVBSM(0)::Stop: begin
2005-09-10 00:44:54.696 SM(0)::Stop: begin
2005-09-10 00:44:54.711 SM(0)::Stop: end
2005-09-10 00:44:54.713 DVBSM(0)::RunTableMonitor(): shutdown
2005-09-10 00:44:54.715 DVBSM(0)::RemovePIDFilter(0x0):
2005-09-10 00:44:54.716 DVBSM(0)::RemovePIDFilter(0x100):
2005-09-10 00:44:54.719 DVBSM(0)::RemovePIDFilter(0x1ffb):
2005-09-10 00:44:54.721 DVBSM(0)::RunTableMonitor(): end
2005-09-10 00:44:54.722 DVBSM(0)::Stop: end
2005-09-10 00:44:54.723 DVBSM(0)::Stop: begin
2005-09-10 00:44:54.724 SM(0)::Stop: begin
2005-09-10 00:44:54.725 SM(0)::Stop: end
2005-09-10 00:44:54.726 DVBSM(0)::Stop: end
2005-09-10 00:44:54.727 SM(0)::Stop: begin
2005-09-10 00:44:54.728 SM(0)::Stop: end
2005-09-10 00:44:54.729 TeardownSignalMonitor() -- end
2005-09-10 00:44:54.731 prog_num(561) vs. dvbc->srv_id(561)
2005-09-10 00:44:54.735 DVB#0 DVB SI Table Parser Started
2005-09-10 00:44:54.737 TVRec: DVB Recorder waiting for PMT.
2005-09-10 00:44:55.297 DVB#0 SetPMT ServiceID=561, PCRPID=128 (0x80)
2005-09-10 00:44:55.299 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-10 00:44:55.299 TVRec: DVB Recorder's PMT set.
2005-09-10 00:44:55.301 DummyDTVRecorder::StopRecordingThread(void)
2005-09-10 00:44:55.302 Waiting for recorder to start
2005-09-10 00:44:55.300 DVB#0 Rec: AutoPID for ServiceID=561, PCRPID=128
(0x80)
2005-09-10 00:44:55.304 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-10 00:44:55.305 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-10 00:44:55.306 DVB#0 Rec: AutoPID selecting PID 660 (0x294), AC3
Audio (eng
2005-09-10 00:44:55.307 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-10 00:44:55.308 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-10 00:44:55.309 DVB#0 Rec: Service is FTA
2005-09-10 00:44:55.302 DVB#0 Rec: Card opened successfully (using TS
mode).
2005-09-10 00:44:55.311 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-10 00:44:55.312 DVB#0 Rec: AutoPID for ServiceID=561, PCRPID=128
(0x80)
2005-09-10 00:44:55.313 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-10 00:44:55.314 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-10 00:44:55.315 DVB#0 Rec: AutoPID selecting PID 660 (0x294), AC3
Audio (eng
2005-09-10 00:44:55.316 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-10 00:44:55.317 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-10 00:44:55.318 DVB#0 Rec: Service is FTA
2005-09-10 00:44:55.319 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-10 00:44:55.320 DVB#0 Rec: AutoPID for ServiceID=561, PCRPID=128
(0x80)
2005-09-10 00:44:55.321 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-10 00:44:55.323 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-10 00:44:55.324 DVB#0 Rec: AutoPID selecting PID 660 (0x294), AC3
Audio (eng
2005-09-10 00:44:55.325 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-10 00:44:55.326 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-10 00:44:55.327 DVB#0 Rec: Service is FTA
2005-09-10 00:44:55.328 DVB#0 Rec: Resetting Demux Filters
2005-09-10 00:44:55.328 Recorder started
2005-09-10 00:44:55.329 DVB#0 Rec: Adding pid 512 (0x200)
2005-09-10 00:44:55.332 DVB#0 Rec: Adding pid 650 (0x28a)
2005-09-10 00:44:55.334 DVB#0 Rec: Adding pid 660 (0x294)
2005-09-10 00:44:55.335 DVB#0 Rec: Adding pid 576 (0x240)
2005-09-10 00:44:55.336 DVB#0 Rec: Adding pid 128 (0x80)
2005-09-10 00:44:55.341 DVB#0 Data read from DMX - This is for debugging
with transform.c
2005-09-10 00:44:55.644 MainServer::HandleAnnounce Playback
2005-09-10 00:44:55.648 adding: media2.dp.vivitec.com.au as a client
(events: 0)
2005-09-10 00:44:55.654 MainServer::HandleAnnounce FileTransfer
2005-09-10 00:44:55.656 adding: media2.dp.vivitec.com.au as a remote file
transfer
2005-09-10 00:44:55.661 Could not open /root/.mythtv/channels/abc.gif. 0
retries remaining.
2005-09-10 00:44:56.076 DVB#0 Rec: Found first keyframe
2005-09-10 00:44:56.078 DVB#0 Rec: Found Payload Start for PID 512
2005-09-10 00:44:56.079 DVB#0 Rec: Found Payload Start for PID 576
2005-09-10 00:44:56.099 DVB#0 Rec: Found Payload Start for PID 650
2005-09-10 00:44:56.122 DVB#0 Rec: Found Payload Start for PID 660
2005-09-10 00:44:56.182 MainServer::HandleAnnounce Playback
2005-09-10 00:44:56.188 adding: media2.dp.vivitec.com.au as a client
(events: 0)
2005-09-10 00:44:56.199 MainServer::HandleAnnounce FileTransfer
2005-09-10 00:44:56.205 adding: media2.dp.vivitec.com.au as a remote file
transfer
2005-09-10 00:44:56.212 Could not open /root/.mythtv/channels/seven.gif.
0 retries remaining.
2005-09-10 00:44:56.744 MainServer::HandleAnnounce Playback
2005-09-10 00:44:56.746 adding: media2.dp.vivitec.com.au as a client
(events: 0)
2005-09-10 00:44:56.752 MainServer::HandleAnnounce FileTransfer
2005-09-10 00:44:56.754 adding: media2.dp.vivitec.com.au as a remote file
transfer
2005-09-10 00:44:56.756 Could not open /root/.mythtv/channels/ten.gif. 0
retries remaining.
2005-09-10 00:44:57.277 MainServer::HandleAnnounce Playback
2005-09-10 00:44:57.279 adding: media2.dp.vivitec.com.au as a client
(events: 0)
2005-09-10 00:44:57.286 MainServer::HandleAnnounce FileTransfer
2005-09-10 00:44:57.288 adding: media2.dp.vivitec.com.au as a remote file
transfer
2005-09-10 00:44:57.289 Could not open /root/.mythtv/channels/abc.gif. 0
retries remaining.
2005-09-10 00:45:00.632 Reschedule requested for id 1401.
2005-09-10 00:45:03.420 Scheduled 400 items in 2.8 = 0.40 match + 2.38
place
2005-09-10 00:45:03.427 Started recording "Rugby League Finals" on
channel: 1009 on cardid: 2, sourceid 1
2005-09-10 00:45:03.430 DVB#1 Trying to tune to channel 9.
2005-09-10 00:45:03.432 DVB#1 Frequency: 191625000 BW: 7MHz HP: 3/4 LP:
2/3C: QAM-64 TM: 8K H: None GI: 1/16
2005-09-10 00:45:03.433 DVB#1 Old Params: freq(226500000) type(OFDM)
2005-09-10 00:45:03.434 DVB#1 New Params: freq(191625000) type(OFDM)
2005-09-10 00:45:03.618 dvbchannel.cpp:wait_for_backend: Status:
2005-09-10 00:45:03.622 DVB#1 DVBChannel::Tune: Frequency tuning
successful.
2005-09-10 00:45:03.623 DVB#1 Tuned to frequency for channel 9.
2005-09-10 00:45:03.661 Changing from None to RecordingOnly
2005-09-10 00:45:03.681 Using profile 'Default' to record
2005-09-10 00:45:03.695 SetRecording(0x0x94f3750)
2005-09-10 00:45:03.696 Prog title: Rugby League Finals
2005-09-10 00:45:03.697 SetSignalMonitoringRate(50, 0)
2005-09-10 00:45:03.699 SetupSignalMonitor()
2005-09-10 00:45:03.700 DVB#1 Opening DVB channel
2005-09-10 00:45:03.701 SetupSignalMonitor() -- DVB hack begin
2005-09-10 00:45:03.702 SetupSignalMonitor() -- DVB hack end
2005-09-10 00:45:03.780 SM: AddFlags: Seen() Match()
Wait(Sig,SNR,BER,UB,)
2005-09-10 00:45:03.782 DVBSM(1)::constructor(): initial flags 0x7400000
2005-09-10 00:45:03.784 signal monitor successfully created
2005-09-10 00:45:03.785 Setting up table monitoring.
2005-09-10 00:45:03.786 Not ATSC channel: major(-1) minor(-1).
2005-09-10 00:45:03.787 mpeg program number: 1072
2005-09-10 00:45:03.788 DTVSM(1)::SetProgramNumber(1072):
2005-09-10 00:45:03.789 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-09-10 00:45:03.790 SM: AddFlags: Seen() Match() Wait(PMT,)
2005-09-10 00:45:03.791 SM: AddFlags: Seen() Match() Wait(PAT,PMT,)
2005-09-10 00:45:03.793 Successfully set up MPEG table monitoring.
2005-09-10 00:45:03.794 SM(1)::Start: begin
2005-09-10 00:45:03.796 SM(1)::Start: end
2005-09-10 00:45:03.798 DTVSM(1)::GetStatusList: WaitForPMT seen(0)
matching(0)
2005-09-10 00:45:06.816 SigMon Flags are: Seen() Match()
Wait(PAT,PMT,Sig,SNR,BER,UB,)
2005-09-10 00:45:06.819 SML[0]: Name(slock) Val(0) thr(>=1) range(0,1)
timeout(3000 ms) is set. Is NOT good.
2005-09-10 00:45:06.820 SML[1]: Name(signal) Val(32767) thr(>=-32768)
range(-32768,32767) timeout(3000 ms) is set. Is good.
2005-09-10 00:45:06.821 SML[2]: Name(seen_pat) Val(0) thr(>=1) range(0,1)
timeout(0 ms) is set. Is NOT good.
2005-09-10 00:45:06.822 SML[3]: Name(matching_pat) Val(0) thr(>=1)
range(0,1) timeout(0 ms) is set. Is NOT good.
2005-09-10 00:45:06.823 SML[4]: Name(seen_pmt) Val(0) thr(>=1) range(0,1)
timeout(0 ms) is set. Is NOT good.
2005-09-10 00:45:06.824 SML[5]: Name(matching_pmt) Val(0) thr(>=1)
range(0,1) timeout(0 ms) is set. Is NOT good.
2005-09-10 00:45:06.826 SML[6]: Name(snr) Val(23644) thr(>=-32768)
range(-32768,32767) timeout(0 ms) is set. Is good.
2005-09-10 00:45:06.826 SML[7]: Name(ber) Val(65535) thr(<=65535)
range(0,65535) timeout(0 ms) is set. Is good.
2005-09-10 00:45:06.827 SML[8]: Name(ucb) Val(0) thr(<=65535)
range(0,65535) timeout(0 ms) is set. Is good.
2005-09-10 00:45:06.828 TVRec: Timed out waiting for lock -- aborting
recording
2005-09-10 00:45:06.829 SetSignalMonitoringRate(0, 0)
2005-09-10 00:45:06.830 TeardownSignalMonitor() -- begin
2005-09-10 00:45:06.831 DVBSM(1)::Stop: begin
2005-09-10 00:45:06.833 SM(1)::Stop: begin
2005-09-10 00:45:06.893 SM(1)::Stop: end
2005-09-10 00:45:06.894 DVBSM(1)::Stop: end
2005-09-10 00:45:06.896 DVBSM(1)::Stop: begin
2005-09-10 00:45:06.898 SM(1)::Stop: begin
2005-09-10 00:45:06.900 SM(1)::Stop: end
2005-09-10 00:45:06.901 DVBSM(1)::Stop: end
2005-09-10 00:45:06.902 SM(1)::Stop: begin
2005-09-10 00:45:06.903 SM(1)::Stop: end
2005-09-10 00:45:06.905 TeardownSignalMonitor() -- end
2005-09-10 00:45:06.905 DummyDTVRecorder::StopRecordingThread(void)
2005-09-10 00:45:06.907 TVRec: StartRecorderPost(): aborting recording
--
Ticket URL: <http://cvs.mythtv.org/trac/ticket/288>
MythTV <http://www.mythtv.org/>
MythTV
More information about the mythtv-commits
mailing list