Channel switching slow? Part 2 (1 Viewer)

[tzi]

Hi!

I dug into channel switchting. I use a TechnoTrend USB 2 DVB-S Box with NO CI. When I read the logs:

31-03-2008 20:10:14.750 TechnoTrend: Get CI Slot State
31-03-2008 20:10:14.750 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:14.750 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:14.750 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:17.765 TechnoTrend: Get CI Slot State
31-03-2008 20:10:17.765 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:17.765 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:17.765 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:20.796 TechnoTrend: Get CI Slot State
31-03-2008 20:10:20.796 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:20.796 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:20.796 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:23.828 TechnoTrend: Get CI Slot State
31-03-2008 20:10:23.828 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:23.828 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:23.828 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:26.843 TechnoTrend: Get CI Slot State
31-03-2008 20:10:26.843 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:26.843 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:26.843 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:29.843 TechnoTrend: Get CI Slot State
31-03-2008 20:10:29.843 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:29.843 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:29.843 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:32.875 TechnoTrend: Get CI Slot State
31-03-2008 20:10:32.875 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:32.875 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:32.875 TechnoTrend: DescrambleMultiple: serviceId:20002
31-03-2008 20:10:35.875 TechnoTrend: Get CI Slot State
31-03-2008 20:10:35.875 Technotrend: bdaapiCIGetSlotStatus failed:15
31-03-2008 20:10:35.875 TechnoTrend: DescrambleMultiple1)
31-03-2008 20:10:35.875 TechnoTrend: DescrambleMultiple: serviceId:20002


The TVServer tries to access the CI and to decrypt the channel. As there is NO CI its useless to even try (and timeout)! Please fix this... this would make channel change for me much faster. Or at least I get the error fast

Btw, this is found in the error.log of the TV Server:
2008-04-03 10:39:12.906250 [14]: Exception ystem.AccessViolationException: Attempted to read or write protected memory. This is often an indication that other memory is corrupt.
at TvLibrary.Interfaces.Analyzer.ITechnoTrend.IsCamPresent(Boolean& no)
at TvLibrary.Implementations.DVB.TechnoTrend.IsCamPresent()
at TvLibrary.Implementations.DVB.ConditionalAccess.IsCamReady()
2008-04-03 10:39:12.906250 [14]: Exception :Attempted to read or write protected memory. This is often an indication that other memory is corrupt.
2008-04-03 10:39:12.906250 [14]: site :Int32 IsCamPresent(Boolean ByRef)
2008-04-03 10:39:12.906250 [14]: source :TVLibrary
2008-04-03 10:39:12.906250 [14]: stacktrace: at TvLibrary.Interfaces.Analyzer.ITechnoTrend.IsCamPresent(Boolean& no)
at TvLibrary.Implementations.DVB.TechnoTrend.IsCamPresent()
at TvLibrary.Implementations.DVB.ConditionalAccess.IsCamReady()
2008-04-03 10:39:15.921875 [4]: Exception ystem.AccessViolationException: Attempted to read or write protected memory. This is often an indication that other memory is corrupt.
at TvLibrary.Interfaces.Analyzer.ITechnoTrend.IsCamPresent(Boolean& no)
at TvLibrary.Implementations.DVB.TechnoTrend.IsCamPresent()
at TvLibrary.Implementations.DVB.ConditionalAccess.IsCamReady()
...

Seems you have not implemented the interface?

TIA, Thomas

 

[tzi]

Hi!

Bump this thread.
I gots a million of errors. Example, I switch to SAT 1 Austria:

