dropouts when watching hd tv (3 Viewers)

[Sebastiii]

Hi Guys,

I have recently some MP freeze (client) and i think it comes from latest TsReader :

Spoiler: Log

[2015-01-18 13:49:20,597] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart()-- LiveTv : 1, TimeShifting: 1 338.575 ( Abs 338.575000 / 338.575958 )- isSamePosn: 0, OnZap: 1, Force 0, ForceRC 0, Media changing 1
[2015-01-18 13:49:20,597] [ b6b2068] [ ad8] - Zap to File Seek : 1 mS ( 338.575000 / 338.575000 ) LiveTv : 1, Seek : 0
[2015-01-18 13:49:20,605] [ b6b2068] [7300] - vidPin:OnThreadStartPlay(338.575000), rate:1.00, threadID:0x7300, GET_TIME_NOW:0x4005c3ce
[2015-01-18 13:49:20,605] [ b6b2068] [7300] - vidPineliverNewSegment(start 338.575000, stop 461168601842.738770), rate:1.00
[2015-01-18 13:49:20,605] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart() End 1
[2015-01-18 13:49:20,605] [ b6b2068] [6710] - audPin:OnThreadStartPlay(338.575000), rate:1.00, threadID:0x6710, GET_TIME_NOW:0x4005c3ce
[2015-01-18 13:49:20,605] [ b6b2068] [ ad8] - audPin: UpdateFromSeek, m_rtStart 338.575000, m_dRateSeeking 1.000000
[2015-01-18 13:49:20,605] [ b6b2068] [6710] - audPineliverNewSegment(start 338.575000, stop 461168601842.738770), rate:1.00
[2015-01-18 13:49:20,605] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart()-- No new seek 338.575000 ( Abs 338.575000 / 338.575958 ) - isSamePosn: 1, OnZap: 0, Force 0, Media changing: 1
[2015-01-18 13:49:20,605] [ b6b2068] [ ad8] - audPin: UpdateFromSeek, m_rtStart 338.575000, m_dRateSeeking 1.000000
[2015-01-18 13:49:20,606] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart()-- No new seek 338.575000 ( Abs 338.575000 / 338.575958 ) - isSamePosn: 1, OnZap: 0, Force 0, Media changing: 1
[2015-01-18 13:49:20,606] [ b6b2068] [258c] - CTsReaderFilter:: ForcePosnUpdate: 338.575 s
[2015-01-18 13:49:20,716] [ b6b2068] [2808] - DeMultiplexer: First Gop after new PAT, 544x576 @ 4:3, 25.000Hz interlaced
[2015-01-18 13:49:20,716] [ b6b2068] [2808] - MPEG I-FRAME found 339.171000
[2015-01-18 13:49:20,716] [ b6b2068] [2808] - DeMultiplexer: Got GOP after channel change detected, format change, aud 1, vid 0
[2015-01-18 13:49:20,716] [ b6b2068] [2808] - demux:Wait for media format change:1
[2015-01-18 13:49:20,716] [ b6b2068] [2808] - audPinetAddPMT()
[2015-01-18 13:49:20,716] [ b6b2068] [2808] - vidPinetAddPMT()
[2015-01-18 13:49:20,744] [ b6b2068] [ ad8] - CTsReaderFilter:top(), state 1
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - CTsReaderFilter:top() done, state 0
[2015-01-18 13:49:20,747] [ b6b2068] [258c] - CTsReaderFilter:: DurationThread -> idle
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - vidPin: UpdateFromSeek, m_rtStart 338.575000, m_dRateSeeking 1.000000
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart() End - Stopped
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - audPin: UpdateFromSeek, m_rtStart 338.575000, m_dRateSeeking 1.000000
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart() End - Stopped
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - audPin: UpdateFromSeek, m_rtStart 338.575000, m_dRateSeeking 1.000000
[2015-01-18 13:49:20,747] [ b6b2068] [ ad8] - CTsReaderFilter::--SeekPreStart() End - Stopped
[2015-01-18 13:51:06,536] [ b6b2068] [ ad8] - CTsReaderFilter:ause() - IsTimeShifting = 1 - state = 0
[2015-01-18 13:51:06,536] [ b6b2068] [ ad8] - MPAR/Reclock not found
[2015-01-18 13:51:06,536] [ b6b2068] [ ad8] - CTsReaderFilter:ause() - END - state = 1
[2015-01-18 13:51:06,536] [ b6b2068] [6bf4] - vidPin:OnThreadStartPlay(338.575000), rate:1.00, threadID:0x6bf4, GET_TIME_NOW:0x40076199
[2015-01-18 13:51:06,536] [ b6b2068] [258c] - CTsReaderFilter:: DurationThread -> busy
[2015-01-18 13:51:06,536] [ b6b2068] [6bf4] - vidPineliverNewSegment(start 338.575000, stop 461168601842.738770), rate:1.00
[2015-01-18 13:51:06,536] [ b6b2068] [ ad8] - filt:IID_IMediaSeeking()
[2015-01-18 13:51:06,536] [ b6b2068] [258c] - CTsReaderFilter:: ForcePosnUpdate: 338.575 s
[2015-01-18 13:51:06,536] [ b6b2068] [ ad8] - CTsReaderFilter::OnGraphRebuild 1
[2015-01-18 13:51:06,536] [ b6b2068] [ ad8] - demux:Wait for media format change:0

