[mythtv-users] Pi3 Playback problems with 1080i MPEG2?

[Tom Harris]

On Mon, May 30, 2016 at 7:35 AM, Peter Bennett (cats22) <cats22 at comcast.net>
wrote:
>
> On 05/29/2016 09:58 PM, Tom Harris wrote:
> > I just set up a new Pi3 frontend on Raspbian Lenny + Peter’s .deb
> > package for 0.27.
> > After some tweaking of various settings in raspbian and mythfrontend,
> > it is working well.
> >
> > The one issue is that playback of 1080i content has problems.  It
> > misses frames, pauses, and is generally not watchable.  720p video is
> > fine.
> >
> > I verified a few things in debugging it:  content streams are fine,
> > bandwidth to frontend is fine, settings are correct (as far as I can
> > tell).   I played back the same videos just fine via Kodi’s mythtv
> > add-on on my Pi2 as well as the Apple TV mythfrontend.
> >
> > ‘-v playback’ confirms openmax is enabled.   But, there is an error,
> > which I’m not sure if it’s normal or a real problem: “E  AFD: Unknown
> > decoding error"
> >
> > Has anyone else run into this?   Any more settings I should check?
> >
> Check your playback profile. You should have "Openmax normal" selected.
> Also edit the profile and check that the decoder and renderer are both
> set to openmax. Set the number of processors to 4.


Yes, all of those settings are correct.   The other settings on those
pages, I just left default:

‘Deblocking filter’ is selected
‘OSD fade’ is not selected
‘OSD renderer’ is soft blend

Primary deinterlacer: Advanced (HW)

Fallback deinterlacer: One field



>
> Do you have the latest version? There was a bug with dropping frames
> that has been fixed. "Unknown decoding error" is one message that
> occurred with that bug.


Yes, it appears to be current (same version currently downloadable from
your site).

>
>
> What type of content is this? Is it MPEG2 or H264? Is it recordings or
> videos? Is it only one particular recording?


MPEG2.  It’s Comcast cable TV recordings, from several stations - some
local NBC/CBS, some national TNT, etc.

>
> Are you using an on screen display (e.g. Subtitles or Info)?


No. No subtitles, etc.

>
> If you still have problems let us know your version (mythfrontend
> --version).


MythTV Version : v0.27.6-86-ge2ccfdf
MythTV Branch : raspi/0.27
Network Protocol : 77
Library API : 0.27.20151025-1
QT Version : 5.3.2
Options compiled in:
 linux profile use_hidesyms using_asa using_oss using_pulse
using_pulseoutput using_backend using_bindings_perl using_bindings_python
using_bindings_php using_dvb using_firewire using_frontend using_hdhomerun
using_ceton using_hdpvr using_ivtv using_joystick_menu using_libcrypto
using_libdns_sd using_libfftw3 using_libxml2 using_lirc using_mheg
using_qtwebkit using_qtscript using_qtdbus using_sdl using_taglib
using_v4l2 using_x11 using_xrandr using_xv using_profiletype
using_bindings_perl using_bindings_python using_bindings_php
using_mythtranscode using_opengles using_openmax using_ffmpeg_threads
using_mheg using_libass using_libxml2

 Here is some more complete output from ‘-v playback’.. anything else I can
enable for more debugging detail?

