[mythtv] PIP motion sync

[Brian C. Huffman]

On Sun, 2004-08-29 at 19:52 -0400, Doug Larrick wrote:
> Brian C. Huffman wrote:
> > All,
> > 
> >     I'm using CVS from 8/24 and 2 PVR250s on Fedora Core 2.  When I use
> > the picture in picture, the PIP itself seems to have a sync problem
> > where the video will stream very quickly and then stop for a second or
> > so and then repeat.  This only happens in the PIP.  If I swap between
> > main screen and PIP, then channel that was previously in the PIP now
> > displays correctly.  Any ideas?
> 
> I'll bet this is fallout from the video sync reimplementation.  If you 
> run 'mythfrontend --verbose playback' are there any messages printed out 
> just after you start PIP?

Here is what is output:
2004-08-30 19:06:54 mythfrontend version: 0.15.20040528-1 www.mythtv.org
2004-08-30 19:06:54 Enabled verbose msgs : important general playback
2004-08-30 19:06:56 Registering Internal as a media playback plugin.
2004-08-30 19:06:56 Registering MythDVD DVD Media Handler as a media
handler
2004-08-30 19:06:56 Registering MythDVD VCD Media Handler as a media
handler
Unable to initialize plugin 'mythgallery'.
Unable to initialize plugin 'mythgame'.
2004-08-30 19:06:57 Registering MythMusic Media Handler as a media
handler
2004-08-30 19:07:15 Connecting to backend server: 192.168.10.100:6543
(try 1 of
5)
2004-08-30 19:07:15 Using protocol version 13
2004-08-30 19:07:15 Using protocol version 13
2004-08-30 19:07:15 Using protocol version 13
2004-08-30 19:07:17 detectInterlace(Detect Scan, Detect Scan, 29.97,
480) ->Interlaced Scan
2004-08-30 19:07:17 Interlaced: Interlaced Scan  video_height: 480  fps:
29.97
2004-08-30 19:07:18 Estimated bitrate = 384000
2004-08-30 19:07:18 detectInterlace(Ignore Scan, Interlaced Scan, 29.97,
480) ->Interlaced Scan
2004-08-30 19:07:18 Interlaced: Interlaced Scan  video_height: 480  fps:
-1
2004-08-30 19:07:18 Partial position map found
2004-08-30 19:07:18 Opening OSS audio device '/dev/dsp'.
2004-08-30 19:07:18 Audio fragment size: 4096
2004-08-30 19:07:18 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
2004-08-30 19:07:18 Using XV port 105
2004-08-30 19:07:18 Image size. dispxoff 0, dispyoff: 0, dispwoff: 640,
disphoff: 479
2004-08-30 19:07:18 Image size. imgx 0, imgy: 0, imgw: 480, imgh: 480
2004-08-30 19:07:18 Changing from None to WatchingLiveTV
2004-08-30 19:07:18 Using deinterlace method kerneldeint
2004-08-30 19:07:18 nVidiaVideoSync: VBlank ioctl did not work,
unimplemented in this driver?
2004-08-30 19:07:18 DRMVideoSync: Could not open device /dev/dri/card0,
Permission denied
2004-08-30 19:07:18 Using audio as timebase
2004-08-30 19:07:18 Video timing method: SGI OpenGL
2004-08-30 19:07:18 Refresh rate: 16666, frame interval: 33366
2004-08-30 19:07:18 waiting for prebuffer...
2004-08-30 19:07:18 prebuffer wait timed out..
2004-08-30 19:07:18 waiting for prebuffer...
2004-08-30 19:07:18 prebuffer wait timed out..
2004-08-30 19:07:18 waiting for prebuffer...
2004-08-30 19:07:18 prebuffer wait timed out..
2004-08-30 19:07:18 waiting for prebuffer...
2004-08-30 19:07:19 detectInterlace(Ignore Scan, Interlaced Scan, 29.97,
480) ->Interlaced Scan
2004-08-30 19:07:19 Interlaced: Interlaced Scan  video_height: 480  fps:
-1
2004-08-30 19:07:19 detectInterlace(Ignore Scan, Interlaced Scan, 29.97,
480) ->Interlaced Scan
2004-08-30 19:07:19 Interlaced: Interlaced Scan  video_height: 480  fps:
-1
'video_output' mean = '34168.14', std. dev. = '3696.68', fps = '29.27'
avsync_delay: 30, avsync_avg: 30, warpfactor: 0.9, warpfactor_avg:
0.00504102
'video_output' mean = '33333.20', std. dev. = '315.62', fps = '30.00'
avsync_delay: 30, avsync_avg: 30, warpfactor: 0.9, warpfactor_avg:
0.0100043
'video_output' mean = '33327.53', std. dev. = '260.16', fps = '30.01'
avsync_delay: 30, avsync_avg: 29, warpfactor: 0.9, warpfactor_avg:
0.0149401
'video_output' mean = '33344.88', std. dev. = '345.17', fps = '29.99'
avsync_delay: 30, avsync_avg: 30, warpfactor: 0.9, warpfactor_avg:
0.0198485
2004-08-30 19:07:35 Using protocol version 13
'video_output' mean = '33347.93', std. dev. = '387.24', fps = '29.99'
avsync_delay: 29, avsync_avg: 29, warpfactor: 0.9, warpfactor_avg:
0.0247296
'video_output' mean = '33340.55', std. dev. = '648.64', fps = '29.99'
avsync_delay: 30, avsync_avg: 29, warpfactor: 0.9, warpfactor_avg:
0.0295837

