Fix in SVN version 20480 causes recording to break (2 Viewers)

[tourettes]

gibman, I would recomend to create a binary patch that contains ONLY the bug fix, not the other PMT related changes that slipped into SVN Then mironicus has a good testing base to make sure that what part of the SVN commit is breaking stuff.

 

[gibman]

I have split up the fixes.

svn #20502 mantis; 0001703: Video / Audio stream detection fails sometimes - MediaPortal Bugtracker

svn #20503 mantis; 0001712: PMT sometimes not found / rework 'wait for PMT' mechanism. - MediaPortal Bugtracker

plz test them individually

/gibman

 

[mironicus]

svn 20502 works fine - no freezes even with multiple recordings from one transponder
svn 20503 Mediaportal freezes and tvserver crashes during multiple recordings from one transponder

 

[mironicus]

I tested svn 20514.

I get this error-report if I want to record more than one channel from one transponder.

2008-10-17 19:50:27.510668 [13]: Exception   :System.AccessViolationException: Es wurde versucht, im geschützten Speicher zu lesen oder zu schreiben. Dies ist häufig ein Hinweis darauf, dass anderer Speicher beschädigt ist.
   bei TvLibrary.Interfaces.Analyzer.ITsFilter.CaGetCaData(Int32 handle, IntPtr caData)
   bei TvLibrary.Implementations.DVB.TvDvbChannel.SendPmtToCam(Boolean& updatePids)
Exception   :Es wurde versucht, im geschützten Speicher zu lesen oder zu schreiben. Dies ist häufig ein Hinweis darauf, dass anderer Speicher beschädigt ist.
  site      :Int32 CaGetCaData(Int32, IntPtr)
  source    :TVLibrary
  stacktrace:   bei TvLibrary.Interfaces.Analyzer.ITsFilter.CaGetCaData(Int32 handle, IntPtr caData)
   bei TvLibrary.Implementations.DVB.TvDvbChannel.SendPmtToCam(Boolean& updatePids)

 

[tourettes]

By any change are you using MDAPI? (wich is not supported) Sorry, cannot look at logs as there is no winrar installed on this PC.

 

[mironicus]

With svn 20524 I get a different error message:

2008-10-18 10:41:18.874714 [5]: Exception in Recording.Persist() with Message Eine Ausnahme vom Typ "System.OutOfMemoryException" wurde ausgelöst.

And I get PMT timeouts.

2008-10-18 10:49:57.279034 [14]: subch:OnCaReceived()
2008-10-18 10:50:07.213945 [18]: WaitForPMT: Timed out waiting for PMT after 9,99936 seconds. Increase the PMT timeout value?
2008-10-18 10:50:07.214921 [18]: card: tuned user: scheduler262 subchannel: 4

 

[mironicus]

svn 20535 with the latest changes TVServer crashes instantly even after starting a single recording.

Error.log

2008-10-19 17:19:03.141856 [4]: Exception ystem.AccessViolationException: Es wurde versucht, im geschützten Speicher zu lesen oder zu schreiben. Dies ist häufig ein Hinweis darauf, dass anderer Speicher beschädigt ist.
bei TvLibrary.Interfaces.Analyzer.ITsFilter.CaGetCaData(Int32 handle, IntPtr caData)
bei TvLibrary.Implementations.DVB.TvDvbChannel.SendPmtToCam(Boolean& updatePids)
Exception :Es wurde versucht, im geschützten Speicher zu lesen oder zu schreiben. Dies ist häufig ein Hinweis darauf, dass anderer Speicher beschädigt ist.
site :Int32 CaGetCaData(Int32, IntPtr)
source :TVLibrary
stacktrace: bei TvLibrary.Interfaces.Analyzer.ITsFilter.CaGetCaData(Int32 handle, IntPtr caData)
bei TvLibrary.Implementations.DVB.TvDvbChannel.SendPmtToCam(Boolean& updatePids)

