[mythtv] svn 7080-7130 breaks DVB recording

Thu Aug 25 17:44:41 UTC 2005

Daniel Kristjansson wrote:
> On Thu, 2005-08-25 at 18:07 +0100, Allan Stirling wrote:
> 
>>David Shirley wrote:
>>
>>>Hi All, (and Daniel :P)
> 
> 
>>>Applied updates from 7080 upto 7130 and now my scheduled recordings fail:
>>>2005-08-25 13:34:11.257 WaitForPMT: seen(1) matching(0)
>>>2005-08-25 13:34:14.054 TVRec: Timed out waiting for lock -- aborting
>>>recording
> 
> 
>>This is probably the same thing I'm finding. I'm trying to track it down 
>>at the moment.
> 
> 
> This is probably two different problems. If it saw a PMT but it
> didn't match, as indicated by the "WaitForPMT" message, it means
> that the the streams are encrypted and cardinput.freetoair is set
> to one, or the PMT does not contain both a video and audio stream.
> This is debuggable by sticking a pmt->toString() somewhere in
> DTVSignalMonitor::SetPMT() and looking at the raw PMT and the
> factors listed above.
Yes, indeed it looks like a different issue. Apologies for the confusion.

> 
> 
>>It broke in change 7119. I'm wondering if the dvb-ttpci is having issues 
>>with events while the tablemonitor is running.
> 
> But we block on events in DVBChannel long before the table monitor is
> turned on. If disabling table monitoring is fixing the problem for 
> you, then maybe the card doesn't work once you have turned on table
> monitoring once. It might be interesting to see if any events are
> being sent to the table monitoring file descriptor. It's possible
> the tuning events are being sent to the wrong file.
> 
> 
>>worked around the issue for me, however, this doesn't apply to current CVS.
> 
> You can stick a return statement as the first statement in 
> setup_mpeg_table_monitoring()... for the same effect.
> 
With that in, it works again.

As the original poster described, I'm also seeing

2005-08-25 18:36:40.606 atsc channel: -1_-1

which I've underlined in the log below.

Here's the full log of startup with the return in 
setup_mpeg_table_monitoring():

2005-08-25 18:36:38.901 Using runtime prefix = /usr/local
2005-08-25 18:36:38.981 New DB connection, total: 1
Starting up as the master server.
2005-08-25 18:36:39.024 New DB connection, total: 2
2005-08-25 18:36:39.027 mythbackend: MythBackend started as master server
2005-08-25 18:36:39.041 Start channel '
2005-08-25 18:36:39.043 DVB#0 Opening DVB channel
2005-08-25 18:36:39.050 DVB#0 Using DVB card 0, with frontend ST STV0299 
DVB-S.
2005-08-25 18:36:39.358 DVB#0 Trying to tune to channel 51001.
2005-08-25 18:36:39.361 New DB connection, total: 3
2005-08-25 18:36:39.365 DVB#0 Frequency: 11661540 Symbol Rate: 27500000 
Pol: H/L Inv: Auto
2005-08-25 18:36:39.670 DVB#0 DiSEqC 1.0 Switch - Port 0
2005-08-25 18:36:39.687 DVB#0 DiSEqC Sending 1.0 Command: e0 10 38 f2
2005-08-25 18:36:39.775 DVB#0 Event Status() frequency(1911540 Hz)
2005-08-25 18:36:39.775 DVB#0 Waiting for frontend event after tune.
2005-08-25 18:36:39.775 DVB#0 Frequency tuning successful.
2005-08-25 18:36:39.775 DVB#0 Tuned to frequency for channel 51001.
2005-08-25 18:36:39.776 SetSignalMonitoringRate(50, 0)
2005-08-25 18:36:39.776 SetupSignalMonitor()
2005-08-25 18:36:39.776 DVB#0 Opening DVB channel
2005-08-25 18:36:39.776 SetupSignalMonitor() -- DVB hack begin
2005-08-25 18:36:39.776 SetupSignalMonitor() -- DVB hack end
2005-08-25 18:36:39.984 DVBSM(0)::constructor(): initial flags 0x7400000
2005-08-25 18:36:39.984 signal monitor successfully created
2005-08-25 18:36:39.984 setting up table monitoring

2005-08-25 18:36:39.984 atsc channel: -1_-1
-------------------------------------------

