Opened 14 years ago

Closed 14 years ago

Last modified 14 years ago

#288 closed defect (invalid)

Myth DVB-T sometimes fails to get lock when starting recording on second card

Reported by: mythtv@… Owned by: danielk
Priority: minor Milestone: 0.19
Component: mythtv Version: head
Severity: medium Keywords:
Cc: Ticket locked: no

Description (last modified by danielk)

50% of the time when starting a second recording just after a first recording has started successfully, it times out waiting for a lock. This has happenned since around SVN 7050.

The cards are both identical Nova-T cards. The failure is not channel specific.

The following log was taken usnig latest SVN. It starts just after the first card starts recording.

I had a problem with the BER being out of range stopping recording on card 0 which Daniel kindly fixed last week.

David.

2005-09-02 19:17:55.614 Recorder to started
2005-09-02 19:17:55.615 DVB#0 Rec: Adding pid 770 (0x302)
2005-09-02 19:17:55.618 DVB#0 Rec: Adding pid 772 (0x304)
2005-09-02 19:17:55.619 DVB#0 Rec: Adding pid 769 (0x301)
2005-09-02 19:17:55.643 DVB#0 Data read from DMX - This is for debugging with transform.c
2005-09-02 19:17:56.916 DVB#0 Rec: Found first keyframe
2005-09-02 19:17:56.919 DVB#0 Rec: Found Payload Start for PID 769
2005-09-02 19:17:56.941 DVB#0 Rec: Found Payload Start for PID 772
2005-09-02 19:17:56.943 DVB#0 Rec: Found Payload Start for PID 770
2005-09-02 19:17:59.645 Reschedule requested for id 1382.
2005-09-02 19:18:01.953 Scheduled 37 items in 2.3 = 0.30 match + 2.00 place
2005-09-02 19:18:01.961 DVB#1 Trying to tune to channel 13.
2005-09-02 19:18:02.057 Started recording "Everybody Loves Raymond" on channel: 1010 on cardid: 2, sourceid 1
2005-09-02 19:18:02.058 DVB#1 Frequency: 219500000 BW: 7MHz HP: 3/4 LP: 1/2C: QAM-64 TM: 8K H: None GI: 1/16
2005-09-02 19:18:02.061 DVBEvents: Flushing Event Status() frequency(226500000 Hz)
2005-09-02 19:18:02.062 DVBEvents: Flushing Event Status(Signal,Carrier,FEC Stable,Sync,Lock,) frequency(226500000 Hz)
2005-09-02 19:18:02.063 DVB#1 Waiting for event
2005-09-02 19:18:02.196 DVB#1 Event Status() frequency(219500000 Hz)
2005-09-02 19:18:02.199 DVB#1 Waiting for frontend event after tune.
2005-09-02 19:18:02.200 DVB#1 Frequency tuning successful.
2005-09-02 19:18:02.201 DVB#1 Tuned to frequency for channel 13.
2005-09-02 19:18:02.241 Changing from None to RecordingOnly
2005-09-02 19:18:02.256 Using profile 'Default' to record
2005-09-02 19:18:02.264 SetRecording(0x0x8229c68)
2005-09-02 19:18:02.270 Prog title: Everybody Loves Raymond
2005-09-02 19:18:02.277 SetSignalMonitoringRate(50, 0)
2005-09-02 19:18:02.284 SetupSignalMonitor()
2005-09-02 19:18:02.289 DVB#1 Opening DVB channel
2005-09-02 19:18:02.295 SetupSignalMonitor() -- DVB hack begin
2005-09-02 19:18:02.299 SetupSignalMonitor() -- DVB hack end
2005-09-02 19:18:02.379 SM:    AddFlags: Seen() Match() Wait(Sig,SNR,BER,UB,)
2005-09-02 19:18:02.381 DVBSM(1)::constructor(): initial flags 0x7400000
2005-09-02 19:18:02.382 signal monitor successfully created
2005-09-02 19:18:02.383 Setting up table monitoring.
2005-09-02 19:18:02.384 Not ATSC channel: major(-1) minor(-1).
2005-09-02 19:18:02.385 mpeg program number: 1586
2005-09-02 19:18:02.386 DTVSM(1)::SetProgramNumber(1586):
2005-09-02 19:18:02.387 SM: RemoveFlags: Seen(PMT,) Match(PMT,) Wait()
2005-09-02 19:18:02.388 SM:    AddFlags: Seen() Match() Wait(PMT,)
2005-09-02 19:18:02.389 SM:    AddFlags: Seen() Match() Wait(PAT,PMT,)
2005-09-02 19:18:02.391 Successfully set up MPEG table monitoring.
2005-09-02 19:18:02.392 SM(1)::Start: begin
2005-09-02 19:18:02.394 SM(1)::Start: end
2005-09-02 19:18:02.396 DTVSM(1)::GetStatusList: WaitForPMT seen(0) matching(0)
2005-09-02 19:18:05.411 SigMon Flags are: Seen() Match() Wait(PAT,PMT,Sig,SNR,BER,UB,)
2005-09-02 19:18:05.413 SML[0]: Name(slock) Val(0) thr(>=1) range(0,1) timeout(3000 ms) is set. Is NOT good.
2005-09-02 19:18:05.414 SML[1]: Name(signal) Val(32767) thr(>=-32768) range(-32768,32767) timeout(3000 ms) is set. Is good.
2005-09-02 19:18:05.416 SML[2]: Name(seen_pat) Val(0) thr(>=1) range(0,1) timeout(0 ms) is set. Is NOT good.
2005-09-02 19:18:05.416 SML[3]: Name(matching_pat) Val(0) thr(>=1) range(0,1) timeout(0 ms) is set. Is NOT good.
2005-09-02 19:18:05.417 SML[4]: Name(seen_pmt) Val(0) thr(>=1) range(0,1) timeout(0 ms) is set. Is NOT good.
2005-09-02 19:18:05.419 SML[5]: Name(matching_pmt) Val(0) thr(>=1) range(0,1) timeout(0 ms) is set. Is NOT good.
2005-09-02 19:18:05.419 SML[6]: Name(snr) Val(32767) thr(>=-32768) range(-32768,32767) timeout(0 ms) is set. Is good.
2005-09-02 19:18:05.421 SML[7]: Name(ber) Val(65535) thr(<=65535) range(0,65535) timeout(0 ms) is set. Is good.
2005-09-02 19:18:05.421 SML[8]: Name(ucb) Val(0) thr(<=65535) range(0,65535) timeout(0 ms) is set. Is good.
2005-09-02 19:18:05.422 TVRec: Timed out waiting for lock -- aborting recording
2005-09-02 19:18:05.424 SetSignalMonitoringRate(0, 0)
2005-09-02 19:18:05.424 TeardownSignalMonitor() -- begin
2005-09-02 19:18:05.425 DVBSM(1)::Stop: begin
2005-09-02 19:18:05.426 SM(1)::Stop: begin
2005-09-02 19:18:05.489 SM(1)::Stop: end
2005-09-02 19:18:05.491 DVBSM(1)::Stop: end
2005-09-02 19:18:05.493 DVBSM(1)::Stop: begin
2005-09-02 19:18:05.495 SM(1)::Stop: begin
2005-09-02 19:18:05.496 SM(1)::Stop: end
2005-09-02 19:18:05.497 DVBSM(1)::Stop: end
2005-09-02 19:18:05.498 SM(1)::Stop: begin
2005-09-02 19:18:05.500 SM(1)::Stop: end
2005-09-02 19:18:05.500 TeardownSignalMonitor() -- end
2005-09-02 19:18:05.502 TVRec: StartRecorderPost(): aborting recording
2005-09-02 19:18:05.503 StartRecorderPost()::closeRecorder -- begin
2005-09-02 19:18:05.506 StartRecorderPost()::closeRecorder -- end