2016-05-31 19:35:47.336368 I
 FileRingBuf(myth://mythbackend/1720_20160531230000.mpg):
OpenFile(myth://mythbackend/1720_20160531230000.mpg, 2000 ms)
2016-05-31 19:35:49.477791 I  MythCoreContext: Connecting to backend
server: 10.1.1.11:6543 (try 1 of 1)
2016-05-31 19:35:49.502527 I  TV: StartPlayer(0, WatchingRecording, main)
-- begin
2016-05-31 19:35:49.502727 I  TV: Elapsed time since TV constructor was
called: 2709 ms
2016-05-31 19:35:49.931088 N  AudioPlayer: Enabling Audio
2016-05-31 19:35:50.109956 I  Player(0): Stretch Factor 1, allow passthru
2016-05-31 19:35:50.128786 I  AFD: Buffer size: 32768 streamed 0 seekable 1
2016-05-31 19:35:50.629389 I  AFD: Stream #0, has id 0xe23 codec id
MPEG2VIDEO, type Video, bitrate 0 at 0x1f67420
2016-05-31 19:35:50.629440 I  AFD: Stream #1, has id 0xe24 codec id AC3,
type Audio, bitrate 384000 at 0x1f64570
2016-05-31 19:35:50.629462 I  AFD: codec AC3 has 6 channels
2016-05-31 19:35:50.629477 I  AFD: Looking for decoder for AC3
2016-05-31 19:35:50.630466 I  AFD: Opened codec 0x1f64770, id(AC3)
type(Audio)
2016-05-31 19:35:50.636921 I  AFD: Stream #2, has id 0xe25 codec id AC3,
type Audio, bitrate 192000 at 0x1f64d70
2016-05-31 19:35:50.636954 I  AFD: codec AC3 has 2 channels
2016-05-31 19:35:50.636971 I  AFD: Looking for decoder for AC3
2016-05-31 19:35:50.637973 I  AFD: Opened codec 0x2450270, id(AC3)
type(Audio)
2016-05-31 19:35:50.638221 I  AFD: Trying to select best video track
2016-05-31 19:35:50.638325 I  AFD: Selected track #0 (id 0xe23 codec id
MPEG2VIDEO, type Video, bitrate 500000 at 0x1f67420)
2016-05-31 19:35:50.675151 I  VDP: Accepting: cmp(> 0 0) dec(openmax)
cpus(4) skiploop(enabled) rend(openmax) osd(softblend) osdfade(disabled)
deint(openmaxadvanced,onefield) filt()
2016-05-31 19:35:50.675233 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-05-31 19:35:50.675309 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-05-31 19:35:50.675362 I  VDP: LoadBestPreferences(1920x1080, 60)
2016-05-31 19:35:50.704657 I  DOMX:2 Codec MPEG2VIDEO =>
OMX_VIDEO_CodingMPEG2
2016-05-31 19:35:50.707993 I  AFD: Using 1 CPUs for decoding
2016-05-31 19:35:50.708047 I  AFD: InitVideoCodec() 0x1f64190
id(MPEG2VIDEO) type (Video).
2016-05-31 19:35:50.708163 I  AFD: Selected FPS is 29.97 (avg 29.97 codec
29.97 container 90000 estimated 29.97)
2016-05-31 19:35:50.708237 I  Player(0): SetFrameInterval ps:1 scan:3
2016-05-31 19:35:50.708309 I  Player(0): detectInterlace(Detect Scan,
Interlaced Scan, 29.97, 1080) ->Interlaced Scan
2016-05-31 19:35:50.708492 I  AFD: Using openmax for video decoding
2016-05-31 19:35:50.708560 I  AFD: Opened codec 0x1f64190, id(MPEG2VIDEO)
type(Video)
2016-05-31 19:35:50.752930 I  AOBase: Opening audio device 'OpenMAX:hdmi'
ch 2(6) sr 48000 sf signed 16 bit reenc 0
2016-05-31 19:35:50.773447 I  Dec: Selected track #1 (type 2) in the
Unknown language(0)
2016-05-31 19:35:50.773522 I  Dec: Resyncing position map. posmapStarted =
0 livetv(0) watchingRec(1)
2016-05-31 19:35:51.427047 I  Dec: Position map filled from DB to: 388010
2016-05-31 19:35:51.519841 I  Dec: Duration map filled from DB to: 388010
2016-05-31 19:35:51.529885 I  Dec: SyncPositionMap watchingrecording, from
DB: 17072 entries
2016-05-31 19:35:51.529991 I  Player(0): Filling position map from 388011
to end
2016-05-31 19:35:51.562345 I  Dec: Position map filled from Encoder to:
388070
2016-05-31 19:35:51.562516 I  Dec: Duration map filled from Encoder to:
388070
2016-05-31 19:35:51.562550 I  Dec: SyncPositionMap watchingrecording total:
17076 entries
2016-05-31 19:35:51.562600 I  Dec: SyncPositionMap, new totframes: 388070,
new length: 12948, posMap size: 17076
2016-05-31 19:35:51.571581 I  AFD: Partial position map found
2016-05-31 19:35:51.571619 I  AFD: Successfully opened decoder for file:
"myth://mythbackend/1720_20160531230000.mpg". novideo(0)
2016-05-31 19:35:51.638587 I  VideoOutput: Allowed renderers:
xv-blit,xshm,xlib,openmax
2016-05-31 19:35:51.638644 I  VideoOutput: Allowed renderers (filt:
openmax): openmax
2016-05-31 19:35:51.681016 I  VDP: Accepting: cmp(> 0 0) dec(openmax)
cpus(4) skiploop(enabled) rend(openmax) osd(softblend) osdfade(disabled)
deint(openmaxadvanced,onefield) filt()
2016-05-31 19:35:51.681067 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-05-31 19:35:51.681135 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-05-31 19:35:51.681188 I  VDP: LoadBestPreferences(1920x1080, 60)
2016-05-31 19:35:51.681254 I  VideoOutput: Preferred renderer: openmax
2016-05-31 19:35:51.681285 I  VideoOutput: Trying video renderer: 'openmax'
2016-05-31 19:35:51.976045 I  VDP: Accepting: cmp(> 0 0) dec(openmax)
cpus(4) skiploop(enabled) rend(openmax) osd(softblend) osdfade(disabled)
deint(openmaxadvanced,onefield) filt()
2016-05-31 19:35:51.976099 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-05-31 19:35:51.976171 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-05-31 19:35:52.027648 I  VideoOutWindow::SetPIPState. pip_state: 0]
2016-05-31 19:35:52.027702 I  VDP: LoadBestPreferences(2048x2048, 29.97)
2016-05-31 19:35:52.027815 I  VOMX:3 Init vbuf=1920x1088 vdisp=1920x1080
aspect=1.77778 win=0,0,1920x1080 codec=MPEG2
2016-05-31 19:35:52.027855 I  Snapping width to avoid scaling: width: 1920,
left: 0
2016-05-31 19:35:52.027888 I  Display Rect  left: 0, top: 135, width: 1920,
height: 810, aspect: 1.33333
2016-05-31 19:35:52.027920 I  Video Rect    left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2016-05-31 19:35:52.027949 I  VDP: LoadBestPreferences(1920x1088, 29.97)
2016-05-31 19:35:52.027993 I  Snapping width to avoid scaling: width: 1920,
left: 0
2016-05-31 19:35:52.028025 I  Display Rect  left: 0, top: 135, width: 1920,
height: 810, aspect: 1.33333
2016-05-31 19:35:52.028061 I  Video Rect    left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2016-05-31 19:35:52.028091 I  VDP: SetVideoRenderer(openmax)
2016-05-31 19:35:52.028115 I  VDP: SetVideoRender(openmax) ==
GetVideoRenderer()
2016-05-31 19:35:52.029805 E  X11 ModeLine query returned zeroes
2016-05-31 19:35:52.030347 I  VideoOutput: Pixel dimensions: Screen
1920x1080, window 1920x1080
2016-05-31 19:35:52.030404 I  VideoOutput: Actual display dimensions:
508x286 mm  Aspect: 1.77622
2016-05-31 19:35:52.030538 I  VideoOutput: Estimated window dimensions:
508x286 mm  Aspect: 1.77622
2016-05-31 19:35:52.136229 I  Snapping height to avoid scaling: height:
1080, top: 0
2016-05-31 19:35:52.136281 I  Snapping width to avoid scaling: width: 1920,
left: 0
2016-05-31 19:35:52.136347 I  Display Rect  left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2016-05-31 19:35:52.136414 I  Video Rect    left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2016-05-31 19:35:52.136531 I  VOMX:3 SetVideoRect display=0,0,1920x1080
(1.77622) video=0,0,1920x1080 (1.77778)
2016-05-31 19:35:52.205637 I  Over/underscan. V: 0, H: 0
2016-05-31 19:35:52.205687 I  Snapping height to avoid scaling: height:
1080, top: 0
2016-05-31 19:35:52.205706 I  Snapping width to avoid scaling: width: 1920,
left: 0
2016-05-31 19:35:52.205747 I  Display Rect  left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2016-05-31 19:35:52.205780 I  Video Rect    left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2016-05-31 19:35:52.205960 I  Player(0): LoadFilters(''..) -> 0x0
2016-05-31 19:35:52.242865 I  Player(0): Decoder thread starting.
2016-05-31 19:35:52.259468 I  OSD: Loaded window osd_message
2016-05-31 19:35:52.272461 I  OSD: Loaded window osd_input
2016-05-31 19:35:52.304081 E  MythFontProperties: Failed to load
'Liberation Sans', got 'DejaVu Sans' instead
                        Location: /usr/share/mythtv/themes/Terra/osd.xml @