2008-10-19 17:19:03.183846 [4]: Exception ystem.AccessViolationException: Es wurde versucht, im geschützten Speicher zu lesen oder zu schreiben. Dies ist häufig ein Hinweis darauf, dass anderer Speicher beschädigt ist.
bei Microsoft.Win32.Win32Native.CoTaskMemFree(IntPtr ptr)
bei TvLibrary.Implementations.DVB.TvDvbChannel.SendPmtToCam(Boolean& updatePids)
bei TvLibrary.Implementations.DVB.TvDvbChannel.OnPMTReceived()
Exception :Es wurde versucht, im geschützten Speicher zu lesen oder zu schreiben. Dies ist häufig ein Hinweis darauf, dass anderer Speicher beschädigt ist.
site :Void CoTaskMemFree(IntPtr)
source :mscorlib
stacktrace: bei Microsoft.Win32.Win32Native.CoTaskMemFree(IntPtr ptr)
bei TvLibrary.Implementations.DVB.TvDvbChannel.SendPmtToCam(Boolean& updatePids)
bei TvLibrary.Implementations.DVB.TvDvbChannel.OnPMTReceived()

Tv.log

2008-10-19 17:10:00.137548 [12]: Recording heute added in _recordingsInProgressList
2008-10-19 17:10:00.151219 [12]: Scheduler : time to record TvDatabase.Channel 19.10.2008 17:10:00-19.10.2008 17:10:00 heute
2008-10-19 17:10:00.151219 [12]: Controller: find free card for channel ZDF
2008-10-19 17:10:00.153172 [12]: Controller: got 1 tuning details for ZDF
2008-10-19 17:10:00.154149 [12]: Controller: channel #1 DVBS:tv:ZDFvision ZDF Freq:11953000 ONID:1 TSID:1079 SID:28006 PMT:0x64 FTA:True LCN:610 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-10-19 17:10:00.160008 [12]: Controller: card:1 typevbS is tuned to same transponder decrypting 1/4 channels
2008-10-19 17:10:00.160984 [12]: Controller: card:1 typevbS is available priority:102 #users:1 same transponder:True
2008-10-19 17:10:00.162937 [12]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-10-19 17:10:00.162937 [12]: Controller: found 1 available
2008-10-19 17:10:00.165867 [12]: Scheduler : record on recommended card:1 priority:2
2008-10-19 17:10:00.166843 [12]: Scheduler : record, first tune to channel
2008-10-19 17:10:01.169709 [11]: card: Tune 1 to ZDF
2008-10-19 17:10:01.170685 [11]: card: user: scheduler281:1:-1 tune DVBS:tv:ZDFvision ZDF Freq:11953000 ONID:1 TSID:1079 SID:28006 PMT:0x64 FTA:True LCN:610 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-10-19 17:10:01.177521 [11]: dvbs: TuneVBS:tv:ZDFvision ZDF Freq:11953000 ONID:1 TSID:1079 SID:28006 PMT:0x64 FTA:True LCN:610 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-10-19 17:10:01.178497 [11]: dvb:GetNewSubChannel:1 #1
2008-10-19 17:10:01.179474 [11]: LNB low:9750 hi:10600 switch:11700
2008-10-19 17:10:01.180450 [11]: DigitalEverywhere DVB-S2 modulation set to:ModNotSet
2008-10-19 17:10:01.180450 [11]: DigitalEverywhere Pilot set to:NotSet
2008-10-19 17:10:01.181427 [11]: DigitalEverywhere RollOff set to:NotSet
2008-10-19 17:10:01.181427 [11]: DigitalEverywhere fec set to:3
2008-10-19 17:10:01.181427 [11]: Channel modulation is set to ModNotSet
2008-10-19 17:10:01.181427 [11]: Channel FECRate is set to Rate3_4
2008-10-19 17:10:01.182403 [11]: FireDTV: already tuned to diseqc:None, frequency:11953000, polarisation:LinearH
2008-10-19 17:10:01.282983 [11]: dvbubmiting tunerequest Channel:ZDF subChannel:1
2008-10-19 17:10:01.282983 [11]: dvb:using existing subchannel:1
2008-10-19 17:10:01.283959 [11]: dvbubmit tunerequest size:2 new:1
2008-10-19 17:10:01.283959 [11]: subch:1 OnBeforeTune
2008-10-19 17:10:01.284936 [11]: dvbubmit tunerequest calling put_TuneRequest
2008-10-19 17:10:01.284936 [11]: dvbubmit tunerequest done calling put_TuneRequest
2008-10-19 17:10:01.284936 [11]: subch:1 OnAfterTune
2008-10-19 17:10:01.285912 [11]: FireDTV: HW Filtering disabled.
2008-10-19 17:10:01.297630 [11]: subch:1 OnGraphStart
2008-10-19 17:10:01.297630 [11]: subch:1 SetupPmtGrabberid 64 sid:6D66
2008-10-19 17:10:01.298607 [11]: subch:1 set pmt grabber pmt:64 sid:6D66
2008-10-19 17:10:01.299583 [11]: subch:1 set ca grabber
2008-10-19 17:10:01.300560 [11]: WaitForPMT: Waiting for PMT.
2008-10-19 17:10:01.366962 [13]: subch:1 OnPMTReceived() True
2008-10-19 17:10:01.366962 [13]: subch:1 SendPmt:6D66 6D66 FFFFFFFF F
2008-10-19 17:10:01.366962 [13]: Decode pmt
2008-10-19 17:10:01.367938 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.367938 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.367938 [13]: PROBLEM : descriptor lengths dont match 7 10
2008-10-19 17:10:01.368915 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.368915 [13]: PROBLEM : descriptor lengths dont match 6 9
2008-10-19 17:10:01.368915 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.369891 [13]: PROBLEM : descriptor lengths dont match 6 9
2008-10-19 17:10:01.369891 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.369891 [13]: PROBLEM : descriptor lengths dont match 6 9
2008-10-19 17:10:01.370868 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.370868 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.370868 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.370868 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.371844 [13]: Set descriptor data with length 3
2008-10-19 17:10:01.371844 [13]: Set descriptor data with length 10
2008-10-19 17:10:01.371844 [13]: PROBLEM : descriptor lengths dont match 3 13

 