Attachments (1)

timedout.txt (4.8 KB) - added by mythtv@… 14 years ago.

Download all attachments as: .zip

Change History (13)

comment:1 Changed 14 years ago by anonymous

Adding the log as an attachment to make it easier to read.

Changed 14 years ago by mythtv@…

Attachment: timedout.txt added

comment:2 Changed 14 years ago by danielk

Description: modified (diff)
Milestone: 0.19
Owner: changed from Isaac Richards to danielk
Status: newassigned

I don't know what is happening here.

If anyone has more info please attach it to this ticket.

comment:3 Changed 14 years ago by danielk

Can you confirm that [7207] fixes this?

comment:4 Changed 14 years ago by mythtv@…

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

comment:5 Changed 14 years ago by danielk

Resolution: fixed
Status: assignedclosed

I believe this is fixed in [2707].

comment:6 Changed 14 years ago by mythtv@…

Resolution: fixed
Status: closedreopened

No, it wasn't fixed for me in 7207, as per my previous message.

comment:7 Changed 14 years ago by veli@…

I'm getting the very same error and I got only one dvb card (nova-t). Sometimes the record starts and sometimes I get the error, seems to be quite random. At least I have not found any situations when it would or would not happen.

LOG: http://piipiip.net/~veli/tmp/TVRec.aborting.log

comment:8 Changed 14 years ago by veli@…

I updated to svn version 7248 but it did not help. Tell me if you want logs from that version.

comment:9 Changed 14 years ago by veli@…

Do you have any pointers how could I debug this my self? I could try and fix the bug my self.

comment:10 Changed 14 years ago by danielk

Resolution: invalid
Status: reopenedclosed

This makes me think this is a driver problem: 2005-09-02 19:18:05.413 SML[0]: Name(slock) Val(0) thr(>=1) range(0,1) timeout(3000 ms) is set. Is NOT good.

This means that MythTV doesn't have an FE_HAS_LOCK from:

ioctl(fd_frontend, FE_READ_STATUS, &stats.status);

Please ask the DVB people for help directly. They will probably want you to tune with their own tools, which may not exhibit the problem because they don't excercise the API as well. If so, you can e-mail me directly and I'll see if I can't modify their program to break the driver :)

Anyway, this doesn't look like it is really a MythTV problem so I'm closing this.

comment:11 Changed 14 years ago by veli@…

Anyhow, maybe myth should then say that it cannot start the record instead of adding the entries to the database but not recording anything.

I'm now building a new version and I patched the 3000ms timeout to 20000ms from libs/libmythtv/dvbsignalmonitor.cpp line 56 (int wait = 20000; timeout when waiting on signal). Lets see if that helps.

Btw. I did not find your mail-address.. :) Maybe I just don't know how to use trac.

comment:12 Changed 14 years ago by veli@…

Patching the timeout fixed this problem!

Note: See TracTickets for help on using tickets.