[mythtv] Refresh rate during playback

Wed Mar 29 11:26:45 UTC 2006

Run the same test with timestamp on as well as playback. generates lots of 
log but will tell you what teh timestamps (a&v) are doing relative to each 
other. perhaps also audio.

mark

> Isaac Richards wrote:
>> On Tuesday 28 March 2006 20:04, Kevin Kuphal wrote:
>>
>>> Can anyone familiar with the videout_xv.cpp code and NuppleVideo player
>>> explain what this means:
>>>
>>> 2006-03-28 17:51:38.347 Refresh rate: 16579, frame interval: 33366
>>>
>>
>> Frame times in microseconds.  Your screen refresh rate is 60.317 fps (1 /
>> 0.016579), and the video to be played is at 29.97 fps ( 1 / 0.033366).
>> Shouldn't be causing any problems.
>>
> Thanks, I'm just grasping at straws trying to figure out why this
> playback is so bad with XvMC on one system and not another.
> mythfrontend -v playback
>
> 2006-03-28 17:51:31.410 TV: Changing from WatchingPreRecorded to None
> 2006-03-28 17:51:35.249 TV: Attempting to change from None to
> WatchingPreRecorded
> 2006-03-28 17:51:35.252
> RingBuf(/usr/local/media/tv/2902_20060323235300.mpg):
> OpenFile(/usr/local/media/tv/2902_20060323235300.mpg, 12)
> 0: start_time: 5909.832 duration: 40.384
> 1: start_time: 5909.784 duration: 40.392
> 2: start_time: 5909.785 duration: 40.389
> stream: start_time: 65664.262 duration: 449.251 bitrate=15030 kb/s
> 2006-03-28 17:51:35.566 AFD: Stream #0, has id 0x2042 codec id
> MPEG2VIDEO, type Video at 0x0xb462c50
> 2006-03-28 17:51:35.569 VideoOutputXv: XvMC version: 1.0
> 2006-03-28 17:51:35.571 XvMCSurfaceTypes::find(w 1920, h 1080, chroma 1,
> vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 86, 860 <=p,
> port, surfNum)
> 2006-03-28 17:51:35.571 Trying XvMC port 86
> 2006-03-28 17:51:35.571 Found a suitable XvMC surface 0
> 2006-03-28 17:51:35.571 VideoOutputXv: @ j=1 Looking for flag[s]:
> XvInputMask
> 2006-03-28 17:51:35.571 VideoOutputXv: Adaptor: 0 has flag[s]:
> XvInputMask XvImageMask
> 2006-03-28 17:51:35.572 XvMCSurfaceTypes::find(w 1920, h 1080, chroma 1,
> vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 86, 860 <=p,
> port, surfNum)
> 2006-03-28 17:51:35.572 Trying XvMC port 86
> 2006-03-28 17:51:35.572 Found a suitable XvMC surface 0
> 2006-03-28 17:51:35.572 VideoOutputXv: Grabbed xv port 86
> 2006-03-28 17:51:35.572 VideoOutputXv: XvMC surface found with IDCT
> support on port 86
> 2006-03-28 17:51:35.586 VideoOutputXv: Closing XVideo port 86
> 2006-03-28 17:51:35.588 detectInterlace(Detect Scan, Interlaced Scan,
> 29.97, 1088) ->Interlaced Scan
> 2006-03-28 17:51:35.588 AFD: Looking for decoder for MPEG2VIDEO_XVMC
> 2006-03-28 17:51:35.588 AFD: Opened codec 0x9bf7150, id(MPEG2VIDEO_XVMC)
> type(Video)
> 2006-03-28 17:51:35.588 AFD: Stream #1, has id 0x2041 codec id AC3, type
> Audio at 0x0xb4cd750
> 2006-03-28 17:51:35.589 AFD: Looking for decoder for AC3
> 2006-03-28 17:51:35.592 AFD: Opened codec 0x9a10500, id(AC3) type(Audio)
> 2006-03-28 17:51:35.592 AFD: Stream #2, has id 0x2040 codec id AC3, type
> Audio at 0x0x98d5b00
> 2006-03-28 17:51:35.592 AFD: Looking for decoder for AC3
> 2006-03-28 17:51:35.593 AFD: Opened codec 0x9a10830, id(AC3) type(Audio)
> 2006-03-28 17:51:35.593
> RingBuf(/usr/local/media/tv/2902_20060323235300.mpg):
> CalcReadAheadThresh(25640 KB)
>                         -> threshhold(938 KB) min read(32 KB) blk
> size(512 KB)
> 2006-03-28 17:51:35.594 Opening audio device 'spdif'.
> 2006-03-28 17:51:35.596 Dec: Trying to select track (w/lang)
> 2006-03-28 17:51:35.596 Dec: Selecting first track
> 2006-03-28 17:51:35.596 Dec: Selected track #1 in the Unknown language(0)
> 2006-03-28 17:51:35.597 Resyncing position map. posmapStarted = 0
> livetv(0) watchingRec(0)
> 2006-03-28 17:51:35.623 Position map filled from DB to: 13455
> 2006-03-28 17:51:35.624 SyncPositionMap prerecorded, from DB: 898 entries
> 2006-03-28 17:51:35.626 SyncPositionMap, new totframes: 13455, new
> length: 448, posMap size: 898
> 2006-03-28 17:51:35.626 AFD: Position map found
> 2006-03-28 17:51:35.626 AFD: Successfully opened decoder for file:
> "/usr/local/media/tv/2902_20060323235300.mpg". novideo(0)
> 2006-03-28 17:51:35.635 VideoOutputXv: ctor
> 2006-03-28 17:51:35.637 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
> 2006-03-28 17:51:35.638 Image size. dispxoff 0, dispyoff: 75, dispwoff:
> 800, disphoff: 450, dispaspect: 1.33333
> 2006-03-28 17:51:35.640 Image size. imgx 0, imgy: 0, imgw: 1920, imgh:
> 1080, aspect: 1.77778
> 2006-03-28 17:51:35.641 VideoOutputXv: Pixel dimensions: Screen 800x600,
> window 800x600
> 2006-03-28 17:51:35.642 VideoOutputXv: Estimated display dimensions:
> 271x203 mm Aspect: 1.33498
> 2006-03-28 17:51:35.643 VideoOutputXv: Estimated window dimensions:
> 271x203 mm Aspect: 1.33498
> 2006-03-28 17:51:35.643 VideoOutputXv: XvMC version: 1.0
> 2006-03-28 17:51:35.647 VideoOutputXv: @ j=1 Looking for flag[s]:
> XvInputMask
> 2006-03-28 17:51:35.647 VideoOutputXv: Adaptor: 0 has flag[s]:
> XvInputMask XvImageMask
> 2006-03-28 17:51:35.647 XvMCSurfaceTypes::find(w 1920, h 1088, chroma 1,
> vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 86, 860 <=p,
> port, surfNum)
> 2006-03-28 17:51:35.647 Trying XvMC port 86
> 2006-03-28 17:51:35.647 Found a suitable XvMC surface 0
> 2006-03-28 17:51:35.648 VideoOutputXv: Grabbed xv port 86
> 2006-03-28 17:51:35.648 VideoOutputXv: XvMC surface found with IDCT
> support on port 86
> 2006-03-28 17:51:35.817 Image size. dispxoff 0, dispyoff: 75, dispwoff:
> 800, disphoff: 450, dispaspect: 1.33333
> 2006-03-28 17:51:35.819 Image size. imgx 0, imgy: 0, imgw: 1920, imgh:
> 1080, aspect: 1.77778
> 2006-03-28 17:51:38.109 NVP: ClearAfterSeek()
> 2006-03-28 17:51:38.111 VideoOutputXv: ClearAfterSeek()
> 2006-03-28 17:51:38.112 VideoOutputXv: DiscardFrames() 1: AAAAAAAA
> 2006-03-28 17:51:38.115 VideoOutputXv: DiscardFrames() 2: AAAAAAAA
> 2006-03-28 17:51:38.116 VideoOutputXv: DiscardFrames() 3: AAAAAAAA
> 2006-03-28 17:51:38.117 TV: StartPlayer(): took 2854 ms to start player.
> 2006-03-28 17:51:38.119 VideoOutputXv: DiscardFrames() 4: AAAAAAAA --  
> done()
> 2006-03-28 17:51:38.120 VideoOutputXv: DiscardFrames() 1: AAAAAAAA
> 2006-03-28 17:51:38.121 VideoOutputXv: DiscardFrames() 2: AAAAAAAA
> 2006-03-28 17:51:38.123 TV: Changing from None to WatchingPreRecorded
> 2006-03-28 17:51:38.125 VideoOutputXv: DiscardFrames() 3: AAAAAAAA
> 2006-03-28 17:51:38.128 VideoOutputXv: DiscardFrames() 4: AAAAAAAA --  
> done()
> 2006-03-28 17:51:38.130 The realtime priority setting is not enabled.
> 2006-03-28 17:51:38.136 Using deinterlace method bobdeint
> 2006-03-28 17:51:38.277 nVidiaVideoSync: VBlank ioctl did not work,
> unimplemented in this driver?
> 2006-03-28 17:51:38.277 DRMVideoSync: Could not open device
> /dev/dri/card0, No such file or directory
> 2006-03-28 17:51:38.281 OpenGLVideoSync: GLX extensions:
> GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig
> GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control
> GLX_ARB_get_proc_address
> 2006-03-28 17:51:38.281 OpenGLVideoSync: x,y -> 401, 301
> 2006-03-28 17:51:38.311 Using OpenGLVideoSync
> 2006-03-28 17:51:38.312 Set video sync frame interval to 33366
> 2006-03-28 17:51:38.347 Using audio as timebase
> 2006-03-28 17:51:38.347 Video timing method: SGI OpenGL
> 2006-03-28 17:51:38.347 Refresh rate: 16579, frame interval: 33366
> 2006-03-28 17:51:38.473 VideoOutputXv Error: ProcessFrameXvMC: Failed to
> get OSD lock
> 2006-03-28 17:51:38.981 WriteAudio: buffer underrun
> 2006-03-28 17:51:39.130 NVP: Video is 3.06129 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:39.232 NVP: Video is 3.98181 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:39.416 NVP: Video is 4.46988 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:39.530 NVP: Video is 4.79098 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:39.651 NVP: Video is 4.06528 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:39.776 WriteAudio: buffer underrun
> 2006-03-28 17:51:39.794 NVP: Video is 4.17284 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:39.894 NVP: Video is 4.80049 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:40.003 NVP: Video is 4.98648 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:40.122 NVP: Video is 5.126 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:40.306 NVP: Video is 5.23812 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:40.410 NVP: Video is 5.41213 frames ahead of audio,
> skipping A/V wait.
> 2006-03-28 17:51:40.517 WriteAudio: buffer underrun
> etc...
>
> Everything looks pretty normal but the NVP messages result in
> unwatchable (almost slow motion) playback
>
> Kevin
> _______________________________________________
> mythtv-dev mailing list
> mythtv-dev at mythtv.org
> http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-dev
> 