[Here is where I turned on PIP]

2004-08-30 19:07:41 Waited 2 seconds for data to become available,
waiting again...
2004-08-30 19:07:41 detectInterlace(Detect Scan, Detect Scan, 29.97,
240) ->Interlaced Scan
2004-08-30 19:07:41 Interlaced: Interlaced Scan  video_height: 240  fps:
29.97
'video_output' mean = '33334.94', std. dev. = '335.70', fps = '30.00'
avsync_delay: 30, avsync_avg: 29, warpfactor: 0.9, warpfactor_avg:
0.0344109
2004-08-30 19:07:43 Estimated bitrate = 384000
2004-08-30 19:07:43 detectInterlace(Ignore Scan, Interlaced Scan, 29.97,
240) ->Interlaced Scan
2004-08-30 19:07:43 Interlaced: Interlaced Scan  video_height: 240  fps:
-1
2004-08-30 19:07:43 Partial position map found
2004-08-30 19:07:43 Image size. dispxoff 0, dispyoff: 0, dispwoff: 0,
disphoff:
0
2004-08-30 19:07:43 Image size. imgx 0, imgy: 0, imgw: 320, imgh: 240
2004-08-30 19:07:43 detectInterlace(Ignore Scan, Interlaced Scan, 29.97,
240) ->Interlaced Scan
2004-08-30 19:07:43 Interlaced: Interlaced Scan  video_height: 240  fps:
-1
2004-08-30 19:07:43 Using deinterlace method kerneldeint
2004-08-30 19:07:44 prebuffering pause
2004-08-30 19:07:44 waiting for prebuffer...
2004-08-30 19:07:44 prebuffer wait timed out..
2004-08-30 19:07:44 waiting for prebuffer...
2004-08-30 19:07:44 prebuffer wait timed out..
2004-08-30 19:07:44 waiting for prebuffer...
2004-08-30 19:07:44 prebuffer wait timed out..
2004-08-30 19:07:44 waiting for prebuffer...
2004-08-30 19:07:44 prebuffer wait timed out..
2004-08-30 19:07:44 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
'video_output' mean = '33344.44', std. dev. = '1692.63', fps = '29.99'
avsync_delay: 30, avsync_avg: 29, warpfactor: 0.9, warpfactor_avg:
0.0392113
2004-08-30 19:07:45 prebuffer wait timed out..
2004-08-30 19:07:45 waiting for prebuffer...
2004-08-30 19:07:47 prebuffering pause
2004-08-30 19:07:47 waiting for prebuffer...
2004-08-30 19:07:47 prebuffer wait timed out..
2004-08-30 19:07:47 waiting for prebuffer...
2004-08-30 19:07:47 prebuffer wait timed out..
2004-08-30 19:07:47 waiting for prebuffer...
2004-08-30 19:07:47 prebuffer wait timed out..
2004-08-30 19:07:47 waiting for prebuffer...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
2004-08-30 19:07:48 Waited 2 seconds for data to become available,
waiting again...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
2004-08-30 19:07:48 prebuffer wait timed out..
2004-08-30 19:07:48 waiting for prebuffer...
'video_output' mean = '33342.58', std. dev. = '868.68', fps = '29.99'
avsync_delay: 30, avsync_avg: 28, warpfactor: 0.9, warpfactor_avg:
0.0439851
'video_output' mean = '64857.74', std. dev. = '221426.63', fps = '15.42'
avsync_delay: 0, avsync_avg: 0, warpfactor: 0.9, warpfactor_avg:
-3.65378e+31
2004-08-30 19:07:50 prebuffering pause
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:50 prebuffer wait timed out..
2004-08-30 19:07:50 waiting for prebuffer...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:51 Waited 2 seconds for data to become available,
waiting again...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:51 prebuffer wait timed out..
2004-08-30 19:07:51 waiting for prebuffer...
2004-08-30 19:07:52 prebuffer wait timed out..
2004-08-30 19:07:52 waiting for prebuffer...
2004-08-30 19:07:52 prebuffer wait timed out..
2004-08-30 19:07:52 waiting for prebuffer...
2004-08-30 19:07:52 prebuffer wait timed out..
2004-08-30 19:07:52 waiting for prebuffer...

[...]

IF there's any other information that you need to help track this down,
please let me know.

Thanks,
Brian
-- 
Brian C. Huffman <huffman at graze.net>