Problem with VideoEditor? (1 Viewer)

[rekenaar]

TV-Server Version: RC2
MediaPortal Version: RC2
MediaPortal Skin: Indigo and Bluetwo
Windows Version: XP SP2 and XP SP3
CPU Type: Intel Core2Due and AMD
HDD: 300gb SATA
Memory: 2048mb
Motherboard: Intel
Video Card: onboard
Sound Card: onboard intel HD
Sound Card AC3: 6 channel rca to amp
1. TV Card: Hauppauge Wintv PVR-150
MPEG2 Video Codec: tried Cyberlink PDVD7 and MPV
MPEG2 Audio Codec: tried Cyberlink PDVD7 and MPA
HTPC Case: Zalman 160
Remote: MCE
TV: 37" LCD
TV - HTPC Connection: vga

I think I found a problem in the VideoEditor of recent SVN versions. I previously reported this in the VideoEditor thread without any response. I now tested it with RC2 and since the problem still happens, I decided to report it here in the RC2 forum. Hope that is ok.

Everytime I am pressing the right button to skip forward, the video pauses. This worked fine a few weeks ago.
mediaportal-svn-06-22-2008--17-10-Rev19453.rar - Worked fine.
mediaportal-svn-06-27-2008--19-25-Rev19510.rar - Does not work

Steps I used to reproduce and to generate the logs:
- Open file in Video Editor
- At exactly 12:38 I played the file
- Wait 5 seconds
- Press right arrow - video pauses
- Wait 10 seconds
- Exit MP and get logs

The logs was gathered last weekend on RC1 with SVN 7-11-2008 19603, but since the issue is exactly the same on RC2 I am just pasting the same log segment again.
TsReader.log

...
12-07-2008 12:38:01.609 [990]CTsReaderFilter::Run(30800.20)
12-07-2008 12:38:01.609 [990]CTsReaderFilter::Run(30800.20) -->done
12-07-2008 12:38:07.140 [990]CTsReaderFilter:ause()
12-07-2008 12:38:07.140 [990]aud seek to 39.015999/3901.658936
12-07-2008 12:38:07.140 [990]demux:set hold audio:1
12-07-2008 12:38:07.140 [990]CTsReaderFilter::--SeekStart()--
12-07-2008 12:38:07.140 [648]aud:isseeking
12-07-2008 12:38:07.171 [990]CTsReaderFilter:eek--
12-07-2008 12:38:07.171 [990]CTsReaderFilter:: Seek-> 39.016000/3901.659000
12-07-2008 12:38:07.171 [990]seek to 39.016000 filepos:1702548 pid:1e0
12-07-2008 12:38:07.171 [990] got 38.923778 at filepos 1702ca0 diff 0.092222
12-07-2008 12:38:07.171 [990] got 1970.363600 at filepos 489fb912 diff 1931.347600
12-07-2008 12:38:07.171 [990] got 1004.719422 at filepos 2507fa31 diff 965.703422
12-07-2008 12:38:07.171 [990] got 521.851578 at filepos 133c1ac0 diff 482.835578
12-07-2008 12:38:07.171 [990] got 280.401089 at filepos a5623b0 diff 241.385089
12-07-2008 12:38:07.171 [990] got 159.734222 at filepos 5e32828 diff 120.718222
12-07-2008 12:38:07.171 [990] got 99.289556 at filepos 3a9aa64 diff 60.273556
12-07-2008 12:38:07.171 [990] got 69.161889 at filepos 28cf2da diff 30.145889
12-07-2008 12:38:07.171 [990] got 54.046778 at filepos 1fe9715 diff 15.030778
12-07-2008 12:38:07.171 [990] got 46.528667 at filepos 1b76932 diff 7.512667
12-07-2008 12:38:07.171 [990] got 42.759356 at filepos 193cae9 diff 3.743356
12-07-2008 12:38:07.171 [990] got 40.900733 at filepos 181fbc4 diff 1.884733
12-07-2008 12:38:07.171 [990] got 39.886222 at filepos 1791432 diff 0.870222
12-07-2008 12:38:07.171 [990] got 39.400267 at filepos 174a069 diff 0.384267
12-07-2008 12:38:07.171 [990] got 39.162022 at filepos 1726684 diff 0.146022
12-07-2008 12:38:07.171 [990] got 39.043689 at filepos 1714992 diff 0.027689
12-07-2008 12:38:07.171 [990] got 38.975844 at filepos 170bb19 diff 0.040156
12-07-2008 12:38:07.171 [990] got 39.008978 at filepos 1710255 diff 0.007022
12-07-2008 12:38:07.171 [990] stop seek: 39.008978 at 1710255 - target: 39.016000, diff: 0.007022
12-07-2008 12:38:07.171 [990]CTsReaderFilter::--SeekDone()
12-07-2008 12:38:07.171 [648]demux:flush audio
12-07-2008 12:38:07.171 [648]aud:OnThreadStartPlay(39.015999) 1.00
12-07-2008 12:38:07.171 [648]aud:isseeking
12-07-2008 12:38:07.171 [990]demux:set hold audio:0
12-07-2008 12:38:07.171 [990]aud seek done---
12-07-2008 12:38:07.171 [990]vid seek to 39.015999
12-07-2008 12:38:07.171 [990]demux:set hold video:1
12-07-2008 12:38:07.171 [df8]vid:isseeking:0 1
12-07-2008 12:38:07.187 [648]Video Continuity error... b ( prev 6 )
12-07-2008 12:38:07.187 [648]DeMultiplexer::FillVideoVideo pts jump found, flushing video : 32.119999 389ce8, 389ce8
12-07-2008 12:38:07.187 [648]aud:set discontinuity
12-07-2008 12:38:07.187 [648]aud:compensation:0.086
12-07-2008 12:38:07.203 [990]demux:set hold video:0
12-07-2008 12:38:07.203 [990]vid seek done---
12-07-2008 12:38:07.203 [df8]demux:flush video
12-07-2008 12:38:07.203 [df8]vid:OnThreadStartPlay(39.015999) 1.00
12-07-2008 12:38:07.203 [df8]vid:set discontinuity
12-07-2008 12:38:07.203 [990]aud:skip seek
12-07-2008 12:38:07.203 [990]CTsReaderFilter::Run(30805.79)
12-07-2008 12:38:07.203 [990]CTsReaderFilter::Run(30805.79) -->done
12-07-2008 12:38:07.218 [990]CTsReaderFilter:ause()
12-07-2008 12:38:21.000 [990]CTsReaderFilter:top()
...

