home
products
contribute
download
documentation
forum
Home
Forums
New posts
Search forums
What's new
New posts
All posts
Latest activity
Members
Registered members
Current visitors
Donate
Log in
Register
What's new
Search
Search
Search titles only
By:
New posts
Search forums
Search titles only
By:
Menu
Log in
Register
Navigation
Install the app
Install
More options
Contact us
Close Menu
Forums
MediaPortal 1
Support
General Support
How to cut down on 5 secs channel change?
Contact us
RSS
JavaScript is disabled. For a better experience, please enable JavaScript in your browser before proceeding.
You are using an out of date browser. It may not display this or other websites correctly.
You should upgrade or use an
alternative browser
.
Reply to thread
Message
<blockquote data-quote="thomas.p" data-source="post: 375889" data-attributes="member: 88481"><p><strong>TV-Server Version</strong>: 1.0.0.21149</p><p><strong>MediaPortal Version</strong>: 1.0.0.21149</p><p><strong>MediaPortal Skin</strong>: Blue3</p><p><strong>Windows Version</strong>: Win XP MCE SP3</p><p><strong>CPU Type</strong>: Intel P4 Nw 2.2 GHz</p><p><strong>HDD</strong>: 1: Seagate 200 GB, 2: WD 250 GB, 3: WD 250 GB</p><p><strong>Memory</strong>: 512 MB + 2x 256 MB PC-2700</p><p><strong>Motherboard</strong>: Gigabyte 8PE667U</p><p><strong>Video Card</strong>: ATI Radeon 9800 Pro w. 128 MB RAM</p><p><strong>Video Card Driver</strong>: Catalyst v8.12</p><p><strong>Sound Card</strong>: Creative Soundblaster Audigy 2 ZS</p><p><strong>Sound Card AC3</strong>: </p><p><strong>Sound Card Driver</strong>: 6.0.1.1361</p><p><strong>1. TV Card</strong>: Hauppauge PVR 500 MCE</p><p><strong>1. TV Card Type</strong>: Dual analogue tuners</p><p><strong>1. TV Card Driver</strong>: 2.0.48.25037</p><p><strong>2. TV Card</strong>: </p><p><strong>2. TV Card Type</strong>: </p><p><strong>2. TV Card Driver</strong>: </p><p><strong>3. TV Card</strong>: </p><p><strong>3. TV Card Type</strong>: </p><p><strong>3. TV Card Driver</strong>: </p><p><strong>4. TV Card</strong>: </p><p><strong>4. TV Card Type</strong>: </p><p><strong>4. TV Card Driver</strong>: </p><p><strong>MPEG2 Video Codec</strong>: MPV Decoder Filter</p><p><strong>MPEG2 Audio Codec</strong>: MPA Decoder Filter</p><p><strong>h.264 Video Codec</strong>: ffdshow Video Decoder</p><p><strong>Satelite/CableTV Provider</strong>: </p><p><strong>HTPC Case</strong>: Old Piece of Trash™</p><p><strong>Cooling</strong>: Zalman CNPS7000A-AlCu & VF1000 LED & NB47J</p><p><strong>Power Supply</strong>: Nexus NX-3000 (300W)</p><p><strong>Remote</strong>: </p><p><strong>TV</strong>: </p><p><strong>TV - HTPC Connection</strong>: </p><p></p><p></p><p>I am new to MediaPortal, so I need some help troubleshooting an issue that has annoyed me since I first tried out my new TV-watching-experience. I have come across several threads describing much the same problem, but most have been reduced to guesses without any reports of a real solution - I hope this will be different.</p><p></p><p>My channel change times are very long - usually in excess of 5 seconds. I have tried to break down the problem by looking at log files, but I have no idea what times are to be expected and what all the stuff that is going on in the log file is all about let alone how to resolve the problems that I have identified. It would be great if somebody could point to exactly which parts of the logs show excessive times and what I can do to counter the particular problems that they may represent.</p><p></p><p>To start things off I have grabbed an example from a log generated during one of my recent sessions - a generic channel switch. This can be found in attatched old_logs.zip. Another set of logs made with the MediaPortal Debug mode is also attatched for further elaboration.</p><p></p><p>mediaportal.log says button press was registered at 02:39:30.791438</p><p></p><p>vmr9.log says presentation started again at 02:39:36.025</p><p></p><p>This is a total of ~5.234 secs, which corresponds pretty well with the times i generally experience on changing channel. So I had an in-depth look at it:</p><p></p><p>[CODE]** MediaPortal.log snippet begin **</p><p>2009-02-10 02:39:30.791438 [Info.][MPMain]: TVHome:OnPreviousChannel()</p><p>[COLOR="Red"]2009-02-10 02:39:30.978938 [Info.][MPMain]: Navigator:ZapPrevious TV 2 Zulu->TV 2[/COLOR]</p><p>2009-02-10 02:39:33.572688 [Info.][MPMain]: Channel change:TV 2</p><p>2009-02-10 02:39:33.572688 [Info.][MPMain]: TVHome.ViewChannelAndCheck(): View channel=TV 2</p><p>[COLOR="Red"]2009-02-10 02:39:33.838313 [Info.][MPMain]: TSReaderPlayer:Pause graph[/COLOR]</p><p>2009-02-10 02:39:35.385188 [Info.][MPMain]: tvhome:SeektoEnd(True, rtsp=False</p><p>2009-02-10 02:39:35.400813 [Info.][MPMain]: TsReaderPlayer:seekabs:648070000 start:2980000 end:648070000</p><p>2009-02-10 02:39:35.478938 [Info.][MPMain]: TsReaderPlayer seek done:0</p><p>2009-02-10 02:39:35.478938 [Info.][MPMain]: TSReaderPlayer: current pos:64,509 dur:64,509</p><p>2009-02-10 02:39:35.478938 [Info.][MPMain]: TSReaderPlayer:Continue graph</p><p>2009-02-10 02:39:35.478938 [Info.][MPMain]: succeeded:Succeeded TvControl.VirtualCard</p><p>** MediaPortal.log snippet end **[/CODE]</p><p></p><p>Two things especially bother me about this: The events at 02:39:30.978938 and 02:39:33.838313. Each last 2.59375 secs and 1.546875 - without them the total change time would be ~1.093 secs which, to me, would be better than the expected total of 1-2 secs. So I am really interested in what is hiding behind those events and times.</p><p></p><p>For greater detail I took a look at the tv.log file - it held no record for the initial "Navigator:ZapPrevious" time consumption, so I am guessing that is client-side and registered in a log I could not find. However it did give a better idea of what was going on with the "TSReaderPlayer<img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite7" alt=":p" title="Stick Out Tongue :p" loading="lazy" data-shortname=":p" />ause graph".</p><p></p><p>(I refrained from pasting my tv.log snippet here - would take up too much room <img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite2" alt=";)" title="Wink ;)" loading="lazy" data-shortname=";)" /></p><p></p><p>The tv.log represents a time frame of 1.671875 - only some 30% of the total channel change time. However there are also a few times that seem out of the ordinary. In particular the following three lines seemed to use up a lot of time. However I have no clue whether this is normal and what can be done to improve those times.</p><p></p><p>02:39:33.994563 0.171875 card: user: mediaportal:1:0 tune tv<img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite5" alt=":confused:" title="Confused :confused:" loading="lazy" data-shortname=":confused:" />portsNyhederne Freq:0 Channel:89 Country<img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite8" alt=":D" title="Big Grin :D" loading="lazy" data-shortname=":D" />enmark Tuner:Cable Video:Tuner</p><p>02:39:34.713313 0.40625 card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo</p><p>02:39:35.150813 0.21875 card: WaitForTimeShiftFile - video and audio are seen after 0,421875 seconds</p><p></p><p>The three together make up 0.796875 secs which is just short of half of the total time represented in the tv.log</p><p></p><p>[CODE]** vmr9.log snippet begin **</p><p>10-02-2009 02:39:33.869 [a74]vmr9:StopPresenting()</p><p>10-02-2009 02:39:36.025 [69c]vmr9:StartPresenting()</p><p>** vmr9.log snippet end **[/CODE]</p><p></p><p>I am guessing that the vmr9.log shows that the MediaPortal client shuts down the display of the video stream while the server is changing the channel. When </p><p></p><p>compared to the other log files it seems the presentation is stopped while the MediaPlayer.log reports the "TSReaderPlayer<img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite7" alt=":p" title="Stick Out Tongue :p" loading="lazy" data-shortname=":p" />ause graph" and the tv.log reports </p><p></p><p>that it is happening just before the "Controller: StartTimeShifting TV 2 15" is happening, which does not seem to coincide with any of the big time consumers in </p><p></p><p>the tv.log. It is difficult for me to assess whether this means something without a bit more insight into how this works <img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite1" alt=":)" title="Smile :)" loading="lazy" data-shortname=":)" /></p><p></p><p>tv.log end 02:39:35.400813</p><p>MediaPortal.log end 02:39:35.478938 +0.078125</p><p>vmr9.log end 02:39:36.025 +0.546062</p><p></p><p>I am wondering what is going on during the time that passes while the tv.log and MediaPortal.log is done reporting and I am waiting to see the picture on my </p><p></p><p>screen as I am guessing the vmr9.log reports more than half a second later</p><p></p><p>Codecs does not seem to be a problem - I have changed between several different sets and channel change times doesn't really get lower.</p><p></p><p>I have also experimented with placement and setup of timeshift files, which also seemed to have no direct effect on the problem.</p><p></p><p>Can anybody explain to me what is going on?</p></blockquote><p></p>
[QUOTE="thomas.p, post: 375889, member: 88481"] [b]TV-Server Version[/b]: 1.0.0.21149 [b]MediaPortal Version[/b]: 1.0.0.21149 [b]MediaPortal Skin[/b]: Blue3 [b]Windows Version[/b]: Win XP MCE SP3 [b]CPU Type[/b]: Intel P4 Nw 2.2 GHz [b]HDD[/b]: 1: Seagate 200 GB, 2: WD 250 GB, 3: WD 250 GB [b]Memory[/b]: 512 MB + 2x 256 MB PC-2700 [b]Motherboard[/b]: Gigabyte 8PE667U [b]Video Card[/b]: ATI Radeon 9800 Pro w. 128 MB RAM [b]Video Card Driver[/b]: Catalyst v8.12 [b]Sound Card[/b]: Creative Soundblaster Audigy 2 ZS [b]Sound Card AC3[/b]: [b]Sound Card Driver[/b]: 6.0.1.1361 [b]1. TV Card[/b]: Hauppauge PVR 500 MCE [b]1. TV Card Type[/b]: Dual analogue tuners [b]1. TV Card Driver[/b]: 2.0.48.25037 [b]2. TV Card[/b]: [b]2. TV Card Type[/b]: [b]2. TV Card Driver[/b]: [b]3. TV Card[/b]: [b]3. TV Card Type[/b]: [b]3. TV Card Driver[/b]: [b]4. TV Card[/b]: [b]4. TV Card Type[/b]: [b]4. TV Card Driver[/b]: [b]MPEG2 Video Codec[/b]: MPV Decoder Filter [b]MPEG2 Audio Codec[/b]: MPA Decoder Filter [b]h.264 Video Codec[/b]: ffdshow Video Decoder [b]Satelite/CableTV Provider[/b]: [b]HTPC Case[/b]: Old Piece of Trash™ [b]Cooling[/b]: Zalman CNPS7000A-AlCu & VF1000 LED & NB47J [b]Power Supply[/b]: Nexus NX-3000 (300W) [b]Remote[/b]: [b]TV[/b]: [b]TV - HTPC Connection[/b]: I am new to MediaPortal, so I need some help troubleshooting an issue that has annoyed me since I first tried out my new TV-watching-experience. I have come across several threads describing much the same problem, but most have been reduced to guesses without any reports of a real solution - I hope this will be different. My channel change times are very long - usually in excess of 5 seconds. I have tried to break down the problem by looking at log files, but I have no idea what times are to be expected and what all the stuff that is going on in the log file is all about let alone how to resolve the problems that I have identified. It would be great if somebody could point to exactly which parts of the logs show excessive times and what I can do to counter the particular problems that they may represent. To start things off I have grabbed an example from a log generated during one of my recent sessions - a generic channel switch. This can be found in attatched old_logs.zip. Another set of logs made with the MediaPortal Debug mode is also attatched for further elaboration. mediaportal.log says button press was registered at 02:39:30.791438 vmr9.log says presentation started again at 02:39:36.025 This is a total of ~5.234 secs, which corresponds pretty well with the times i generally experience on changing channel. So I had an in-depth look at it: [CODE]** MediaPortal.log snippet begin ** 2009-02-10 02:39:30.791438 [Info.][MPMain]: TVHome:OnPreviousChannel() [COLOR="Red"]2009-02-10 02:39:30.978938 [Info.][MPMain]: Navigator:ZapPrevious TV 2 Zulu->TV 2[/COLOR] 2009-02-10 02:39:33.572688 [Info.][MPMain]: Channel change:TV 2 2009-02-10 02:39:33.572688 [Info.][MPMain]: TVHome.ViewChannelAndCheck(): View channel=TV 2 [COLOR="Red"]2009-02-10 02:39:33.838313 [Info.][MPMain]: TSReaderPlayer:Pause graph[/COLOR] 2009-02-10 02:39:35.385188 [Info.][MPMain]: tvhome:SeektoEnd(True, rtsp=False 2009-02-10 02:39:35.400813 [Info.][MPMain]: TsReaderPlayer:seekabs:648070000 start:2980000 end:648070000 2009-02-10 02:39:35.478938 [Info.][MPMain]: TsReaderPlayer seek done:0 2009-02-10 02:39:35.478938 [Info.][MPMain]: TSReaderPlayer: current pos:64,509 dur:64,509 2009-02-10 02:39:35.478938 [Info.][MPMain]: TSReaderPlayer:Continue graph 2009-02-10 02:39:35.478938 [Info.][MPMain]: succeeded:Succeeded TvControl.VirtualCard ** MediaPortal.log snippet end **[/CODE] Two things especially bother me about this: The events at 02:39:30.978938 and 02:39:33.838313. Each last 2.59375 secs and 1.546875 - without them the total change time would be ~1.093 secs which, to me, would be better than the expected total of 1-2 secs. So I am really interested in what is hiding behind those events and times. For greater detail I took a look at the tv.log file - it held no record for the initial "Navigator:ZapPrevious" time consumption, so I am guessing that is client-side and registered in a log I could not find. However it did give a better idea of what was going on with the "TSReaderPlayer:Pause graph". (I refrained from pasting my tv.log snippet here - would take up too much room ;) The tv.log represents a time frame of 1.671875 - only some 30% of the total channel change time. However there are also a few times that seem out of the ordinary. In particular the following three lines seemed to use up a lot of time. However I have no clue whether this is normal and what can be done to improve those times. 02:39:33.994563 0.171875 card: user: mediaportal:1:0 tune tv:SportsNyhederne Freq:0 Channel:89 Country:Denmark Tuner:Cable Video:Tuner 02:39:34.713313 0.40625 card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo 02:39:35.150813 0.21875 card: WaitForTimeShiftFile - video and audio are seen after 0,421875 seconds The three together make up 0.796875 secs which is just short of half of the total time represented in the tv.log [CODE]** vmr9.log snippet begin ** 10-02-2009 02:39:33.869 [a74]vmr9:StopPresenting() 10-02-2009 02:39:36.025 [69c]vmr9:StartPresenting() ** vmr9.log snippet end **[/CODE] I am guessing that the vmr9.log shows that the MediaPortal client shuts down the display of the video stream while the server is changing the channel. When compared to the other log files it seems the presentation is stopped while the MediaPlayer.log reports the "TSReaderPlayer:Pause graph" and the tv.log reports that it is happening just before the "Controller: StartTimeShifting TV 2 15" is happening, which does not seem to coincide with any of the big time consumers in the tv.log. It is difficult for me to assess whether this means something without a bit more insight into how this works :) tv.log end 02:39:35.400813 MediaPortal.log end 02:39:35.478938 +0.078125 vmr9.log end 02:39:36.025 +0.546062 I am wondering what is going on during the time that passes while the tv.log and MediaPortal.log is done reporting and I am waiting to see the picture on my screen as I am guessing the vmr9.log reports more than half a second later Codecs does not seem to be a problem - I have changed between several different sets and channel change times doesn't really get lower. I have also experimented with placement and setup of timeshift files, which also seemed to have no direct effect on the problem. Can anybody explain to me what is going on? [/QUOTE]
Insert quotes…
Verification
Post reply
Forums
MediaPortal 1
Support
General Support
How to cut down on 5 secs channel change?
Contact us
RSS
Top
Bottom