[mironicus]

svn 20539
With this build the problems with freezing and recording multiple channels from the same transponders are solved.

But there is another problem. Switching between channels can take up to 10 seconds now. Just see in the log. It's still waiting for PMT. The behaviour seems randomly. Sometimes the switching between channels takes 1-2 seconds, sometimes it's 10 seconds and this is written in the logs:

2008-10-20 10:09:37.656204 [13]: audioVideoEventHandler Audio
2008-10-20 10:09:47.055016 [8]: WaitForPMT: Timed out waiting for PMT after 9,99936 seconds. Increase the PMT timeout value?
2008-10-20 10:09:47.127277 [8]: card: Tuner locked: True

 

[iosub]

svn 20539
With this build the problems with freezing and recording multiple channels from the same transponders are solved.

But there is another problem. Switching between channels can take up to 10 seconds now. Just see in the log. It's still waiting for PMT. The behaviour seems randomly. Sometimes the switching between channels takes 1-2 seconds, sometimes it's 10 seconds and this is written in the logs:

2008-10-20 10:09:37.656204 [13]: audioVideoEventHandler Audio
2008-10-20 10:09:47.055016 [8]: WaitForPMT: Timed out waiting for PMT after 9,99936 seconds. Increase the PMT timeout value?
2008-10-20 10:09:47.127277 [8]: card: Tuner locked: True

I can confirm here the same problem

Iosu

 

[gibman]

I've done some 100s channel changes using the "tvserverstresstest 100" cmd line.

grab it here;
https://mediaportal.svn.sourceforge.net/svnroot/mediaportal/trunk/Tools/TvserverStressTest/

Try the attached bins.

/gibman