MediaPortal.log

...
2008-07-12 12:38:02.156250 [Info.][3]: planescene: PresentSurface() frame:0 enabled:True allowed:False 720x576
2008-07-12 12:38:02.156250 [Debug][MPMain]: VMR9: Repainting -> Playing, Frames: 1
2008-07-12 12:38:02.203125 [Debug][3]: PlaneScene: crop T, B : 0, 0
2008-07-12 12:38:02.203125 [Debug][3]: PlaneScene: crop L, R : 0, 0
2008-07-12 12:38:02.203125 [Info.][3]: PlaneScene: video WxH : 720x576
2008-07-12 12:38:02.203125 [Debug][3]: PlaneScene: video AR : 5:4
2008-07-12 12:38:02.203125 [Info.][3]: PlaneScene: screen WxH : 350x260
2008-07-12 12:38:02.218750 [Debug][3]: PlaneScene: AR type : Zoom
2008-07-12 12:38:02.218750 [Debug][3]: PlaneScene: PixelRatio : 1
2008-07-12 12:38:02.218750 [Debug][3]: PlaneScene: src : (0,20)-(720,554)
2008-07-12 12:38:02.218750 [Debug][3]: PlaneScene: dst : (300,107)-(650,367)
2008-07-12 12:38:07.140625 [Debug][MPMain]: g_Player.SeekAbsolute() - Preparing to seek to 0:0:39
2008-07-12 12:38:07.140625 [Info.][MPMain]: TsReaderPlayer:seekabs:390165900 start:0 end:39016590000
2008-07-12 12:38:07.203125 [Info.][MPMain]: TsReaderPlayer seek done:0
2008-07-12 12:38:07.218750 [Info.][MPMain]: TSReaderPlayer: current pos:39.28159 dur:3901.659
2008-07-12 12:38:07.218750 [Debug][MPMain]: VMR9: SetRepaint()
2008-07-12 12:38:20.984375 [Info.][MPMain]: g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False
2008-07-12 12:38:20.984375 [Info.][MPMain]: g_Player.OnStopped()
...

 

[infinite.loop]

Please

1. start -> Team MediaPortal -> MediaPortal
2. select "MediaPortal Debug"
3. reproduce the issue
4. close mediaportal
5. attach the created *.zip to the first post (as requred in the guidelines)

 

[rekenaar]

Thanks for moving.
Since the specific time part of the logs was not sufficient, full RC2 logs attached.

 

[infinite.loop]

thanks for the full logs, i will sent gemx a pm about this since he did changes to tsreader recently.

 

[rekenaar]

Same problem with the 1st SVN of RC2 installed.

 

[gemx]

Hmm, will have a look at it.

 

[rekenaar]

Updated report:
Same problem with RC2 + mediaportal-svn-08-01-2008--19-31-Rev19843.rar.

New logs attached.

Can someone else please confirm if this is working correctly. I would like to know if it is something on my setup (although it is the same on both my PC's).

 

[geov]

Hi Rekenaar, I can confirm this bug for you....I get the same pause problem.....

 

[rekenaar]

Good to know I am not alone

 

[rekenaar]

Same problem on new SVN 2008-08-09.

Thank you.