[confirm] Random glitches in LiveTV ("Unexpected LiveTV PAT change" log) (1 Viewer)

peque

Moderator - Spanish Forums
  • Premium Supporter
  • August 4, 2007
    859
    98
    Spain Spain
    Country flag
    • Thread starter
    • Moderator
    • #51
    morpheus_xx... I don't know if you've comitted this tsreader&tswriter to SVN... but I've found a problem with them... Exactly with tswriter... It was working right... but suddenly it somehow got broken. To reproduce problem, I do this:

    - Start livetv for the first time, in any channel, no prob.
    - Stop livetv, and start it again IN SAME channel.
    - First time you do this, livetv takes 10 or 15 seconds to work.
    - Second time you stop and start, you get a "unable to start graph", and you can't see this channel any more... You must select another channel... then it works again...

    So it's a problem when starting livetv in same channel you were watching when you stopped. If you choose any other channel, there's no problem.

    I've been reviewing my logs, and I've come across a very interesing message, that I get when I stop livetv for the first time:

    Code:
    2010-05-09 03:03:33.207212 [(12)]: !!! Error releasing TSWriter filter (_filterTsWriter as IBaseFilter was null!)
    TsWriter filter isn't released for some reason, so it can do its job anymore...

    Please tell me if you need any more info.

    Just going back to last "tswriter only" fix, fixes this issue...

    Sorry for the bad news... bye!
     

    peque

    Moderator - Spanish Forums
  • Premium Supporter
  • August 4, 2007
    859
    98
    Spain Spain
    Country flag
    • Thread starter
    • Moderator
    • #52
    I've repeated my tests with last available patched filters, but doing it "the right way", uninstalling old filters (regsvr32 /u), replacing files and installing new ones (regsvr32), because I can't understand what's going on on my system... but same results... I can't understand why I had no problems for some days, but nowadays, these patched filters are unusable on my system... I can watch a channel and do zapping with no problem for the first time, but stopping livetv triggers above tv.log, and subsequent trials of going into livetv fail because of that... Just changing last patched tswriter with the one provided just before (so leaving last patched tsreader.ax), I get rid of problems...

    No one else is having problems with this? I'm a little annoyed, because I've seen that morpheus has comitted changes to RC3, and I wouldn't like to not be able to use RC3 because of this!!! May be a complete MP RC2 reinstall can help... but I can't do it so I'm just having some busy days now... :(

    Thanks!
     

    tourettes

    Retired Team Member
  • Premium Supporter
  • January 7, 2005
    17,301
    4,800
    I've repeated my tests with last available patched filters, but doing it "the right way", uninstalling old filters (regsvr32 /u), replacing files and installing new ones (regsvr32), because I can't understand what's going on on my system... but same results... I can't understand why I had no problems for some days, but nowadays, these patched filters are unusable on my system... I can watch a channel and do zapping with no problem for the first time, but stopping livetv triggers above tv.log, and subsequent trials of going into livetv fail because of that... Just changing last patched tswriter with the one provided just before (so leaving last patched tsreader.ax), I get rid of problems...

    No one else is having problems with this? I'm a little annoyed, because I've seen that morpheus has comitted changes to RC3, and I wouldn't like to not be able to use RC3 because of this!!! May be a complete MP RC2 reinstall can help... but I can't do it so I'm just having some busy days now... :(
    Please test if RC2 binaries (TsWriter.ax and TsReader.ax) are causing now the same issue. Since changes morpheus has done shouldn't affect the filter loading / releasing.
     

    peque

    Moderator - Spanish Forums
  • Premium Supporter
  • August 4, 2007
    859
    98
    Spain Spain
    Country flag
    • Thread starter
    • Moderator
    • #54
    Ok tourettes... Could you please post here original RC2 filters? I use to save all my files, but I had a problem and I lost original filters.. I tried to extract them from RC2 install package (using Universal Extractor), but I didn't find these files inside...

    Also note that with tsreader from this post, and first patched tswriter also in this post, I DON'T have any problem.

    Thank you!
     

    peque

    Moderator - Spanish Forums
  • Premium Supporter
  • August 4, 2007
    859
    98
    Spain Spain
    Country flag
    • Thread starter
    • Moderator
    • #55
    Ok tourettes... Could you please post here original RC2 filters? I use to save all my files, but I had a problem and I lost original filters.. I tried to extract them from RC2 install package (using Universal Extractor), but I didn't find these files inside...

    Also note that with tsreader from this post, and first patched tswriter also in this post, I DON'T have any problem.

    Thank you!
    Forget it. I managed to install MP 1.1.0 RC2 on another PC, and get filters from that installation.

    Result: no problems so far... Just these weird glitches due to pmt changes...

    :(

    Let me know if I can do anything to help...
     

    morpheus_xx

    Lead Dev MP2
  • Team MediaPortal
  • March 24, 2007
    11,601
    7,060
    Germany Germany
    Country flag
    AW: Random glitches in LiveTV ("Unexpected LiveTV PAT change" log)

    can you confirm that your new issues are gone, if you revert to tsreader of RC2?

    if my tswriter here from thread solves the glitches, than the RC3 will only contain TsWriter, and TsReader will be reverted.
     

    peque

    Moderator - Spanish Forums
  • Premium Supporter
  • August 4, 2007
    859
    98
    Spain Spain
    Country flag
    • Thread starter
    • Moderator
    • #57
    I can confirm that with tsreader from final patch, and tswriter from first patch are working well together... When I try change from first tswriter to last tswriter, I run into explained problems... In half an hour I'll try what you are asking for and report back (tsreader from RC2, last tswriter from this post).
     

    peque

    Moderator - Spanish Forums
  • Premium Supporter
  • August 4, 2007
    859
    98
    Spain Spain
    Country flag
    • Thread starter
    • Moderator
    • #58
    Ok... tests done.

    TSREADER (RC2) & TSWRITER (LAST PATCH IN THIS POST)

    I DON'T have filter release problems... BUT, in subsequents "start livetv" and "stop livetv" inside MyTV (so, no zapping between start and stop), I suffer from ocasional timeouts finding PMT... It always ends starting livetv, though, but with some lag. tv.log for one of these timeouts, and how first retry works correctly:

    Code:
    2010-05-13 09:06:02.666806 [(20)]: WaitForPMT: Waiting for PMT 5DC
    2010-05-13 09:06:02.989806 [(15)]: subch:OnCaReceived()
    [b]2010-05-13 09:06:12.666806 [(20)]: WaitForPMT: Timed out waiting for PMT after 10,001 seconds. Increase the PMT timeout value?[/b]
    2010-05-13 09:06:12.666806 [(20)]: Setting to 0 to search for new PMT.
    2010-05-13 09:06:12.667806 [(20)]: subch:0 SetupPmtGrabber:pid 0 sid:154
    2010-05-13 09:06:12.667806 [(20)]: subch:0 set pmt grabber pmt:0 sid:154
    2010-05-13 09:06:12.668806 [(20)]: subch:0 set ca grabber 
    2010-05-13 09:06:12.669806 [(20)]: WaitForPMT: Waiting for PMT 0
    2010-05-13 09:06:12.960806 [(15)]: subch:OnCaReceived()
    2010-05-13 09:06:22.672806 [(20)]: WaitForPMT: Timed out waiting for PMT after 10,003 seconds. Increase the PMT timeout value?
    2010-05-13 09:06:22.673806 [(20)]: Setting to 0 to search for new PMT.
    2010-05-13 09:06:22.674806 [(20)]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
    2010-05-13 09:06:23.706806 [(20)]: card: Tuner locked: True
    2010-05-13 09:06:23.706806 [(20)]: **************************************************
    2010-05-13 09:06:23.707806 [(20)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
    2010-05-13 09:06:23.707806 [(20)]: **************************************************
    2010-05-13 09:06:23.708806 [(20)]: card: tuned user: MarzoMP subchannel: 0
    2010-05-13 09:06:23.708806 [(20)]: user:MarzoMP add
    2010-05-13 09:06:23.709806 [(20)]: card2:MarzoMP 18 0
    2010-05-13 09:06:23.709806 [(20)]: Controller: MarzoMP 18 0
    2010-05-13 09:06:23.710806 [(20)]: control2:MarzoMP 18 0
    2010-05-13 09:06:23.712806 [(20)]: Controller: delete timeshift files G:\Grabaciones_TV\Timeshift\live18-0.ts
    2010-05-13 09:06:23.726806 [(20)]: card: StartTimeShifting 18 G:\Grabaciones_TV\Timeshift\live18-0.ts 
    2010-05-13 09:06:23.726806 [(20)]: card: CAM enabled : False
    2010-05-13 09:06:23.727806 [(20)]: start subch:0 No PMT received. Timeshifting failed
    2010-05-13 09:06:23.727806 [(20)]: card Hauppauge WinTV 88x DVB-T Tuner/Demod: StopTimeShifting user:MarzoMP sub:0
    2010-05-13 09:06:23.728806 [(20)]: card: StopTimeShifting user:MarzoMP sub:0
    2010-05-13 09:06:23.729806 [(20)]: card not IDLE - removing user: MarzoMP
    2010-05-13 09:06:23.730806 [(20)]: user:MarzoMP remove
    2010-05-13 09:06:23.730806 [(20)]: mdplug: FreeChannel laSexta
    2010-05-13 09:06:23.731806 [(20)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
    2010-05-13 09:06:23.734806 [(20)]: DVB subch:0 Decompose()
    2010-05-13 09:06:23.740806 [(20)]: FreeSubChannel CA: freeing sub channel : 0
    2010-05-13 09:06:23.741806 [(20)]: tvcard:FreeSubChannel : no subchannels present, stopping graph
    2010-05-13 09:06:23.741806 [(20)]: dvb:StopGraph called
    2010-05-13 09:06:23.742806 [(20)]: tvcard:FreeAllSubChannels
    2010-05-13 09:06:23.742806 [(20)]: mdplug: FreeAllChannels
    2010-05-13 09:06:23.752806 [(20)]: dvb:StopGraph
    2010-05-13 09:06:23.763806 [TS_File_Cleanup(18)]: card: delete timeshift files G:\Grabaciones_TV\Timeshift\live18-0.ts
    2010-05-13 09:06:23.765806 [(20)]: dvb:StopGraph called
    2010-05-13 09:06:23.772806 [(20)]: tvcard:FreeAllSubChannels
    2010-05-13 09:06:23.773806 [(20)]: mdplug: FreeAllChannels
    2010-05-13 09:06:23.774806 [(20)]: dvb:StopGraph filterstate already stopped, returning.
    2010-05-13 09:06:23.775806 [(20)]: user:MarzoMP remove
    2010-05-13 09:06:23.777806 [(20)]: Controller: Timeshifting failed, lets try next available card.
    2010-05-13 09:06:23.780806 [(20)]: card: CardTune 17 laSexta MarzoMP:17:-1
    2010-05-13 09:06:23.780806 [(20)]: card: Tune 17 to laSexta
    2010-05-13 09:06:23.781806 [(20)]: card: user: MarzoMP:17:-1 tune DVBT:tv:La Sexta laSexta Freq:842000 ONID:8916 TSID:2 SID:340 PMT:0x5DC FTA:True LCN:6 BandWidth:8
    2010-05-13 09:06:23.790806 [(20)]: card: to different transponder
    2010-05-13 09:06:23.791806 [(20)]:   stop subchannel: 0 user: epg
    2010-05-13 09:06:23.791806 [(20)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
    2010-05-13 09:06:23.792806 [(20)]: DVB subch:0 Decompose()
    2010-05-13 09:06:23.794806 [(20)]: FreeSubChannel CA: freeing sub channel : 0
    2010-05-13 09:06:23.795806 [(20)]: tvcard:FreeSubChannel : no subchannels present, stopping graph
    2010-05-13 09:06:23.795806 [(20)]: dvb:StopGraph called
    2010-05-13 09:06:23.796806 [(20)]: tvcard:FreeAllSubChannels
    2010-05-13 09:06:23.796806 [(20)]: dvb:StopGraph
    2010-05-13 09:06:23.798806 [(20)]: user:epg remove
    2010-05-13 09:06:23.798806 [(20)]: dvbs ss2: Tune:DVBT:tv:La Sexta laSexta Freq:842000 ONID:8916 TSID:2 SID:340 PMT:0x5DC FTA:True LCN:6 BandWidth:8
    2010-05-13 09:06:23.799806 [(20)]: ss2:Tune(DVBT:tv:La Sexta laSexta Freq:842000 ONID:8916 TSID:2 SID:340 PMT:0x5DC FTA:True LCN:6 BandWidth:8)
    2010-05-13 09:06:23.799806 [(20)]: dvb:GetNewSubChannel:0 #0
    2010-05-13 09:06:23.802806 [(20)]: TvDvbChannel ctor new subchIndex:0
    2010-05-13 09:06:23.802806 [(20)]: subch:0 OnBeforeTune
    2010-05-13 09:06:23.805806 [(20)]: ss2:  Transponder Frequency:842 MHz
    2010-05-13 09:06:23.805806 [(20)]: ss2:  GuardInterval:auto
    2010-05-13 09:06:23.805806 [(20)]: ss2:  Bandwidth:8 MHz
    2010-05-13 09:06:24.056806 [(20)]: ss2:hw pids:all
    2010-05-13 09:06:24.056806 [(20)]: subch:0 OnAfterTune
    2010-05-13 09:06:24.057806 [(20)]: subch:0 OnGraphStart
    2010-05-13 09:06:24.058806 [(20)]: dvb:  RunGraph
    2010-05-13 09:06:24.359806 [(20)]: dvb-s ss2:  LockedInOnSignal ok
    2010-05-13 09:06:24.359806 [(20)]: subch:0 OnGraphStarted
    2010-05-13 09:06:24.360806 [(20)]: subch:0 SetupPmtGrabber:pid 5DC sid:154
    2010-05-13 09:06:24.360806 [(20)]: subch:0 set pmt grabber pmt:5DC sid:154
    2010-05-13 09:06:24.361806 [(20)]: WaitForPMT: Waiting for PMT 5DC
    2010-05-13 09:06:24.632806 [(24)]: subch:0 OnPMTReceived() pmt:5DC ran:True dynamic:False
    2010-05-13 09:06:24.633806 [(20)]: WaitForPMT: Found PMT after 0,271 seconds.
    TSREADER (RC2) & TSWRITER (FIRST PATCH IN THIS POST)

    I NEITHER have filter release problems, NOR timeouts finding PMT...

    So, as stated before, first patched tswriter is the best combination for me. Sorry if it's not what you wanted to hear...
     

    iosub

    Portal Pro
    April 26, 2006
    573
    30
    San Sebastian
    Ok... tests done.

    TSREADER (RC2) & TSWRITER (LAST PATCH IN THIS POST)

    I DON'T have filter release problems... BUT, in subsequents "start livetv" and "stop livetv" inside MyTV (so, no zapping between start and stop), I suffer from ocasional timeouts finding PMT... It always ends starting livetv, though, but with some lag. tv.log for one of these timeouts, and how first retry works correctly:

    Code:
    2010-05-13 09:06:02.666806 [(20)]: WaitForPMT: Waiting for PMT 5DC
    2010-05-13 09:06:02.989806 [(15)]: subch:OnCaReceived()
    [b]2010-05-13 09:06:12.666806 [(20)]: WaitForPMT: Timed out waiting for PMT after 10,001 seconds. Increase the PMT timeout value?[/b]
    2010-05-13 09:06:12.666806 [(20)]: Setting to 0 to search for new PMT.
    2010-05-13 09:06:12.667806 [(20)]: subch:0 SetupPmtGrabber:pid 0 sid:154
    2010-05-13 09:06:12.667806 [(20)]: subch:0 set pmt grabber pmt:0 sid:154
    2010-05-13 09:06:12.668806 [(20)]: subch:0 set ca grabber 
    2010-05-13 09:06:12.669806 [(20)]: WaitForPMT: Waiting for PMT 0
    2010-05-13 09:06:12.960806 [(15)]: subch:OnCaReceived()
    2010-05-13 09:06:22.672806 [(20)]: WaitForPMT: Timed out waiting for PMT after 10,003 seconds. Increase the PMT timeout value?
    2010-05-13 09:06:22.673806 [(20)]: Setting to 0 to search for new PMT.
    2010-05-13 09:06:22.674806 [(20)]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
    2010-05-13 09:06:23.706806 [(20)]: card: Tuner locked: True
    2010-05-13 09:06:23.706806 [(20)]: **************************************************
    2010-05-13 09:06:23.707806 [(20)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
    2010-05-13 09:06:23.707806 [(20)]: **************************************************
    2010-05-13 09:06:23.708806 [(20)]: card: tuned user: MarzoMP subchannel: 0
    2010-05-13 09:06:23.708806 [(20)]: user:MarzoMP add
    2010-05-13 09:06:23.709806 [(20)]: card2:MarzoMP 18 0
    2010-05-13 09:06:23.709806 [(20)]: Controller: MarzoMP 18 0
    2010-05-13 09:06:23.710806 [(20)]: control2:MarzoMP 18 0
    2010-05-13 09:06:23.712806 [(20)]: Controller: delete timeshift files G:\Grabaciones_TV\Timeshift\live18-0.ts
    2010-05-13 09:06:23.726806 [(20)]: card: StartTimeShifting 18 G:\Grabaciones_TV\Timeshift\live18-0.ts 
    2010-05-13 09:06:23.726806 [(20)]: card: CAM enabled : False
    2010-05-13 09:06:23.727806 [(20)]: start subch:0 No PMT received. Timeshifting failed
    2010-05-13 09:06:23.727806 [(20)]: card Hauppauge WinTV 88x DVB-T Tuner/Demod: StopTimeShifting user:MarzoMP sub:0
    2010-05-13 09:06:23.728806 [(20)]: card: StopTimeShifting user:MarzoMP sub:0
    2010-05-13 09:06:23.729806 [(20)]: card not IDLE - removing user: MarzoMP
    2010-05-13 09:06:23.730806 [(20)]: user:MarzoMP remove
    2010-05-13 09:06:23.730806 [(20)]: mdplug: FreeChannel laSexta
    2010-05-13 09:06:23.731806 [(20)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
    2010-05-13 09:06:23.734806 [(20)]: DVB subch:0 Decompose()
    2010-05-13 09:06:23.740806 [(20)]: FreeSubChannel CA: freeing sub channel : 0
    2010-05-13 09:06:23.741806 [(20)]: tvcard:FreeSubChannel : no subchannels present, stopping graph
    2010-05-13 09:06:23.741806 [(20)]: dvb:StopGraph called
    2010-05-13 09:06:23.742806 [(20)]: tvcard:FreeAllSubChannels
    2010-05-13 09:06:23.742806 [(20)]: mdplug: FreeAllChannels
    2010-05-13 09:06:23.752806 [(20)]: dvb:StopGraph
    2010-05-13 09:06:23.763806 [TS_File_Cleanup(18)]: card: delete timeshift files G:\Grabaciones_TV\Timeshift\live18-0.ts
    2010-05-13 09:06:23.765806 [(20)]: dvb:StopGraph called
    2010-05-13 09:06:23.772806 [(20)]: tvcard:FreeAllSubChannels
    2010-05-13 09:06:23.773806 [(20)]: mdplug: FreeAllChannels
    2010-05-13 09:06:23.774806 [(20)]: dvb:StopGraph filterstate already stopped, returning.
    2010-05-13 09:06:23.775806 [(20)]: user:MarzoMP remove
    2010-05-13 09:06:23.777806 [(20)]: Controller: Timeshifting failed, lets try next available card.
    2010-05-13 09:06:23.780806 [(20)]: card: CardTune 17 laSexta MarzoMP:17:-1
    2010-05-13 09:06:23.780806 [(20)]: card: Tune 17 to laSexta
    2010-05-13 09:06:23.781806 [(20)]: card: user: MarzoMP:17:-1 tune DVBT:tv:La Sexta laSexta Freq:842000 ONID:8916 TSID:2 SID:340 PMT:0x5DC FTA:True LCN:6 BandWidth:8
    2010-05-13 09:06:23.790806 [(20)]: card: to different transponder
    2010-05-13 09:06:23.791806 [(20)]:   stop subchannel: 0 user: epg
    2010-05-13 09:06:23.791806 [(20)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
    2010-05-13 09:06:23.792806 [(20)]: DVB subch:0 Decompose()
    2010-05-13 09:06:23.794806 [(20)]: FreeSubChannel CA: freeing sub channel : 0
    2010-05-13 09:06:23.795806 [(20)]: tvcard:FreeSubChannel : no subchannels present, stopping graph
    2010-05-13 09:06:23.795806 [(20)]: dvb:StopGraph called
    2010-05-13 09:06:23.796806 [(20)]: tvcard:FreeAllSubChannels
    2010-05-13 09:06:23.796806 [(20)]: dvb:StopGraph
    2010-05-13 09:06:23.798806 [(20)]: user:epg remove
    2010-05-13 09:06:23.798806 [(20)]: dvbs ss2: Tune:DVBT:tv:La Sexta laSexta Freq:842000 ONID:8916 TSID:2 SID:340 PMT:0x5DC FTA:True LCN:6 BandWidth:8
    2010-05-13 09:06:23.799806 [(20)]: ss2:Tune(DVBT:tv:La Sexta laSexta Freq:842000 ONID:8916 TSID:2 SID:340 PMT:0x5DC FTA:True LCN:6 BandWidth:8)
    2010-05-13 09:06:23.799806 [(20)]: dvb:GetNewSubChannel:0 #0
    2010-05-13 09:06:23.802806 [(20)]: TvDvbChannel ctor new subchIndex:0
    2010-05-13 09:06:23.802806 [(20)]: subch:0 OnBeforeTune
    2010-05-13 09:06:23.805806 [(20)]: ss2:  Transponder Frequency:842 MHz
    2010-05-13 09:06:23.805806 [(20)]: ss2:  GuardInterval:auto
    2010-05-13 09:06:23.805806 [(20)]: ss2:  Bandwidth:8 MHz
    2010-05-13 09:06:24.056806 [(20)]: ss2:hw pids:all
    2010-05-13 09:06:24.056806 [(20)]: subch:0 OnAfterTune
    2010-05-13 09:06:24.057806 [(20)]: subch:0 OnGraphStart
    2010-05-13 09:06:24.058806 [(20)]: dvb:  RunGraph
    2010-05-13 09:06:24.359806 [(20)]: dvb-s ss2:  LockedInOnSignal ok
    2010-05-13 09:06:24.359806 [(20)]: subch:0 OnGraphStarted
    2010-05-13 09:06:24.360806 [(20)]: subch:0 SetupPmtGrabber:pid 5DC sid:154
    2010-05-13 09:06:24.360806 [(20)]: subch:0 set pmt grabber pmt:5DC sid:154
    2010-05-13 09:06:24.361806 [(20)]: WaitForPMT: Waiting for PMT 5DC
    2010-05-13 09:06:24.632806 [(24)]: subch:0 OnPMTReceived() pmt:5DC ran:True dynamic:False
    2010-05-13 09:06:24.633806 [(20)]: WaitForPMT: Found PMT after 0,271 seconds.
    TSREADER (RC2) & TSWRITER (FIRST PATCH IN THIS POST)

    I NEITHER have filter release problems, NOR timeouts finding PMT...

    So, as stated before, first patched tswriter is the best combination for me. Sorry if it's not what you wanted to hear...
    Hi Same results
    I can confirm...
    Iosu
     

    Users who are viewing this thread

    Top Bottom