119
                        Name: 'timefont'        Type: 'fontdef'
2016-05-31 19:35:52.308133 I  OSD: Loaded window program_info
2016-05-31 19:35:52.325793 E  MythFontProperties: Failed to load
'Liberation Sans', got 'DejaVu Sans' instead
                        Location: /usr/share/mythtv/themes/Terra/osd.xml @
247
                        Name: 'timefont'        Type: 'fontdef'
2016-05-31 19:35:52.329043 I  OSD: Loaded window browse_info
2016-05-31 19:35:52.340911 I  OSD: Loaded window osd_status
2016-05-31 19:35:52.356016 I  OSD: Loaded window osd_program_editor
2016-05-31 19:35:52.394023 I  OSD: Loaded window osd_debug
2016-05-31 19:35:52.394120 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
2016-05-31 19:35:52.394604 I  OSD: Created window aa_OSD_SUBTITLES
2016-05-31 19:35:52.394787 I  Player(0): ClearAfterSeek(0)
2016-05-31 19:35:52.396431 E  X11 ModeLine query returned zeroes
2016-05-31 19:35:52.396708 I  VDP: GetFilteredDeint() : openmax ->
'openmaxadvanced'
2016-05-31 19:35:52.396782 I  VOMX:3 SetupDeinterlace switching on
'openmaxadvanced'
2016-05-31 19:35:52.397203 I  VSYNC: DRMVideoSync: Could not open device
/dev/dri/card0, No such file or directory
2016-05-31 19:35:52.397324 E  VSYNC: RTCVideoSync: Could not open /dev/rtc:
                        eno: No such file or directory (2)