2008-04-07 20:54:53.828125 [16]: card: CardTune 2 SAT.1 A tzi:2:-1
2008-04-07 20:54:53.828125 [16]: card: Tune 2 to SAT.1 A
2008-04-07 20:54:53.828125 [16]: card: user: tzi:2:-1 tune DVBS:tvroSiebenSat.1 SAT.1 A Freq:12051000 ONID:1 TSID:1082 SID:20005 PMT:404 FTA:True LCN:333 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-04-07 20:54:53.843750 [16]: dvbs: TuneVBS:tvroSiebenSat.1 SAT.1 A Freq:12051000 ONID:1 TSID:1082 SID:20005 PMT:404 FTA:True LCN:333 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-04-07 20:54:53.843750 [16]: LNB low:9750 hi:10600 switch:11700
2008-04-07 20:54:53.843750 [16]: Technotrend DVB-S2 modulation set to:ModNotSet
2008-04-07 20:54:53.843750 [16]: Technotrend DVB-S2 Pilot set to:NotSet
2008-04-07 20:54:53.843750 [16]: Technotrend DVB-S2 RollOff set to:NotSet
2008-04-07 20:54:53.843750 [16]: Technotrend DVB-S2 fec set to:Rate3_4
2008-04-07 20:54:53.843750 [16]: Channel modulation is set to ModNotSet
2008-04-07 20:54:53.843750 [16]: Channel FECRate is set to Rate3_4
2008-04-07 20:54:53.859375 [16]: TechnoTrend SendDiseqcCommand() diseqc:None, antenna:0 frequency:12051000, polarisation:LinearV hiband:True
2008-04-07 20:54:54.328125 [16]: dvbubmiting tunerequest ChannelAT.1 A subChannel:-1
2008-04-07 20:54:54.328125 [16]: dvb:Getting new subchannel
2008-04-07 20:54:54.328125 [16]: dvb:GetNewSubChannel:0 #0
2008-04-07 20:54:54.343750 [16]: dvbubmit tunerequest size:1 new:0
2008-04-07 20:54:54.343750 [16]: subch:0 OnBeforeTune
2008-04-07 20:54:54.906250 [16]: subch:0 OnAfterTune
2008-04-07 20:54:54.906250 [16]: subch:0 OnGraphStart
2008-04-07 20:54:54.906250 [16]: subch:0 RunGraph: already running
2008-04-07 20:54:54.906250 [16]: subch:0 SetupPmtGrabberid 404 sid:4E25
2008-04-07 20:54:54.906250 [16]: subch:0 set pmt grabber pmt:404 sid:4E25
2008-04-07 20:54:54.906250 [16]: subch:0 wait for pmt 404
2008-04-07 20:54:54.921875 [4]: subch:0 OnPMTReceived() True
2008-04-07 20:54:54.921875 [4]: subch:0 SendPmt:4E25 4E25 FFFFFFFF 1D
2008-04-07 20:54:54.921875 [4]: Decode pmt
2008-04-07 20:54:54.921875 [4]: Set descriptor data with length 3
2008-04-07 20:54:54.921875 [4]: Set descriptor data with length 3
2008-04-07 20:54:54.921875 [4]: PROBLEM : descriptor lengths dont match 6 9
2008-04-07 20:54:54.937500 [4]: Set descriptor data with length 3
2008-04-07 20:54:54.937500 [4]: PROBLEM : descriptor lengths dont match 7 10
2008-04-07 20:54:54.937500 [4]: Set descriptor data with length 6
2008-04-07 20:54:54.937500 [4]: PROBLEM : descriptor lengths dont match 3 9
2008-04-07 20:54:54.937500 [4]: subch:0 SendPMT version:29 len:71 20005
2008-04-07 20:54:54.937500 [16]: subch:0 wait for pmt 404
2008-04-07 20:54:54.937500 [4]: subch:0 SendPmt failed cam flags:True
2008-04-07 20:54:54.953125 [16]: subch:0 wait for pmt 404
.. last line repeated about 50 times .. then
2008-04-07 20:54:57.937500 [11]: subch:0 SendPmt:4E25 4E25 FFFFFFFF 1D
2008-04-07 20:54:57.937500 [11]: Decode pmt
2008-04-07 20:54:57.937500 [11]: Set descriptor data with length 3
2008-04-07 20:54:57.937500 [11]: Set descriptor data with length 3
2008-04-07 20:54:57.937500 [11]: PROBLEM : descriptor lengths dont match 6 9
2008-04-07 20:54:57.937500 [11]: Set descriptor data with length 3
2008-04-07 20:54:57.937500 [11]: PROBLEM : descriptor lengths dont match 7 10
2008-04-07 20:54:57.937500 [11]: Set descriptor data with length 6
2008-04-07 20:54:57.937500 [11]: PROBLEM : descriptor lengths dont match 3 9
2008-04-07 20:54:57.937500 [11]: subch:0 SendPMT version:29 len:71 20005
2008-04-07 20:54:57.937500 [16]: subch:0 wait for pmt 404
2008-04-07 20:54:57.953125 [11]: subch:0 SendPmt failed cam flags:True
2008-04-07 20:54:57.968750 [16]: subch:0 wait for pmt 404
2008-04-07 20:54:57.984375 [16]: subch:0 wait for pmt 404
.. and so on .. takes 15 seconds then:
2008-04-07 20:55:04.890625 [16]: subch:0 wait for pmt 404
2008-04-07 20:55:04.906250 [16]: card: tuned user: tzi subchannel: 0
2008-04-07 20:55:04.906250 [16]: user:tzi add
2008-04-07 20:55:04.921875 [16]: card: Tuner locked: True
2008-04-07 20:55:04.921875 [16]: **************************************************
2008-04-07 20:55:04.921875 [16]: ***** SIGNAL LEVEL: 79, SIGNAL QUALITY: 100 *****
2008-04-07 20:55:04.921875 [16]: **************************************************
2008-04-07 20:55:04.921875 [16]: card2:tzi 2 0
2008-04-07 20:55:04.921875 [16]: control1:tzi 2 0
2008-04-07 20:55:04.921875 [16]: control2:tzi 2 0
2008-04-07 20:55:04.921875 [16]: Controller: delete timeshift files E:\Divx\TOBURN\Capture\card1\live2-0.ts
2008-04-07 20:55:04.937500 [16]: Controller: delete E:\Divx\TOBURN\Capture\card1\live2-0.ts.tsbuffer
2008-04-07 20:55:04.937500 [16]: Controller: delete E:\Divx\TOBURN\Capture\card1\live2-0.ts.tsbuffer1.ts
2008-04-07 20:55:04.937500 [16]: card: StartTimeShifting 2 E:\Divx\TOBURN\Capture\card1\live2-0.ts
2008-04-07 20:55:04.937500 [16]: card: WaitForUnScrambledSignal
2008-04-07 20:55:04.937500 [16]: card: return not scrambled
2008-04-07 20:55:04.937500 [16]: subch:0 SetTimeShiftFileName:E:\Divx\TOBURN\Capture\card1\live2-0.ts
2008-04-07 20:55:04.937500 [16]: subch:0 SetTimeShiftFileName fill in pids
2008-04-07 20:55:04.953125 [16]: Set video / audio observer
2008-04-07 20:55:04.953125 [16]: card: WaitForTimeShiftFile
2008-04-07 20:55:04.953125 [16]: card: WaitForUnScrambledSignal
2008-04-07 20:55:04.953125 [16]: card: return not scrambled
2008-04-07 20:55:04.953125 [16]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo
2008-04-07 20:55:05.093750 [4]: PID seen - type = Audio
2008-04-07 20:55:05.093750 [4]: audioVideoEventHandler Audio
2008-04-07 20:55:05.109375 [4]: PID seen - type = Audio
2008-04-07 20:55:05.109375 [4]: audioVideoEventHandler Audio
2008-04-07 20:55:05.281250 [4]: PID seen - type = Video
2008-04-07 20:55:05.281250 [4]: audioVideoEventHandler Video
2008-04-07 20:55:05.281250 [16]: card: WaitForTimeShiftFile - start of the video & audio is seen
.. and SAT 1 is visible!

There MUST be some issue with PMT decoding!
I read a dozen of those 2008-04-07 20:55:03.968750 [9]: PROBLEM : descriptor lengths dont match 6 9
errors.

Bg, Thomas