[mythtv] Current DVB problems rundown
David Peterson
mythtv at opencraft.org
Sat Aug 27 07:43:11 UTC 2005
I'm having the same DVB time out waiting for lock problem with the
latest SVN of Myth. Going back to an older version (7020) fixes it.
Interestingly it happens only on one channel here (channel 9).
I've attached a working log and a not working log. If you want me to
test or send anything else, I'd be happy to.
David.
WORKING :
2005-08-27 17:34:10.550 Started recording "Postcards" on channel: 1009
on cardid: 1, sourceid 1
2005-08-27 17:34:10.550 DVB#0 Trying to tune to channel 9.
2005-08-27 17:34:10.555 DVB#0 Frequency: 191625000 BW: 7MHz HP: 3/4
LP: 2/3C: QAM-64 TM: 8K H: None GI: 1/16
2005-08-27 17:34:10.556 DVBEvents: Flushing Event
Status(Signal,Carrier,FEC Stable,Sync,Lock,) frequency(177500000 Hz)
2005-08-27 17:34:10.557 DVB#0 Waiting for event
2005-08-27 17:34:10.720 DVB#0 Event Status() frequency(191625000 Hz)
2005-08-27 17:34:10.723 DVB#0 Waiting for frontend event after tune.
2005-08-27 17:34:10.725 DVB#0 Frequency tuning successful.
2005-08-27 17:34:10.725 DVB#0 Tuned to frequency for channel 9.
2005-08-27 17:34:10.762 Changing from None to RecordingOnly
2005-08-27 17:34:10.774 Using profile 'Default' to record
2005-08-27 17:34:10.777 SetRecording(0x0x9696020)
2005-08-27 17:34:10.778 Prog title: Postcards
2005-08-27 17:34:10.779 SetSignalMonitoringRate(50, 0)
2005-08-27 17:34:10.780 SetupSignalMonitor()
2005-08-27 17:34:10.781 DVB#0 Opening DVB channel
2005-08-27 17:34:10.782 SetupSignalMonitor() -- DVB hack begin
2005-08-27 17:34:10.783 SetupSignalMonitor() -- DVB hack end
2005-08-27 17:34:10.861 SM: AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2005-08-27 17:34:10.863 DVBSM(0)::constructor(): initial flags 0x7400000
2005-08-27 17:34:10.863 signal monitor successfully created
2005-08-27 17:34:10.864 Setting up table monitoring.
2005-08-27 17:34:10.865 Not ATSC channel: major(-1) minor(-1).
2005-08-27 17:34:10.866 mpeg program number: 1072
2005-08-27 17:34:10.868 DTVSM(0)::SetProgramNumber(1072):
2005-08-27 17:34:10.868 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-08-27 17:34:10.869 SM: AddFlags: Seen() Match() Wait(PMT,)
2005-08-27 17:34:10.871 SM: AddFlags: Seen() Match() Wait(PAT,PMT,)
2005-08-27 17:34:10.873 Successfully set up MPEG table monitoring.
2005-08-27 17:34:10.874 SM(0)::Start: begin
2005-08-27 17:34:10.877 SM(0)::Start: end
2005-08-27 17:34:10.879 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2005-08-27 17:34:11.413 DVBSM(0)::UpdateValues(): Signal Lock
2005-08-27 17:34:11.418 DVBSM(0)::RunTableMonitor(): begin (# of pids 2)
2005-08-27 17:34:11.419 DVBSM(0)::AddPIDFilter(0x0):
2005-08-27 17:34:11.431 DVBSM(0)::AddPIDFilter(0x1ffb):
2005-08-27 17:34:11.494 SM: AddFlags: Seen(PAT,) Match() Wait()
2005-08-27 17:34:11.495 SM: AddFlags: Seen() Match(PAT,) Wait()
2005-08-27 17:34:11.496 CreatePATSingleProgram()
2005-08-27 17:34:11.498 PAT in input stream
2005-08-27 17:34:11.499 Program Association Table
PSIP prefix(0x0) tableID(0x0) length(25) extension(0x430)
version(16) current(1) section(0) last_section(0)
tsid: 1072
programCount: 4
program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16
program number 1072 has PID 0x 100 data 0x4 0x48 0x225 0x0
program number 1073 has PID 0x 101 data 0x4 0x49 0x225 0x1
program number 1074 has PID 0x 102 data 0x4 0x50 0x225 0x2
2005-08-27 17:34:11.500 desired_program(1072) pid(0x100)
2005-08-27 17:34:11.501 pmt_pid(0x100)
2005-08-27 17:34:11.502 PAT for output stream
2005-08-27 17:34:11.503 Program Association Table
PSIP prefix(0x0) tableID(0x0) length(13) extension(0x430)
version(16) current(1) section(0) last_section(0)
tsid: 1072
programCount: 1
program number 1 has PID 0x 100 data 0x0 0x1 0x225 0x0
2005-08-27 17:34:11.504 DVBSM(0)::AddPIDFilter(0x100):
2005-08-27 17:34:11.561 SM: AddFlags: Seen(PMT,) Match() Wait()
2005-08-27 17:34:11.562 SM: AddFlags: Seen() Match(PMT,) Wait()
2005-08-27 17:34:11.563 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(0)
2005-08-27 17:34:11.565 CreatePMTSingleProgram()
2005-08-27 17:34:11.565 PMT in input stream
2005-08-27 17:34:11.566 Program Map Table ver(19) pid(0x100) pnum(1072)
Unknown Descriptor (0xb) length(2)
Unknown Descriptor (0xc) length(4)
Unknown Descriptor (0xe) length(3)
Unknown Descriptor (0x10) length(6)
Stream #0 pid(0x207) type(video-mp2 0x2)
Unknown Descriptor (0x2) length(3)
Stream Identifier Descriptor (0x52) length(1)
Unknown Descriptor (0xe) length(3)
Unknown Descriptor (0x6) length(1)
Stream #1 pid(0x2d0) 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)
Unknown Descriptor (0xe) length(3)
Stream #2 pid(0x2d1) type(unknown 0x6)
AC-3 Descriptor (0x6a) length(3)
ISO-639 Language Descriptor (0xa) length(4)
Stream Identifier Descriptor (0x52) length(1)
Unknown Descriptor (0xe) length(3)
Stream #3 pid(0x247) type(unknown 0x6)
Teletext Descriptor (0x56) length(5)
Stream Identifier Descriptor (0x52) length(1)
Unknown Descriptor (0xe) length(3)
2005-08-27 17:34:11.567 PMT for output stream
2005-08-27 17:34:11.568 Program Map Table ver(19) pid(0x100) pnum(1)
Stream #0 pid(0x207) type(video-mp2 0x2)
Stream #1 pid(0x2d0) type(audio-mp2-layer[1,2,3] 0x4)
2005-08-27 17:34:11.586 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
2005-08-27 17:34:11.588 SetSignalMonitoringRate(0, 0)
2005-08-27 17:34:11.588 TeardownSignalMonitor() -- begin
2005-08-27 17:34:11.589 DVBSM(0)::Stop: begin
2005-08-27 17:34:11.590 SM(0)::Stop: begin
2005-08-27 17:34:11.624 SM(0)::Stop: end
2005-08-27 17:34:11.627 DVBSM(0)::RunTableMonitor(): shutdown
2005-08-27 17:34:11.628 DVBSM(0)::RemovePIDFilter(0x0):
2005-08-27 17:34:11.629 DVBSM(0)::RemovePIDFilter(0x100):
2005-08-27 17:34:11.630 DVBSM(0)::RemovePIDFilter(0x1ffb):
2005-08-27 17:34:11.632 DVBSM(0)::RunTableMonitor(): end
2005-08-27 17:34:11.632 DVBSM(0)::Stop: end
2005-08-27 17:34:11.633 DVBSM(0)::Stop: begin
2005-08-27 17:34:11.634 SM(0)::Stop: begin
2005-08-27 17:34:11.635 SM(0)::Stop: end
2005-08-27 17:34:11.636 DVBSM(0)::Stop: end
2005-08-27 17:34:11.638 SM(0)::Stop: begin
2005-08-27 17:34:11.638 SM(0)::Stop: end
2005-08-27 17:34:11.639 TeardownSignalMonitor() -- end
2005-08-27 17:34:11.641 prog_num(1072) vs. dvbc->srv_id(1072)
2005-08-27 17:34:11.644 DVB#0 DVB SI Table Parser Started
2005-08-27 17:34:11.645 TVRec: DVB Recorder waiting for PMT.
2005-08-27 17:34:11.871 DVB#0 SetPMT ServiceID=1072, PCRPID=135 (0x87)
2005-08-27 17:34:11.872 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-08-27 17:34:11.873 DVB#0 Rec: AutoPID for ServiceID=1072, PCRPID=135 (0x87)
2005-08-27 17:34:11.874 TVRec: DVB Recorder's PMT set.
2005-08-27 17:34:11.876 Waiting for recorder to start
2005-08-27 17:34:11.875 DVB#0 Rec: AutoPID selecting PID 519 (0x207),
MPEG-2 Video
2005-08-27 17:34:11.877 DVB#0 Rec: AutoPID selecting PID 720 (0x2d0),
MPEG-2 Audio (eng
2005-08-27 17:34:11.878 DVB#0 Rec: AutoPID selecting PID 721 (0x2d1),
AC3 Audio (eng
2005-08-27 17:34:11.879 DVB#0 Rec: AutoPID selecting PID 583 (0x247), Teletext
2005-08-27 17:34:11.880 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for service
2005-08-27 17:34:11.881 DVB#0 Rec: Service is FTA
2005-08-27 17:34:11.876 DVB#0 Rec: Card opened successfully (using TS mode).
2005-08-27 17:34:11.883 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-08-27 17:34:11.884 DVB#0 Rec: AutoPID for ServiceID=1072, PCRPID=135 (0x87)
2005-08-27 17:34:11.886 DVB#0 Rec: AutoPID selecting PID 519 (0x207),
MPEG-2 Video
2005-08-27 17:34:11.887 DVB#0 Rec: AutoPID selecting PID 720 (0x2d0),
MPEG-2 Audio (eng
2005-08-27 17:34:11.888 DVB#0 Rec: AutoPID selecting PID 721 (0x2d1),
AC3 Audio (eng
2005-08-27 17:34:11.889 DVB#0 Rec: AutoPID selecting PID 583 (0x247), Teletext
2005-08-27 17:34:11.890 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for service
2005-08-27 17:34:11.891 DVB#0 Rec: Service is FTA
2005-08-27 17:34:11.892 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-08-27 17:34:11.893 DVB#0 Rec: AutoPID for ServiceID=1072, PCRPID=135 (0x87)
2005-08-27 17:34:11.895 DVB#0 Rec: AutoPID selecting PID 519 (0x207),
MPEG-2 Video
2005-08-27 17:34:11.896 DVB#0 Rec: AutoPID selecting PID 720 (0x2d0),
MPEG-2 Audio (eng
2005-08-27 17:34:11.897 DVB#0 Rec: AutoPID selecting PID 721 (0x2d1),
AC3 Audio (eng
2005-08-27 17:34:11.898 DVB#0 Rec: AutoPID selecting PID 583 (0x247), Teletext
2005-08-27 17:34:11.899 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for service
2005-08-27 17:34:11.900 DVB#0 Rec: Service is FTA
2005-08-27 17:34:11.901 DVB#0 Rec: Resetting Demux Filters
2005-08-27 17:34:11.902 Recorder to started
2005-08-27 17:34:11.902 DVB#0 Rec: Adding pid 519 (0x207)
2005-08-27 17:34:11.905 DVB#0 Rec: Adding pid 720 (0x2d0)
2005-08-27 17:34:11.906 DVB#0 Rec: Adding pid 721 (0x2d1)
2005-08-27 17:34:11.907 DVB#0 Rec: Adding pid 583 (0x247)
2005-08-27 17:34:11.908 DVB#0 Rec: Adding pid 135 (0x87)
2005-08-27 17:34:11.917 DVB#0 Data read from DMX - This is for
debugging with transform.c
2005-08-27 17:34:12.339 DTV: pm#0[-1]: 0 cr(0x975d600)
2005-08-27 17:34:12.896 DTV: pm#1[11]: 0 cr(0x975d600)
NOT WORKING:
2005-08-27 17:32:25.939 Started recording "Postcards" on channel: 1009
on cardid: 1, sourceid 1
2005-08-27 17:32:25.940 DVB#0 Trying to tune to channel 9.
2005-08-27 17:32:25.948 DVB#0 Frequency: 191625000 BW: 7MHz HP: 3/4
LP: 2/3C: QAM-64 TM: 8K H: None GI: 1/16
2005-08-27 17:32:25.950 DVBEvents: Flushing Event
Status(Signal,Carrier,FEC Stable,Sync,Lock,) frequency(177500000 Hz)
2005-08-27 17:32:25.953 DVB#0 Waiting for event
2005-08-27 17:32:26.111 DVB#0 Event Status() frequency(191625000 Hz)
2005-08-27 17:32:26.114 DVB#0 Waiting for frontend event after tune.
2005-08-27 17:32:26.116 DVB#0 Frequency tuning successful.
2005-08-27 17:32:26.117 DVB#0 Tuned to frequency for channel 9.
2005-08-27 17:32:26.151 Changing from None to RecordingOnly
2005-08-27 17:32:26.165 Using profile 'Default' to record
2005-08-27 17:32:26.169 SetRecording(0x0x8ddad78)
2005-08-27 17:32:26.170 Prog title: Postcards
2005-08-27 17:32:26.171 SetSignalMonitoringRate(50, 0)
2005-08-27 17:32:26.172 SetupSignalMonitor()
2005-08-27 17:32:26.173 DVB#0 Opening DVB channel
2005-08-27 17:32:26.174 SetupSignalMonitor() -- DVB hack begin
2005-08-27 17:32:26.175 SetupSignalMonitor() -- DVB hack end
2005-08-27 17:32:26.253 SM: AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2005-08-27 17:32:26.255 DVBSM(0)::constructor(): initial flags 0x7400000
2005-08-27 17:32:26.256 signal monitor successfully created
2005-08-27 17:32:26.257 Setting up table monitoring.
2005-08-27 17:32:26.258 Not ATSC channel: major(-1) minor(-1).
2005-08-27 17:32:26.259 mpeg program number: 1072
2005-08-27 17:32:26.260 DTVSM(0)::SetProgramNumber(1072):
2005-08-27 17:32:26.261 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-08-27 17:32:26.262 SM: AddFlags: Seen() Match() Wait(PMT,)
2005-08-27 17:32:26.263 SM: AddFlags: Seen() Match() Wait(PAT,PMT,)
2005-08-27 17:32:26.265 Successfully set up MPEG table monitoring.
2005-08-27 17:32:26.266 SM(0)::Start: begin
2005-08-27 17:32:26.268 SM(0)::Start: end
2005-08-27 17:32:26.270 DTVSM(0)::GetStatusList: WaitForPMT seen(0) matching(0)
2005-08-27 17:32:26.804 DVBSM(0)::UpdateValues(): Signal Lock
2005-08-27 17:32:26.809 DVBSM(0)::RunTableMonitor(): begin (# of pids 2)
2005-08-27 17:32:26.811 DVBSM(0)::AddPIDFilter(0x0):
2005-08-27 17:32:26.823 DVBSM(0)::AddPIDFilter(0x1ffb):
2005-08-27 17:32:26.933 SM: AddFlags: Seen(PAT,) Match() Wait()
2005-08-27 17:32:26.934 SM: AddFlags: Seen() Match(PAT,) Wait()
2005-08-27 17:32:26.936 CreatePATSingleProgram()
2005-08-27 17:32:26.937 PAT in input stream
2005-08-27 17:32:26.938 Program Association Table
PSIP prefix(0x0) tableID(0x0) length(25) extension(0x430)
version(16) current(1) section(0) last_section(0)
tsid: 1072
programCount: 4
program number 0 has PID 0x 10 data 0x0 0x0 0x224 0x16
program number 1072 has PID 0x 100 data 0x4 0x48 0x225 0x0
program number 1073 has PID 0x 101 data 0x4 0x49 0x225 0x1
program number 1074 has PID 0x 102 data 0x4 0x50 0x225 0x2
2005-08-27 17:32:26.939 desired_program(1072) pid(0x100)
2005-08-27 17:32:26.941 pmt_pid(0x100)
2005-08-27 17:32:26.942 PAT for output stream
2005-08-27 17:32:26.943 Program Association Table
PSIP prefix(0x0) tableID(0x0) length(13) extension(0x430)
version(16) current(1) section(0) last_section(0)
tsid: 1072
programCount: 1
program number 1 has PID 0x 100 data 0x0 0x1 0x225 0x0
2005-08-27 17:32:26.944 DVBSM(0)::AddPIDFilter(0x100):
2005-08-27 17:32:26.955 SM: AddFlags: Seen(PMT,) Match() Wait()
2005-08-27 17:32:26.961 SM: AddFlags: Seen() Match(PMT,) Wait()
2005-08-27 17:32:26.967 CreatePMTSingleProgram()
2005-08-27 17:32:26.973 PMT in input stream
2005-08-27 17:32:26.979 Program Map Table ver(19) pid(0x100) pnum(1072)
Unknown Descriptor (0xb) length(2)
Unknown Descriptor (0xc) length(4)
Unknown Descriptor (0xe) length(3)
Unknown Descriptor (0x10) length(6)
Stream #0 pid(0x207) type(video-mp2 0x2)
Unknown Descriptor (0x2) length(3)
Stream Identifier Descriptor (0x52) length(1)
Unknown Descriptor (0xe) length(3)
Unknown Descriptor (0x6) length(1)
Stream #1 pid(0x2d0) 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)
Unknown Descriptor (0xe) length(3)
Stream #2 pid(0x2d1) type(unknown 0x6)
AC-3 Descriptor (0x6a) length(3)
ISO-639 Language Descriptor (0xa) length(4)
Stream Identifier Descriptor (0x52) length(1)
Unknown Descriptor (0xe) length(3)
Stream #3 pid(0x247) type(unknown 0x6)
Teletext Descriptor (0x56) length(5)
Stream Identifier Descriptor (0x52) length(1)
Unknown Descriptor (0xe) length(3)
2005-08-27 17:32:26.985 PMT for output stream
2005-08-27 17:32:26.976 DTVSM(0)::GetStatusList: WaitForPMT seen(1) matching(1)
2005-08-27 17:32:26.991 Program Map Table ver(19) pid(0x100) pnum(1)
Stream #0 pid(0x207) type(video-mp2 0x2)
Stream #1 pid(0x2d0) type(audio-mp2-layer[1,2,3] 0x4)
2005-08-27 17:32:29.279 TVRec: Timed out waiting for lock -- aborting recording
2005-08-27 17:32:29.296 SigMon Flags are: Seen(PAT,PMT,)
Match(PAT,PMT,) Wait(PAT,PMT,Sig,SNR,BER,UB,)
2005-08-27 17:32:29.298 SetSignalMonitoringRate(0, 0)
2005-08-27 17:32:29.299 TeardownSignalMonitor() -- begin
2005-08-27 17:32:29.300 DVBSM(0)::Stop: begin
2005-08-27 17:32:29.301 SM(0)::Stop: begin
2005-08-27 17:32:29.303 SM(0)::Stop: end
2005-08-27 17:32:29.306 DVBSM(0)::RunTableMonitor(): shutdown
2005-08-27 17:32:29.308 DVBSM(0)::RemovePIDFilter(0x0):
2005-08-27 17:32:29.309 DVBSM(0)::RemovePIDFilter(0x100):
2005-08-27 17:32:29.310 DVBSM(0)::RemovePIDFilter(0x1ffb):
2005-08-27 17:32:29.311 DVBSM(0)::RunTableMonitor(): end
2005-08-27 17:32:29.312 DVBSM(0)::Stop: end
2005-08-27 17:32:29.313 DVBSM(0)::Stop: begin
2005-08-27 17:32:29.314 SM(0)::Stop: begin
2005-08-27 17:32:29.315 SM(0)::Stop: end
2005-08-27 17:32:29.316 DVBSM(0)::Stop: end
2005-08-27 17:32:29.317 SM(0)::Stop: begin
2005-08-27 17:32:29.318 SM(0)::Stop: end
2005-08-27 17:32:29.319 TeardownSignalMonitor() -- end
2005-08-27 17:32:29.320 StartRecorderPost() -- failed
2005-08-27 17:32:29.321 StartRecorderPost()::closeRecorder -- begin
2005-08-27 17:32:29.324 StartRecorderPost()::closeRecorder -- end
2005-08-27 17:32:49.622 Reschedule requested for id 1344.
2005-08-27 17:32:52.393 Scheduled 443 items in 2.8 = 0.36 match + 2.41 place
More information about the mythtv-dev
mailing list