2016-05-31 19:35:52.398550 E  X11 ModeLine query returned zeroes
2016-05-31 19:35:52.398790 I  Player(0): Video timing method: USleep with
busy wait
2016-05-31 19:35:52.398867 I  Player(0): Display Refresh Rate: 59.941 Video
Frame Rate: 29.970
2016-05-31 19:35:52.398921 I  Player(0): SetFrameInterval ps:1 scan:1
2016-05-31 19:35:52.402965 I  playCtx: StartPlaying(): took 0 ms to start
player.
2016-05-31 19:35:52.403052 I  TV: Created player.
2016-05-31 19:35:52.403146 I  TV: StartPlayer(0, WatchingRecording, main)
-- end ok
2016-05-31 19:35:52.403280 I  TV: Changing from None to WatchingRecording
2016-05-31 19:35:52.442642 I  TV: DrawUnusedRects() -- begin
2016-05-31 19:35:52.442686 I  TV: DrawUnusedRects() -- end
2016-05-31 19:35:52.442857 I  TV: Main UI disabled.
2016-05-31 19:35:52.442885 I  TV: HandleStateChange(0) -- end
2016-05-31 19:35:52.443040 I  TV: tv->Playback() -- end
2016-05-31 19:35:52.443099 I  TV: Entering main playback loop.
2016-05-31 19:35:52.481832 I  DOMX:2 SettingsChanged - begin
2016-05-31 19:35:52.483807 I  OMX:2 Port 131: output, bufs=2(1)
bufsize=3133440 at 16 discontiguous, disabled, unpopulated
2016-05-31 19:35:52.483963 I  OMX:2 Port 131: video, w=1920 h=1080
stride=1920 sliceH=1088 bps=0 fps=29.97 compress=OMX_VIDEO_CodingUnused
enc=OMX_COLOR_FormatYUV420PackedPlanar
2016-05-31 19:35:52.484300 I  DOMX:2
OMX_InterlaceFieldsInterleavedUpperFirst
2016-05-31 19:35:52.484466 I  DOMX:2 Pixel aspect x/y = 1/1
2016-05-31 19:35:52.499934 I  ScreenSaverX11Private: DPMS Deactivated 1
2016-05-31 19:35:52.501348 I  Player(0): Waiting for video buffers...
2016-05-31 19:35:52.504373 I  DOMX:2 SettingsChanged - end
2016-05-31 19:35:52.504407 E  AFD: Unknown decoding error
2016-05-31 19:35:52.569794 I  VDP: GetFilteredDeint() : openmax ->
'openmaxadvanced'
2016-05-31 19:35:52.569854 I  Player(0): Enabled deinterlacing
2016-05-31 19:35:53.019851 I  VideoOutput: Created YV12 OSD.
2016-05-31 19:35:53.029279 I  TV: DrawUnusedRects() -- begin
2016-05-31 19:35:53.029310 I  TV: DrawUnusedRects() -- end
2016-05-31 19:35:53.033187 I  TV: DrawUnusedRects() -- begin
2016-05-31 19:35:53.033217 I  TV: DrawUnusedRects() -- end
2016-05-31 19:35:53.131675 I  TV: DrawUnusedRects() -- begin
2016-05-31 19:35:53.131711 I  TV: DrawUnusedRects() -- end
2016-05-31 19:35:53.171827 I  Player(0): Video is 6.27117 frames behind
audio (too slow), dropping frame to catch up.
2016-05-31 19:35:53.189281 I  Player(0): Video is 10.7273 frames behind
audio (too slow), dropping frame to catch up.

 (snip..  it keeps generating messages about skipping frames, etc.)
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20160601/436e606d/attachment.html>