2005-08-25 18:36:39.984 SM(0)::Start: begin
2005-08-25 18:36:39.985 SM(0)::Start: end
2005-08-25 18:36:40.188 DVBSM(0)::UpdateValues(): Signal Lock
2005-08-25 18:36:40.205 SetSignalMonitoringRate(0, 0)
2005-08-25 18:36:40.205 TeardownSignalMonitor() -- begin
2005-08-25 18:36:40.206 DVBSM(0)::Stop: begin
2005-08-25 18:36:40.206 SM(0)::Stop: begin
2005-08-25 18:36:40.240 SM(0)::Stop: end
2005-08-25 18:36:40.240 DVBSM(0)::Stop: end
2005-08-25 18:36:40.240 SM(0)::Stop: begin
2005-08-25 18:36:40.240 SM(0)::Stop: end
2005-08-25 18:36:40.241 TeardownSignalMonitor() -- end
2005-08-25 18:36:40.262 DVB#1 Opening DVB channel
2005-08-25 18:36:40.273 DVB#1 Using DVB card 1, with frontend VLSI 
VES1x93 DVB-S.
2005-08-25 18:36:40.579 DVB#1 CA: CI handler successfully initialized!
2005-08-25 18:36:40.581 DVB#1 CA: CI handler thread running
2005-08-25 18:36:40.581 DVB#1 Trying to tune to channel 7301.
2005-08-25 18:36:40.584 DVB#1 Frequency: 12129000 Symbol Rate: 27500000 
Pol: V/R Inv: Auto
2005-08-25 18:36:40.585 DVB#1 Setting LNB: Tone ON 13V
2005-08-25 18:36:40.601 DVB#1 Event Status() frequency(1529000 Hz)
2005-08-25 18:36:40.601 DVB#1 Waiting for frontend event after tune.
2005-08-25 18:36:40.601 DVB#1 Frequency tuning successful.
2005-08-25 18:36:40.601 DVB#1 Tuned to frequency for channel 7301.
2005-08-25 18:36:40.602 SetSignalMonitoringRate(50, 0)
2005-08-25 18:36:40.602 SetupSignalMonitor()
2005-08-25 18:36:40.602 DVB#1 Opening DVB channel
2005-08-25 18:36:40.602 SetupSignalMonitor() -- DVB hack begin
2005-08-25 18:36:40.602 SetupSignalMonitor() -- DVB hack end
2005-08-25 18:36:40.605 DVBSM(1)::constructor(): initial flags 0x7400000
2005-08-25 18:36:40.605 signal monitor successfully created
2005-08-25 18:36:40.605 setting up table monitoring

2005-08-25 18:36:40.606 atsc channel: -1_-1
-------------------------------------------

2005-08-25 18:36:40.606 SM(1)::Start: begin
2005-08-25 18:36:40.608 SM(1)::Start: end
2005-08-25 18:36:40.676 DVBSM(1)::UpdateValues(): Signal Lock
2005-08-25 18:36:40.961 SetSignalMonitoringRate(0, 0)
2005-08-25 18:36:40.961 TeardownSignalMonitor() -- begin
2005-08-25 18:36:40.961 DVBSM(1)::Stop: begin
2005-08-25 18:36:40.961 SM(1)::Stop: begin
2005-08-25 18:36:40.993 SM(1)::Stop: end
2005-08-25 18:36:40.993 DVBSM(1)::Stop: end
2005-08-25 18:36:40.993 SM(1)::Stop: begin
2005-08-25 18:36:40.993 SM(1)::Stop: end
2005-08-25 18:36:40.993 TeardownSignalMonitor() -- end
2005-08-25 18:36:41.012 New DB scheduler connection
2005-08-25 18:36:41.021 mythbackend version: 0.19.20050712-1 www.mythtv.org
2005-08-25 18:36:41.022 Enabled verbose msgs : important general channel 
record
2005-08-25 18:36:41.027 AutoExpire: Found 2 recorders w/max rate of 277 
MiB/min
2005-08-25 18:36:41.029 AutoExpire: space: 2.2 GB w/freq: 5 min
2005-08-25 18:36:43.021 Reschedule requested for id -1.
2005-08-25 18:36:43.034 Scheduled 0 items in 0.0 = 0.01 match + 0.01 place
2005-08-25 18:36:43.039 scheduler: Scheduled items
2005-08-25 18:36:43.044 Seem to be woken up by USER
2005-08-25 18:36:51.026 mythbackend: Running housekeeping thread