For some reason, the cam send dcw that is not correct and right after the correct one comes but TsReader is waiting something lol
Tony any clue ?

 

[Owlsroost]

As always, can you attach all the logs please.....

 

[Sebastiii]

I think it try to render something that it can do but maybe i'm totally wrong lol

[2015-01-18 13:49:20,606] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: pos: 3385750000 start:0 end:3385750000
[2015-01-18 13:49:20,606] [Log ] [MPMain ] [INFO ] - TsReaderPlayer seek done:0
[2015-01-18 13:49:20,606] [Log ] [MPMain ] [DEBUG] - SubtitleRenderer: OnSeek - clear subtitles
[2015-01-18 13:49:20,607] [Log ] [MPMain ] [DEBUG] - New StartPos is 338.575
[2015-01-18 13:49:20,607] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: current pos:338.575 dur:338.575
[2015-01-18 13:49:20,607] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:Continue graph
[2015-01-18 13:49:20,747] [Log ] [MPMain ] [INFO ] - Rerendering audio pin of tsreader filter.
[2015-01-18 13:49:20,748] [Log ] [MPMain ] [INFO ] - Disconnecting all pins from filter LAV Audio Decoder
[2015-01-18 13:49:20,748] [Log ] [MPMain ] [INFO ] - Disconnecting pin Output
[2015-01-18 13:49:20,748] [Log ] [MPMain ] [INFO ] - Disconnecting all pins from filter Default DirectSound Device
[2015-01-18 13:49:20,750] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: UpdateFilters Remove filter - LAV Audio Decoder
[2015-01-18 13:49:20,755] [Log ] [MPMain ] [INFO ] - Added filter: LAV Audio Decoder to graph
[2015-01-18 13:49:20,755] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Audio
[2015-01-18 13:49:20,756] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: volume handler value 65535
[2015-01-18 13:49:20,756] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Audio
[2015-01-18 13:49:20,803] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Timed-out waiting for grabbed frame!
[2015-01-18 13:49:21,511] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Timed-out waiting for grabbed frame!
[2015-01-18 13:49:22,216] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Timed-out waiting for grabbed frame!
[2015-01-18 13:49:22,924] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Timed-out waiting for grabbed frame!
[2015-01-18 13:49:22,924] [Log ] [Auto3D analyze thread] [DEBUG] - Results(4) - Normal=5 - SBS3D=0 - TB3D=0
[2015-01-18 13:49:23,633] [Log ] [Auto3D analyze thread] [DEBUG] - FrameGrabber: Timed-out waiting for grabbed frame!
[2015-01-18 13:49:23,633] [Log ] [Auto3D analyze thread] [DEBUG] - Results(5) - Normal=6 - SBS3D=0 - TB3D=0
[2015-01-18 13:49:23,633] [Log ] [Auto3D analyze thread] [INFO ] - Auto3D: Format is 2D. No switch necessary
[2015-01-18 13:51:06,186] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: RenderUnconnectedOutputPins Pin Audio - failed
[2015-01-18 13:51:06,186] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Subtitle
[2015-01-18 13:51:06,186] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Subtitle
[2015-01-18 13:51:06,243] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: RenderUnconnectedOutputPins Pin Subtitle - failed
[2015-01-18 13:51:06,243] [Log ] [MPMain ] [DEBUG] - Filter: LAV Audio Decoder - try to connect: Output
[2015-01-18 13:51:06,243] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: build the graph for PIN : Output
[2015-01-18 13:51:06,527] [Log ] [MPMain ] [DEBUG] - DirectShowUtil: RenderUnconnectedOutputPins Pin Output - failed
[2015-01-18 13:51:06,528] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Audio Decoder
[2015-01-18 13:51:06,530] [Log ] [MPMain ] [DEBUG] - - remove done
[2015-01-18 13:51:06,530] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Enhanced Video Renderer
[2015-01-18 13:51:06,531] [Log ] [MPMain ] [DEBUG] - Check graph connections for: Default DirectSound Device
[2015-01-18 13:51:06,531] [Log ] [MPMain ] [DEBUG] - Check graph connections for: LAV Video Decoder
[2015-01-18 13:51:06,531] [Log ] [MPMain ] [DEBUG] - Check graph connections for: TsReader
[2015-01-18 13:51:06,536] [Log ] [MPMain ] [INFO ] - Reconfigure graph done
[2015-01-18 13:51:06,561] [Log ] [MPMain ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2015-01-18 13:51:06,562] [Log ] [MPMain ] [INFO ] - Main: Deactivation request received
[2015-01-18 13:51:06,562] [Log ] [MPMain ] [DEBUG] - D3D: OnLostFocus()
[2015-01-18 13:51:06,565] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 50

 

