[mythtv-users] Change v30.0-25-gbceea69806 makes playback visibly jumpy (March 10th)

Tue Mar 26 10:42:15 UTC 2019

On 25/03/2019 19:33, jksjdevelop wrote:
> The Medea is 1080i 25Hz UK DVB-S2
>
> The platform is Ubuntu 18.10 64 bit Nvidia GeForce GT 1030/PCIe/SSE2 
> Driver Version: 390.116
>
> Change AVSync2: Use frame timestamps to determine elapsed play time - 
> causes frames to be discarded.
>
> With verbose logging on playback.
>
> At the previous commit, the picture is rock solid with an even 
> scrolling news ticker, despite some frames being discarded
>
> MythTV Version : v30.0-24-g90468ec49a
> MythTV Branch : (HEAD detached at 90468ec49a)
>
> 2019-03-25 18:37:47.571475 I  Player(0): FPS:   25.09 Mean: 39850 
> Std.Dev:  6823 CPUs: 6% 4% 3% 3%
> 2019-03-25 18:37:47.611354 I  Player(0): AV Sync, audio ahead by -25 ms
> 2019-03-25 18:37:47.651361 I  Player(0): AV Sync, audio ahead by -24 ms
> 2019-03-25 18:37:47.691386 I  Player(0): AV Sync, audio ahead by -24 ms
> 2019-03-25 18:37:47.731370 I  Player(0): AV Sync, audio ahead by 61 ms
> 2019-03-25 18:37:47.771335 I  Player(0): AV Sync, audio ahead by -24 ms
> 2019-03-25 18:37:47.811385 I  Player(0): AV Sync, audio ahead by -24 ms
> 2019-03-25 18:37:47.851356 I  Player(0): AV Sync, audio ahead by -25 ms
> 2019-03-25 18:37:47.945553 I  Player(0): AV Sync, audio ahead by 75 ms
> 2019-03-25 18:37:48.115719 I  Player(0): AV Sync, audio ahead by 86 ms
> 2019-03-25 18:37:48.165400 I  Player(0): AV Sync, audio ahead by 96 ms
> 2019-03-25 18:37:48.265463 I  Player(0): AV Sync, audio ahead by 75 ms
> 2019-03-25 18:37:48.315680 I  Player(0): AV Sync, audio ahead by 85 ms
> 2019-03-25 18:37:48.375455 I  Player(0): AV Sync, audio ahead by 66 ms
> 2019-03-25 18:37:48.455489 I  Player(0): AV Sync, audio ahead by 65 ms
> 2019-03-25 18:37:48.505434 I  Player(0): AV Sync, audio ahead by 75 ms
> 2019-03-25 18:37:48.515385 I  VidOutVDPAU: Frame B is in use by avlib 
> and so is being held for later discarding.
> 2019-03-25 18:37:48.535378 I  VidOutVDPAU: Frame B is in use by avlib 
> and so is being held for later discarding.
> 2019-03-25 18:37:48.535387 I  VidOutVDPAU: Frame B is in use by avlib 
> and so is being held for later discarding.
> 2019-03-25 18:37:48.551326 I  VidOutVDPAU: Frame B is in use by avlib 
> and so is being held for later discarding.
> 2019-03-25 18:37:48.551335 I  VidOutVDPAU: Frame B is in use by avlib 
> and so is being held for later discarding.
> 2019-03-25 18:37:48.571431 I  Player(0): AV Sync, audio ahead by -24 ms
> 2019-03-25 18:37:48.615457 I  Player(0): AV Sync, audio ahead by 66 ms
> 2019-03-25 18:37:48.665543 I  Player(0): AV Sync, audio ahead by 76 ms
> 2019-03-25 18:37:48.731440 I  Player(0): AV Sync, audio ahead by 61 ms
> 2019-03-25 18:37:48.811439 I  Player(0): AV Sync, audio ahead by 61 ms
> 2019-03-25 18:37:48.891484 I  Player(0): AV Sync, audio ahead by 62 ms
> 2019-03-25 18:37:48.971474 I  Player(0): AV Sync, audio ahead by -24 ms
> 2019-03-25 18:37:49.065890 I  Player(0): AV Sync, audio ahead by 75 ms
>
> Introducing the next change and patching the compilation error. Causes 
> many more frames to be dropped and the picture becomes visibly jerky. 
> Changing to Opengl High Quality does not fix the issue,
>
> MythTV Version : v30.0-25-gbceea69806-dirty
> MythTV Branch : (HEAD detached at bceea69806)
>
> 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 76 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 86 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 65 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 65 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 66 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 85 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame B is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 77 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame d is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 75 ms
> Mar 25 18:58:06 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame d is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 86 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame g is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 77 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame g is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 87 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame g is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame g is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 68 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 65 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame F is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 75 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame F is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 75 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 75 ms
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame h is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:749 (AutoDeint) Player(0): 400 interlaced frames seen.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame h is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:07 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame h is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:08 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> jitterometer.cpp:120 (RecordEndTime) Player(0): FPS: 24.93 Mean: 40106 
> Std.Dev:  8712 CPUs: 4% 4% 4% 4%
> Mar 25 18:58:08 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame a is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:08 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 77 ms
> Mar 25 18:58:08 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame a is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:08 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> mythplayer.cpp:2289 (AVSync2) Player(0): AV Sync, audio ahead by 87 ms
> Mar 25 18:58:08 tv mythfrontend: mythfrontend[17102]: I CoreContext 
> videoout_vdpau.cpp:1065 (CheckFrameStates) VidOutVDPAU: Frame a is in 
> use by avlib and so is being held for later discarding.
> Mar 25 18:58:08 tv mythfro
>
>
Apologies for misleading info, the testing must have got confused.

This issue is limited to playback of 1080i 25Hz using VDPAU high quality 
and Avsync2. When using AvSync1 with VDPAU there is no visible jitter 
but frames are still being dropped, dependent on the medea. There are no 
visible issues or dropped frames with OpenGL High Quality under Avsync2.