[Sebastiii]

@Owlsroost i have sent you a pm

 

[RicoHTPC]

@Owlsroost: Unfortunately here I am again.
All the work you've done gave a perfect result on mp1.9 but after I updated to 1.10 it got a bit worse again.
Some channels showed frequent stops and on some channels I had desync issues.
Attached is a log of such a desync scene.
After a short period of testing the standard tsreader from 1.10 I switched back to the one you've posted here.
So this log was also taken with this tsreader in use.
Could you have a look please?

 

[Owlsroost]

@Owlsroost: Unfortunately here I am again.
All the work you've done gave a perfect result on mp1.9 but after I updated to 1.10 it got a bit worse again.
Some channels showed frequent stops and on some channels I had desync issues.
Attached is a log of such a desync scene.
After a short period of testing the standard tsreader from 1.10 I switched back to the one you've posted here.
So this log was also taken with this tsreader in use.
Could you have a look please?

I can't think of a reason why there should be a difference in behaviour between 1.9 and 1.10 (there were no changes related to TV), and there isn't anything obvious in the logs.

If you want to try it, there is a later version of TsReader here - https://forum.team-mediaportal.com/threads/dropouts-when-watching-hd-tv.127679/page-15#post-1116173 (note it needs VC2013 x86 re-distributable installed)

 

[RicoHTPC]

I tried this version but that gave me crashes immediately after starting an sd channel. Logs are attached.
Appart from the logic that there should nothing different between 1.9 and 1.10 could you see anything in the logs?

 

[Owlsroost]

I tried this version but that gave me crashes immediately after starting an sd channel. Logs are attached

Have you installed the x86 version of the VC2013 redistributable ? (I've not had any other reports of crashes with that version of TsReader)

Apart from the logic that there should nothing different between 1.9 and 1.10 could you see anything in the logs?

No - the logs looked normal.

 

[RicoHTPC]

To be honest I installed the x64 as well as I was thinking that 64 bit systems always need both versions...
Anyway I switched back to the setting before and tried another codec which for now seems to work fine.
Maybe I just took the wrong codec back when I updated to 1.10 (via clean install).
If that had been the cause, I need to apologize for false-alarming...

 

[corvy]

Hello all, I have been playing with this the last few days. First part I used some efforts to tune was the Mascom CAM. I have adjusted quite some settings in the expert menu by trial and error to get the most optimal decrypt performance and the settings are now as follows:

CA systems: SINGLE
CA registration: STATIC
Force reading original PMT: OFF
CA-PMT delete time: 1s
CI-Watchdog: 1500 ms

Not sure about these settings but at least now the decrypt is quite snappy and stable. Note, Mascom Conax v. 1.25.

Then I have been playing more with the TV client. I have gone to using UNC paths for the timeshift and recordings and this gives a lot better performance. Still I have seen the black screen from time to time. Last change I did was to move MPEG2 and h.264 codec from LAV Video to Cyberlink PDVD 13. This last change has given quite good effect. With the codec change I am no longer able to provoke the black screen issue. Have now tuned some 200 channel changes without issues. Before I have only been able to do between 5-10 tunes before I get issues.

Right now I am using the stock TSreader from 1.10, have also tried the latest development version in this thread without any issues. Current setup is stock, working very stable. I will give the new tsreader another run after a few days just to compare.

Thanks.