Playback choppy for some recordings after upgrade to v31

Have a MythTV related problem? Ask for help from other MythTV users here.

Moderator: Forum Moderators

Post Reply
dawson64
Newcomer
Posts: 4
Joined: Sun Feb 14, 2021 6:46 pm
United States of America

Playback choppy for some recordings after upgrade to v31

Post by dawson64 »

I upgraded to ubuntu 20.04 on Thursday and I found that some recordings have a very choppy playback in mythtv frontend & also when I pull up the .ts file in VLC. I see that v31 has new video decoding & playback settings but I'm a little lost. I'm not too familiar with different codecs but I've spent quite a bit of time trying to troubleshoot and gather information. I haven't made much progress in understanding the issue though. I'm hoping someone can point me in the right direction.

Here is a playback log of a recording that isn't working.

Code: Select all

$ mythfrontend -v playback
"Display: Requesting EGL for 'Mesa Project, 1.5'"
2021-02-14 14:01:09.947503 I  Setup Interrupt handler
2021-02-14 14:01:09.947521 I  Setup Terminated handler
2021-02-14 14:01:09.947526 I  Setup Segmentation fault handler
2021-02-14 14:01:09.947531 I  Setup Aborted handler
2021-02-14 14:01:09.947535 I  Setup Bus error handler
2021-02-14 14:01:09.947540 I  Setup Floating point exception handler
2021-02-14 14:01:09.947545 I  Setup Illegal instruction handler
2021-02-14 14:01:09.947551 I  Setup Real-time signal 0 handler
2021-02-14 14:01:09.947556 I  Setup User defined signal 1 handler
2021-02-14 14:01:09.947560 I  Setup User defined signal 2 handler
2021-02-14 14:01:09.947564 I  Setup Hangup handler
2021-02-14 14:01:09.947653 C  mythfrontend version: fixes/31 [v31.0-v31.0] 
2021-02-14 14:01:09.947658 C  Qt version: compile: 5.12.5, runtime: 5.12.8
2021-02-14 14:01:09.947691 I  Ubuntu 20.04.2 LTS (x86_64)
2021-02-14 14:01:09.947694 N  Enabled verbose msgs:  general playback
2021-02-14 14:01:09.947705 N  Setting Log Level to LOG_INFO
2021-02-14 14:01:09.957961 I  Added logging to the console
2021-02-14 14:01:09.958003 N  Using runtime prefix = /usr
2021-02-14 14:01:09.958010 N  Using configuration directory = /home/dawson64/.mythtv
2021-02-14 14:01:09.958048 I  Assumed character encoding: en_US.UTF-8
2021-02-14 14:01:09.958349 I  Added syslogging
2021-02-14 14:01:09.958607 I  Loading en_us translation for module mythfrontend
2021-02-14 14:01:09.958893 I  Empty LocalHostName. This is typical.
2021-02-14 14:01:09.958903 I  Using a profile name of: 'MythTv' (Usually the same as this host's name.)
2021-02-14 14:01:09.958996 I  Start up testing connections. DB localhost, BE , attempt 0, status dbAwake, Delay: 2000
2021-02-14 14:01:10.977156 N  Setting QT default locale to en_US
2021-02-14 14:01:10.977169 I  Current locale en_US
2021-02-14 14:01:10.977230 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2021-02-14 14:01:10.986027 I  Display: Found screen number 0 (DVI-0)
2021-02-14 14:01:10.986050 I  Display: Using screen 'DVI-0' (Make: Panasonic Industry Company Model: Panasonic-TV-)
2021-02-14 14:01:10.986083 I  Display: Geometry: 1920x1080+0+0 Size(Qt): 698mmx392mm
2021-02-14 14:01:10.987552 I  DisplayX11: Ignoring interlaced mode 1920x1080 60.00i
2021-02-14 14:01:10.987563 I  DisplayX11: Ignoring interlaced mode 1920x1080 59.94i
2021-02-14 14:01:10.987577 I  DisplayX11: Ignoring interlaced mode 1440x480 59.94i
2021-02-14 14:01:10.987587 I  DisplayX11: Ignoring interlaced mode 720x480 60.00i
2021-02-14 14:01:10.987591 I  DisplayX11: Ignoring interlaced mode 720x480 59.94i
2021-02-14 14:01:10.987639 I  Display: Available modes:
2021-02-14 14:01:10.987675 I  1920x1080	60.00	59.94	24.00	23.98
2021-02-14 14:01:10.987684 I  1280x720	60.00	59.94
2021-02-14 14:01:10.987691 I  720x480	60.00	59.94
2021-02-14 14:01:10.987697 I  640x480	60.00	59.94
2021-02-14 14:01:10.988145 N  Display: Desktop video mode: 1920x1080 60.000Hz
2021-02-14 14:01:10.988151 N  Display: Display has custom colourspace
2021-02-14 14:01:10.997860 I  Starting process manager
2021-02-14 14:01:10.997923 I  Starting process signal handler
2021-02-14 14:01:10.997980 I  Starting IO manager (write)
2021-02-14 14:01:10.999624 I  Starting IO manager (read)
2021-02-14 14:01:11.048237 I  ScreenSaverX11Private: XScreenSaver support enabled
2021-02-14 14:01:11.049546 I  ScreenSaverX11Private: DPMS is disabled.
2021-02-14 14:01:11.211116 I  Power: On AC power
2021-02-14 14:01:11.211151 I  Power: Supported actions: Suspend,Restart,Shutdown
2021-02-14 14:01:11.216115 I  Listening on TCP 0.0.0.0:6547
2021-02-14 14:01:11.216821 I  Listening on TCP [::]:6547
2021-02-14 14:01:13.546360 I  Loading en_us translation for module mythfrontend
2021-02-14 14:01:13.550533 E  LIRC: Failed to connect to Unix socket '/dev/lircd'
			eno: No such file or directory (2)
2021-02-14 14:01:13.550565 I  No joystick configuration found, not enabling joystick control
2021-02-14 14:01:13.550570 I  UDPListener: Enabling
2021-02-14 14:01:13.551055 I  Binding to UDP 0.0.0.0:6948
2021-02-14 14:01:13.551120 I  Binding to UDP [::]:6948
2021-02-14 14:01:13.578020 I  Using Frameless Window
2021-02-14 14:01:13.578026 I  Using Full Screen Window
2021-02-14 14:01:13.601424 I  Display: Have main widget
2021-02-14 14:01:13.601430 I  Display: Have main window
2021-02-14 14:01:13.601700 I  Display: Found screen number 0 (DVI-0)
2021-02-14 14:01:13.602226 I  UI Screen Resolution: 1920 x 1080
2021-02-14 14:01:13.662331 I  OpenGL: OpenGL vendor        : X Org
2021-02-14 14:01:13.662338 I  OpenGL: OpenGL renderer      : AMD CAICOS (DRM 2.50.0 / 5.4.0-65-generic, LLVM 11.0.0)
2021-02-14 14:01:13.662342 I  OpenGL: OpenGL version       : 3.1 Mesa 20.2.6
2021-02-14 14:01:13.662347 I  OpenGL: Qt platform          : xcb
2021-02-14 14:01:13.662390 I  OpenGL: EGL display          : Yes
2021-02-14 14:01:13.662393 I  OpenGL: EGL images           : Yes
2021-02-14 14:01:13.662396 I  OpenGL: Qt OpenGL format     : OpenGL 3.1
2021-02-14 14:01:13.662399 I  OpenGL: Qt OpenGL surface    : RGBA: 8880 Depth: 0 Stencil: 0
2021-02-14 14:01:13.662403 I  OpenGL: Max texture size     : 16384
2021-02-14 14:01:13.662406 I  OpenGL: Max texture units    : 96
2021-02-14 14:01:13.662409 I  OpenGL: Shaders              : Yes
2021-02-14 14:01:13.662412 I  OpenGL: NPOT textures        : Yes
2021-02-14 14:01:13.662416 I  OpenGL: Multitexturing       : Yes
2021-02-14 14:01:13.662418 I  OpenGL: Rectangular textures : Yes
2021-02-14 14:01:13.662421 I  OpenGL: Buffer mapping       : Yes
2021-02-14 14:01:13.662424 I  OpenGL: Framebuffer objects  : Yes
2021-02-14 14:01:13.662427 I  OpenGL: 16bit framebuffers   : Yes
2021-02-14 14:01:13.662430 I  OpenGL: Unpack Subimage      : Yes
2021-02-14 14:01:13.662432 I  OpenGL: GL_RED/GL_R8         : Yes
2021-02-14 14:01:13.669398 I  OpenGL: Initialised MythRenderOpenGL
2021-02-14 14:01:13.669406 I  OpenGL: Using full range output
2021-02-14 14:01:13.671930 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/remotecache
2021-02-14 14:01:13.672302 I  MythUIHelper: Kept 51 files, deleted 0 files, stat error on 0 files
2021-02-14 14:01:13.672328 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/thumbnails
2021-02-14 14:01:13.673459 I  MythUIHelper: Kept 121 files, deleted 0 files, stat error on 0 files
2021-02-14 14:01:13.783166 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.64.2:6543 (try 1 of 1)
2021-02-14 14:01:13.784093 I  MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2021-02-14 14:01:13.844204 I  CECAdapter: Using physical address 1.0.0.0 from EDID
2021-02-14 14:01:13.999740 E  CECAdapter: Failed to find any CEC devices.
[AVHWDeviceContext @ 0x563ac221e980] Failed to initialise VAAPI connection: -1 (unknown libva error).
Cannot load libcuda_dot_so.1
2021-02-14 14:01:14.499981 I  CECAdapter: Closing down CEC.
2021-02-14 14:01:14.504195 I  Current MythTV Schema Version (DBSchemaVer): 1361
2021-02-14 14:01:14.525656 I  VDPAUHelp: Supported/available VDPAU decoders:
2021-02-14 14:01:14.531496 I  VDPAUHelp: MPEG2 Simple (Max size: 2048x1152)
2021-02-14 14:01:14.531504 I  VDPAUHelp: MPEG2 Main (Max size: 2048x1152)
2021-02-14 14:01:14.531508 I  VDPAUHelp: MPEG4 Simple (Max size: 2048x1152)
2021-02-14 14:01:14.531512 I  VDPAUHelp: MPEG4 Advanced Simple (Max size: 2048x1152)
2021-02-14 14:01:14.531516 I  VDPAUHelp: VC1 Simple (Max size: 2048x1152)
2021-02-14 14:01:14.531520 I  VDPAUHelp: VC1 Main (Max size: 2048x1152)
2021-02-14 14:01:14.531532 I  VDPAUHelp: VC1 Advanced (Max size: 2048x1152)
2021-02-14 14:01:14.531535 I  VDPAUHelp: H264 Baseline (Max size: 2048x1152)
2021-02-14 14:01:14.531538 I  VDPAUHelp: H264 Main (Max size: 2048x1152)
2021-02-14 14:01:14.531541 I  VDPAUHelp: H264 High (Max size: 2048x1152)
2021-02-14 14:01:14.531545 I  VDPAUHelp: H264 Constrained (Max size: 2048x1152)
2021-02-14 14:01:14.533067 E  MythCodecContext: Failed to create hardware device 'vaapi' Error 'Input/output error'
2021-02-14 14:01:14.533074 I  VAAPIDec: VAAPI functionality checked failed
2021-02-14 14:01:14.533193 E  NVDECInterop: Failed to load functions
2021-02-14 14:01:14.533208 I  NVDEC: No NVDEC decoders found
2021-02-14 14:01:14.533542 I  V4L2_M2M: No V4L2 decoders found
2021-02-14 14:01:14.533782 I  DRMPRIMECtx: DRM PRIME codecs supported: None 
2021-02-14 14:01:14.533816 I  VDP: decoder<->render support: ffmpeg      null opengl opengl-yv12
2021-02-14 14:01:14.533821 I  VDP: decoder<->render support: vdpau       opengl-hw
2021-02-14 14:01:14.533824 I  VDP: decoder<->render support: vdpau-dec   null opengl opengl-yv12
2021-02-14 14:01:14.540813 N  VDP: Ignoring profile 42 (decoder nvdec is not available)
2021-02-14 14:01:14.546482 N  VDP: Ignoring profile 44 (decoder v4l2 is not available)
2021-02-14 14:01:14.547583 N  VDP: Ignoring profile 32 (decoder vaapi is not available)
2021-02-14 14:01:14.727393 N  Registering Internal as a media playback plugin.
2021-02-14 14:01:14.728560 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2021-02-14 14:01:14.729499 W  MMUnix:UDisks2 service found. Media Monitor does not support this yet!
2021-02-14 14:01:14.733854 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1024
2021-02-14 14:01:14.742348 I  Loading en_us translation for module mythmusic
2021-02-14 14:01:14.743671 I  Listening on TCP 0.0.0.0:6546
2021-02-14 14:01:14.744065 I  Listening on TCP [::]:6546
2021-02-14 14:01:14.774288 N  Found mainmenu.xml for theme 'Mythbuntu'
2021-02-14 14:01:14.776634 I  Checking for theme updates every hour
2021-02-14 14:01:14.776795 I  Registering HouseKeeperTask 'HardwareProfiler'.
2021-02-14 14:01:14.777630 I  Starting HouseKeeper.
2021-02-14 14:01:14.846264 I  Bonjour: Service registration complete: name 'Mythfrontend on MythTv' type '_mythfrontend._tcp.' domain: 'local.'
2021-02-14 14:01:19.740515 I  TV::TV(): Creating TV object
2021-02-14 14:01:19.747652 I  TV::TV(): Finished creating TV object
2021-02-14 14:01:19.747684 N  Suspending idle timer
2021-02-14 14:01:19.750778 I  TV::Init(): Created TvPlayWindow.
2021-02-14 14:01:19.763570 I  TV::HandleStateChange(): Attempting to change from None to WatchingPreRecorded
2021-02-14 14:01:19.764363 I  FileRingBuf(/var/lib/mythtv/recordings/1171_20210212022900.ts): OpenFile(/var/lib/mythtv/recordings/1171_20210212022900.ts, 2000 ms)
2021-02-14 14:01:19.767397 I  TV::StartPlayer(): Elapsed time since TV constructor was called: 26 ms
2021-02-14 14:01:19.966816 N  AudioPlayer: Enabling Audio
2021-02-14 14:01:19.966829 I  Player(0): Opening '/var/lib/mythtv/recordings/1171_20210212022900.ts'
2021-02-14 14:01:19.970467 I  VDP: Accepting: cmp() w() h() framerate() codecs() dec(ffmpeg) cpus(1) skiploop(enabled) rend(opengl) deint(medium:shader,medium:shader)
2021-02-14 14:01:19.970813 I  AFD: PlayerFlags: 0xc0, AudioReadAhead: 100 msec
2021-02-14 14:01:19.970831 I  Player(0): Stretch Factor 1, allow passthru 
2021-02-14 14:01:19.971015 I  AFD: Buffer size: 32768 Streamed 0 Seekable 1 Available 327680
2021-02-14 14:01:19.972450 I  AFD: Stream #0: ID: 0x0 Codec ID: Unknown Codec ID Type: Video(1280x720) Bitrate: 0
2021-02-14 14:01:19.972458 I  AFD: Stream #1: ID: 0x0 Codec ID: MP3 Type: Audio Bitrate: 1411200
2021-02-14 14:01:19.972463 I  AFD: codec MP3 has 2 channels
2021-02-14 14:01:19.972466 I  AFD: Looking for decoder for MP3
2021-02-14 14:01:19.972505 I  AFD: Opened codec 0x563ac3309140, id(MP3) type(Audio)
2021-02-14 14:01:19.972555 I  AFD: Trying to select best video track
2021-02-14 14:01:19.972577 I  AFD: Selected track #0: ID: 0x0 Codec ID: Unknown Codec ID Profile:  Type: Video(1280x720) Bitrate: 500000
2021-02-14 14:01:19.972614 I  AFD: Selected FPS: 59.9401 (Avg:59.9401 Mult:1 Codec:0 Container:1000 Estimated:59.9401)
2021-02-14 14:01:19.972647 I  VDP: LoadBestPreferences(1280x720, 59.940, nuv)
2021-02-14 14:01:19.972666 I  VDP: LoadBestPreferences result: priority:1 width: height: fps: codecs:
2021-02-14 14:01:19.972673 I  VDP: decoder:ffmpeg renderer:opengl deint0:medium:shader deint1:medium:shader cpus:1
2021-02-14 14:01:19.972682 E  AFD: Unknown video codec - defaulting to MPEG2
2021-02-14 14:01:19.972685 I  AFD: Using 1 CPUs for decoding
2021-02-14 14:01:19.972689 I  AFD: InitVideoCodec ID:Unknown Codec ID Type:Video Size:1280x720
2021-02-14 14:01:19.972718 I  Player(0): SetFrameInterval Interval:16683 Speed:1 Scan:Progressive Scan (Multiplier: 1)
2021-02-14 14:01:19.972728 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2021-02-14 14:01:19.972740 I  AFD: Using ffmpeg for video decoding
2021-02-14 14:01:19.972756 I  AFD: Opened codec 0x563ac33182c0, id(Unknown Codec ID) type(Video)
2021-02-14 14:01:19.972816 I  AOBase: Opening audio device 'front:CARD=PCH,DEV=0' ch 2(2) sr 48000 sf signed 32 bit reenc 0
2021-02-14 14:01:19.974084 W  ALSA: Requested 500000us got 170666 buffer time
2021-02-14 14:01:19.974152 W  ALSA: Try to manually increase audio buffer with: echo 192 | sudo tee /proc/asound/card0/pcm0p/sub0/prealloc
2021-02-14 14:01:20.015382 E  ALSA: no playback control PCM found on mixer device default
2021-02-14 14:01:20.015397 E  ALSA: Unable to open audio mixer. Volume control disabled
2021-02-14 14:01:20.015504 I  Dec: Selected track #1 (type 2) in the Unknown language(0)
2021-02-14 14:01:20.015528 I  Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2021-02-14 14:01:20.017060 I  Dec: SyncPositionMap prerecorded, from DB: 0 entries
2021-02-14 14:01:20.017911 I  AFD: Recording has no position -- using libavformat seeking.
2021-02-14 14:01:20.018050 I  AFD: Successfully opened decoder for file: "/var/lib/mythtv/recordings/1171_20210212022900.ts". novideo(0)
2021-02-14 14:01:20.018980 I  VideoOutput: Allowed renderers for MPEG-2 ffmpeg (Decoder: ffmpeg): 'opengl,opengl-yv12'
2021-02-14 14:01:20.018999 I  VDP: Safe renderers for 'ffmpeg': null,opengl,opengl-yv12
2021-02-14 14:01:20.019010 I  VideoOutput: Allowed renderers (filt: ffmpeg): opengl,opengl-yv12
2021-02-14 14:01:20.020528 I  VDP: Accepting: cmp() w() h() framerate() codecs() dec(ffmpeg) cpus(1) skiploop(enabled) rend(opengl) deint(medium:shader,medium:shader)
2021-02-14 14:01:20.020553 I  VDP: LoadBestPreferences(1280x720, 59.940, nuv)
2021-02-14 14:01:20.020573 I  VDP: LoadBestPreferences result: priority:1 width: height: fps: codecs:
2021-02-14 14:01:20.020586 I  VDP: decoder:ffmpeg renderer:opengl deint0:medium:shader deint1:medium:shader cpus:1
2021-02-14 14:01:20.020598 I  VideoOutput: Preferred renderer: opengl
2021-02-14 14:01:20.020602 I  VideoOutput: Trying video renderer: 'opengl'
2021-02-14 14:01:20.027445 I  GLVid: Chroma upsampling filter disabled
2021-02-14 14:01:20.027479 I  Clearing OpenGL painter cache.
2021-02-14 14:01:20.028986 I  VideoWin: Window using screen DVI-0 1920x1080
2021-02-14 14:01:20.028997 I  VideoWin: Window Rect:  1920x1080+0+0
2021-02-14 14:01:20.029005 I  VideoWin: Display Rect: 1920x1080+0+0 Aspect: 1.77778
2021-02-14 14:01:20.029011 I  VideoWin: Video Rect:   1280x720+0+0 Aspect: 1.77778
2021-02-14 14:01:20.029029 I  ColourSpace: PictureAttributes: Brightness,Contrast,Colour,Hue,Range
2021-02-14 14:01:20.029044 I  VideoOutput: Display aspect ratio: 1.78061 (Detected)
2021-02-14 14:01:20.036642 I  Created 16 YUV420P (1280x720) video buffers
2021-02-14 14:01:20.038418 I  Player(0): Decoder thread starting.
2021-02-14 14:01:20.038433 I  AFD: DoFastForward(329 (0), do discard frames)
2021-02-14 14:01:20.254937 I  AFD: SeekReset(299, 30, do flush, do discard)
2021-02-14 14:01:20.254968 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA
2021-02-14 14:01:20.254991 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA -- done
2021-02-14 14:01:20.254998 I  AFD: SeekReset() flushing
2021-02-14 14:01:20.290792 I  AFD: gopset not set, syncing positionMap
2021-02-14 14:01:20.290802 I  Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2021-02-14 14:01:20.290805 I  AFD: Initial key frame distance: 15.
2021-02-14 14:01:20.339798 I  Player(0): ClearAfterSeek(0)
2021-02-14 14:01:20.350630 I  OSD: Loaded window osd_message
2021-02-14 14:01:20.353063 I  OSD: Loaded window osd_input
2021-02-14 14:01:20.365154 I  OSD: Loaded window program_info
2021-02-14 14:01:20.368436 I  OSD: Loaded window browse_info
2021-02-14 14:01:20.370992 I  OSD: Loaded window osd_status
2021-02-14 14:01:20.373512 I  OSD: Loaded window osd_program_editor
2021-02-14 14:01:20.384410 I  OSD: Loaded window osd_debug
2021-02-14 14:01:20.384421 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
2021-02-14 14:01:20.384496 I  OSD: Created window aa_OSD_SUBTITLES
2021-02-14 14:01:20.384530 I  Player(0): ClearAfterSeek(0)
2021-02-14 14:01:20.384557 I  VideoOutput: SetDeinterlacing (Doublerate 1): Single Medium|CPU|GLSL Double Medium|CPU|GLSL
2021-02-14 14:01:20.384568 I  Player(0): Display Refresh Rate: 59.999 Video Frame Rate: 59.941
2021-02-14 14:01:20.384576 I  Player(0): SetFrameInterval Interval:16683 Speed:1 Scan:Interlaced Scan (Multiplier: 1)
2021-02-14 14:01:20.384744 I  playCtx: StartPlaying(): took 0 ms to start player.
2021-02-14 14:01:20.384748 I  AFD: Key frame distance changed from 59 to 1.
2021-02-14 14:01:20.384766 I  TV::StartPlayer(): Created player.
2021-02-14 14:01:20.384829 I  TV::HandleStateChange(): Changing from None to WatchingPreRecorded
2021-02-14 14:01:20.385898 I  TV::HandleStateChange(): Main UI disabled.
2021-02-14 14:01:20.385937 I  TV::StartTV(): Entering main playback loop.
2021-02-14 14:01:20.390980 I  Player(0): Waiting for video buffers...
2021-02-14 14:01:20.397954 I  VideoOutput: Disabled all deinterlacing
2021-02-14 14:01:20.398025 W  GLVid: New frame format: None:None 1280x720 (Tex: 2D) -> YUV420P:YUY2 1280x720 (Tex: 2D)
2021-02-14 14:01:20.398215 I  GLVid: Created 1 input textures for 'opengl'
2021-02-14 14:01:20.398675 I  ColourSpace: Input : bt709(Guessed) Depth:8 Range:Limited
2021-02-14 14:01:20.398685 I  ColourSpace: Input : Primaries:bt709 Transfer: bt709
2021-02-14 14:01:20.398689 I  ColourSpace: Output: Range:Full Primaries: bt709
2021-02-14 14:01:20.402655 I  GLVid: Resizing from 1280x720 to 1920x1080 for Sampling
2021-02-14 14:01:20.405200 I  Player(0): AV Sync: Audio behind by 73 ms
2021-02-14 14:01:20.465226 I  Player(0): AV Sync: Audio behind by 80 ms
2021-02-14 14:01:20.771316 I  PreviewQueue: Requesting preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:20.771358 I  PreviewQueue: Requested preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:20.772896 I  Player(0): AV Sync: Audio behind by 77 ms
2021-02-14 14:01:20.788485 I  Player(0): AV Sync: Audio behind by 73 ms
2021-02-14 14:01:20.809976 I  Player(0): AV Sync: Audio behind by 68 ms
2021-02-14 14:01:20.831566 I  Player(0): AV Sync: Audio behind by 63 ms
2021-02-14 14:01:20.851940 I  Player(0): AV Sync: Audio behind by 61 ms
2021-02-14 14:01:20.873032 I  Player(0): AV Sync: Audio behind by 59 ms
2021-02-14 14:01:20.893942 I  Player(0): AV Sync: Audio behind by 54 ms
2021-02-14 14:01:20.913759 I  Player(0): AV Sync: Audio behind by 51 ms
2021-02-14 14:01:20.934202 I  Player(0): AV Sync: Audio behind by 48 ms
2021-02-14 14:01:20.954521 I  Player(0): AV Sync: Audio behind by 42 ms
2021-02-14 14:01:20.968683 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:20.973693 I  Player(0): AV Sync: Audio behind by 43 ms
2021-02-14 14:01:20.974217 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:20.993461 I  Player(0): AV Sync: Audio behind by 43 ms
2021-02-14 14:01:20.993730 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.013313 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.013401 I  Player(0): AV Sync: Audio behind by 40 ms
2021-02-14 14:01:21.031894 I  Player(0): AV Sync: Audio behind by 37 ms
2021-02-14 14:01:21.032915 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.051506 I  Player(0): AV Sync: Audio behind by 35 ms
2021-02-14 14:01:21.052473 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.071011 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.071095 I  Player(0): AV Sync: Audio behind by 32 ms
2021-02-14 14:01:21.089141 I  Player(0): AV Sync: Audio behind by 30 ms
2021-02-14 14:01:21.089526 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.108231 I  Player(0): AV Sync: Audio behind by 28 ms
2021-02-14 14:01:21.109189 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.127212 I  Player(0): AV Sync: Audio behind by 26 ms
2021-02-14 14:01:21.127689 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.145131 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.145222 I  Player(0): AV Sync: Audio behind by 24 ms
2021-02-14 14:01:21.163688 I  Player(0): AV Sync: Audio behind by 22 ms
2021-02-14 14:01:21.164746 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.182263 I  Player(0): AV Sync: Audio behind by 21 ms
2021-02-14 14:01:21.183355 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.201866 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.218306 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.236720 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.255316 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.271700 N  GetNextFreeFrame() served a busy frame B. Dropping. UUUUUUUUUUUUUUUU
2021-02-14 14:01:21.359399 I  TV::HandleStateChange(): Attempting to change from WatchingPreRecorded to None
2021-02-14 14:01:21.359443 I  Player(0): StopPlaying - begin
2021-02-14 14:01:21.360273 I  VidOutGL: (1): Uu(AU)UUUUUUUUUUUUU
2021-02-14 14:01:21.360401 I  VideoBuffers::DiscardFrames(1): Uu(AU)UUUUUUUUUUUUU
2021-02-14 14:01:21.361159 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA -- done
2021-02-14 14:01:21.361208 I  Player(0): Decoder thread exiting.
2021-02-14 14:01:21.361291 I  Player(0): Exited decoder loop.
2021-02-14 14:01:21.362378 I  VidOutGL: (1): AAAAAAAAAAAAAAAA
2021-02-14 14:01:21.362399 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA
2021-02-14 14:01:21.362433 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA -- done
2021-02-14 14:01:21.540567 I  Player(0): StopPlaying - end
2021-02-14 14:01:21.540593 I  TV::StopStuff(): Stopping ring buffer
2021-02-14 14:01:21.540713 I  TV::StopStuff(): Stopping player
2021-02-14 14:01:21.540742 I  TV::HandleStateChange(): Changing from WatchingPreRecorded to None
2021-02-14 14:01:21.540765 I  TV::StartTV(): Exiting main playback loop.
2021-02-14 14:01:21.540772 I  TV::StartTV(): -- process events 2 begin
2021-02-14 14:01:21.541041 I  TV::StartTV(): -- process events 2 end
2021-02-14 14:01:21.551326 I  TV::~TV(): -- lock
2021-02-14 14:01:21.551403 I  Player(0): StopPlaying - begin
2021-02-14 14:01:21.551426 I  Player(0): Exited decoder loop.
2021-02-14 14:01:21.551434 I  Player(0): StopPlaying - end
2021-02-14 14:01:21.552917 N  Resuming idle timer
2021-02-14 14:01:21.556035 I  PreviewQueue: Requesting preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:21.556087 I  PreviewQueue: Requested preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:21.746514 I  PreviewQueue: Requesting preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:21.746551 I  PreviewQueue: Requested preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:22.118616 I  PreviewQueue: Requesting preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:22.118655 I  PreviewQueue: Requested preview for '1171_20210212022900.ts_0x0_-1s'
2021-02-14 14:01:25.646922 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/remotecache
2021-02-14 14:01:25.647225 I  MythUIHelper: Kept 51 files, deleted 0 files, stat error on 0 files
2021-02-14 14:01:25.647250 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/thumbnails
2021-02-14 14:01:25.647828 I  MythUIHelper: Kept 121 files, deleted 0 files, stat error on 0 files
2021-02-14 14:01:25.649159 N  Resuming idle timer
2021-02-14 14:01:25.649196 N  Resuming idle timer
2021-02-14 14:01:25.651122 I  Bonjour: De-registering service '_mythfrontend._tcp.' on 'Mythfrontend on MythTv'
2021-02-14 14:01:25.659958 W  MythPainter: 45 images not yet de-allocated.
2021-02-14 14:01:25.659983 I  Clearing OpenGL painter cache.
2021-02-14 14:01:25.660551 I  OpenGL: MythRenderOpenGL closing
2021-02-14 14:01:25.665318 I  Display: Deleting
2021-02-14 14:01:25.666774 I  RAOP Device: Cleaning up.
2021-02-14 14:01:25.666795 I  AirPlay: Cleaning up.
2021-02-14 14:01:25.666812 I  Shutting down UPnP client...
2021-02-14 14:01:27.811936 I  PowerDBus: Closing interfaces
2021-02-14 14:01:27.812368 I  Waiting for threads to exit.
2021-02-14 14:01:30.708316 I  Exiting
2021-02-14 14:01:30.708726 I  Removing syslogging
Here is the log for a recording that is working.

Code: Select all

$ mythfrontend -v playback
"Display: Requesting EGL for 'Mesa Project, 1.5'"
2021-02-14 13:58:40.243457 I  Setup Interrupt handler
2021-02-14 13:58:40.243474 I  Setup Terminated handler
2021-02-14 13:58:40.243479 I  Setup Segmentation fault handler
2021-02-14 13:58:40.243484 I  Setup Aborted handler
2021-02-14 13:58:40.243488 I  Setup Bus error handler
2021-02-14 13:58:40.243493 I  Setup Floating point exception handler
2021-02-14 13:58:40.243498 I  Setup Illegal instruction handler
2021-02-14 13:58:40.243503 I  Setup Real-time signal 0 handler
2021-02-14 13:58:40.243508 I  Setup User defined signal 1 handler
2021-02-14 13:58:40.243512 I  Setup User defined signal 2 handler
2021-02-14 13:58:40.243516 I  Setup Hangup handler
2021-02-14 13:58:40.243602 C  mythfrontend version: fixes/31 [v31.0-v31.0] 
2021-02-14 13:58:40.243607 C  Qt version: compile: 5.12.5, runtime: 5.12.8
2021-02-14 13:58:40.243635 I  Ubuntu 20.04.2 LTS (x86_64)
2021-02-14 13:58:40.243638 N  Enabled verbose msgs:  general playback
2021-02-14 13:58:40.243649 N  Setting Log Level to LOG_INFO
2021-02-14 13:58:40.253888 I  Added logging to the console
2021-02-14 13:58:40.253921 N  Using runtime prefix = /usr
2021-02-14 13:58:40.253928 N  Using configuration directory = /home/dawson64/.mythtv
2021-02-14 13:58:40.253964 I  Assumed character encoding: en_US.UTF-8
2021-02-14 13:58:40.254259 I  Added syslogging
2021-02-14 13:58:40.254507 I  Loading en_us translation for module mythfrontend
2021-02-14 13:58:40.254782 I  Empty LocalHostName. This is typical.
2021-02-14 13:58:40.254791 I  Using a profile name of: 'MythTv' (Usually the same as this host's name.)
2021-02-14 13:58:40.254888 I  Start up testing connections. DB localhost, BE , attempt 0, status dbAwake, Delay: 2000
2021-02-14 13:58:41.273419 N  Setting QT default locale to en_US
2021-02-14 13:58:41.273432 I  Current locale en_US
2021-02-14 13:58:41.273493 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2021-02-14 13:58:41.282361 I  Display: Found screen number 0 (DVI-0)
2021-02-14 13:58:41.282384 I  Display: Using screen 'DVI-0' (Make: Panasonic Industry Company Model: Panasonic-TV-)
2021-02-14 13:58:41.282408 I  Display: Geometry: 1920x1080+0+0 Size(Qt): 698mmx392mm
2021-02-14 13:58:41.283696 I  DisplayX11: Ignoring interlaced mode 1920x1080 60.00i
2021-02-14 13:58:41.283706 I  DisplayX11: Ignoring interlaced mode 1920x1080 59.94i
2021-02-14 13:58:41.283719 I  DisplayX11: Ignoring interlaced mode 1440x480 59.94i
2021-02-14 13:58:41.283728 I  DisplayX11: Ignoring interlaced mode 720x480 60.00i
2021-02-14 13:58:41.283732 I  DisplayX11: Ignoring interlaced mode 720x480 59.94i
2021-02-14 13:58:41.283775 I  Display: Available modes:
2021-02-14 13:58:41.283809 I  1920x1080	60.00	59.94	24.00	23.98
2021-02-14 13:58:41.283817 I  1280x720	60.00	59.94
2021-02-14 13:58:41.283824 I  720x480	60.00	59.94
2021-02-14 13:58:41.283830 I  640x480	60.00	59.94
2021-02-14 13:58:41.284406 N  Display: Desktop video mode: 1920x1080 60.000Hz
2021-02-14 13:58:41.284412 N  Display: Display has custom colourspace
2021-02-14 13:58:41.293556 I  Starting process manager
2021-02-14 13:58:41.293637 I  Starting IO manager (read)
2021-02-14 13:58:41.295457 I  Starting IO manager (write)
2021-02-14 13:58:41.295539 I  Starting process signal handler
2021-02-14 13:58:41.345826 I  ScreenSaverX11Private: XScreenSaver support enabled
2021-02-14 13:58:41.346902 I  ScreenSaverX11Private: DPMS is disabled.
2021-02-14 13:58:41.489981 I  Power: On AC power
2021-02-14 13:58:41.490020 I  Power: Supported actions: Suspend,Restart,Shutdown
2021-02-14 13:58:41.495136 I  Listening on TCP 0.0.0.0:6547
2021-02-14 13:58:41.496003 I  Listening on TCP [::]:6547
2021-02-14 13:58:44.547113 I  Loading en_us translation for module mythfrontend
2021-02-14 13:58:44.550868 E  LIRC: Failed to connect to Unix socket '/dev/lircd'
			eno: No such file or directory (2)
2021-02-14 13:58:44.550895 I  No joystick configuration found, not enabling joystick control
2021-02-14 13:58:44.550899 I  UDPListener: Enabling
2021-02-14 13:58:44.551225 I  Binding to UDP 0.0.0.0:6948
2021-02-14 13:58:44.551297 I  Binding to UDP [::]:6948
2021-02-14 13:58:44.577084 I  Using Frameless Window
2021-02-14 13:58:44.577101 I  Using Full Screen Window
2021-02-14 13:58:44.595417 I  Display: Have main widget
2021-02-14 13:58:44.595425 I  Display: Have main window
2021-02-14 13:58:44.595695 I  Display: Found screen number 0 (DVI-0)
2021-02-14 13:58:44.596396 I  UI Screen Resolution: 1920 x 1080
2021-02-14 13:58:44.649849 I  OpenGL: OpenGL vendor        : X Org
2021-02-14 13:58:44.649854 I  OpenGL: OpenGL renderer      : AMD CAICOS (DRM 2.50.0 / 5.4.0-65-generic, LLVM 11.0.0)
2021-02-14 13:58:44.649857 I  OpenGL: OpenGL version       : 3.1 Mesa 20.2.6
2021-02-14 13:58:44.649861 I  OpenGL: Qt platform          : xcb
2021-02-14 13:58:44.649892 I  OpenGL: EGL display          : Yes
2021-02-14 13:58:44.649895 I  OpenGL: EGL images           : Yes
2021-02-14 13:58:44.649897 I  OpenGL: Qt OpenGL format     : OpenGL 3.1
2021-02-14 13:58:44.649899 I  OpenGL: Qt OpenGL surface    : RGBA: 8880 Depth: 0 Stencil: 0
2021-02-14 13:58:44.649901 I  OpenGL: Max texture size     : 16384
2021-02-14 13:58:44.649904 I  OpenGL: Max texture units    : 96
2021-02-14 13:58:44.649906 I  OpenGL: Shaders              : Yes
2021-02-14 13:58:44.649908 I  OpenGL: NPOT textures        : Yes
2021-02-14 13:58:44.649911 I  OpenGL: Multitexturing       : Yes
2021-02-14 13:58:44.649913 I  OpenGL: Rectangular textures : Yes
2021-02-14 13:58:44.649915 I  OpenGL: Buffer mapping       : Yes
2021-02-14 13:58:44.649917 I  OpenGL: Framebuffer objects  : Yes
2021-02-14 13:58:44.649919 I  OpenGL: 16bit framebuffers   : Yes
2021-02-14 13:58:44.649921 I  OpenGL: Unpack Subimage      : Yes
2021-02-14 13:58:44.649923 I  OpenGL: GL_RED/GL_R8         : Yes
2021-02-14 13:58:44.654781 I  OpenGL: Initialised MythRenderOpenGL
2021-02-14 13:58:44.654787 I  OpenGL: Using full range output
2021-02-14 13:58:44.655993 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/remotecache
2021-02-14 13:58:44.656260 I  MythUIHelper: Kept 51 files, deleted 0 files, stat error on 0 files
2021-02-14 13:58:44.656277 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/thumbnails
2021-02-14 13:58:44.656816 I  MythUIHelper: Kept 121 files, deleted 0 files, stat error on 0 files
2021-02-14 13:58:44.754654 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.64.2:6543 (try 1 of 1)
2021-02-14 13:58:44.755494 I  MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2021-02-14 13:58:44.817891 I  CECAdapter: Using physical address 1.0.0.0 from EDID
2021-02-14 13:58:44.969535 E  CECAdapter: Failed to find any CEC devices.
2021-02-14 13:58:45.469789 I  CECAdapter: Closing down CEC.
[AVHWDeviceContext @ 0x55dfceaa9280] Failed to initialise VAAPI connection: -1 (unknown libva error).
Cannot load libcuda_dot_so.1
2021-02-14 13:58:45.474230 I  Current MythTV Schema Version (DBSchemaVer): 1361
2021-02-14 13:58:45.488527 I  VDPAUHelp: Supported/available VDPAU decoders:
2021-02-14 13:58:45.492929 I  VDPAUHelp: MPEG2 Simple (Max size: 2048x1152)
2021-02-14 13:58:45.492937 I  VDPAUHelp: MPEG2 Main (Max size: 2048x1152)
2021-02-14 13:58:45.492942 I  VDPAUHelp: MPEG4 Simple (Max size: 2048x1152)
2021-02-14 13:58:45.492946 I  VDPAUHelp: MPEG4 Advanced Simple (Max size: 2048x1152)
2021-02-14 13:58:45.492951 I  VDPAUHelp: VC1 Simple (Max size: 2048x1152)
2021-02-14 13:58:45.492955 I  VDPAUHelp: VC1 Main (Max size: 2048x1152)
2021-02-14 13:58:45.492959 I  VDPAUHelp: VC1 Advanced (Max size: 2048x1152)
2021-02-14 13:58:45.492963 I  VDPAUHelp: H264 Baseline (Max size: 2048x1152)
2021-02-14 13:58:45.492967 I  VDPAUHelp: H264 Main (Max size: 2048x1152)
2021-02-14 13:58:45.492971 I  VDPAUHelp: H264 High (Max size: 2048x1152)
2021-02-14 13:58:45.492975 I  VDPAUHelp: H264 Constrained (Max size: 2048x1152)
2021-02-14 13:58:45.494008 E  MythCodecContext: Failed to create hardware device 'vaapi' Error 'Input/output error'
2021-02-14 13:58:45.494014 I  VAAPIDec: VAAPI functionality checked failed
2021-02-14 13:58:45.494130 E  NVDECInterop: Failed to load functions
2021-02-14 13:58:45.494144 I  NVDEC: No NVDEC decoders found
2021-02-14 13:58:45.494477 I  V4L2_M2M: No V4L2 decoders found
2021-02-14 13:58:45.494732 I  DRMPRIMECtx: DRM PRIME codecs supported: None 
2021-02-14 13:58:45.494768 I  VDP: decoder<->render support: ffmpeg      null opengl opengl-yv12
2021-02-14 13:58:45.494773 I  VDP: decoder<->render support: vdpau       opengl-hw
2021-02-14 13:58:45.494777 I  VDP: decoder<->render support: vdpau-dec   null opengl opengl-yv12
2021-02-14 13:58:45.500310 N  VDP: Ignoring profile 42 (decoder nvdec is not available)
2021-02-14 13:58:45.505521 N  VDP: Ignoring profile 44 (decoder v4l2 is not available)
2021-02-14 13:58:45.506734 N  VDP: Ignoring profile 32 (decoder vaapi is not available)
2021-02-14 13:58:45.688967 N  Registering Internal as a media playback plugin.
2021-02-14 13:58:45.690080 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2021-02-14 13:58:45.691179 W  MMUnix:UDisks2 service found. Media Monitor does not support this yet!
2021-02-14 13:58:45.695746 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1024
2021-02-14 13:58:45.706127 I  Loading en_us translation for module mythmusic
2021-02-14 13:58:45.707744 I  Listening on TCP 0.0.0.0:6546
2021-02-14 13:58:45.708185 I  Listening on TCP [::]:6546
2021-02-14 13:58:45.740715 N  Found mainmenu.xml for theme 'Mythbuntu'
2021-02-14 13:58:45.743010 I  Checking for theme updates every hour
2021-02-14 13:58:45.743205 I  Registering HouseKeeperTask 'HardwareProfiler'.
2021-02-14 13:58:45.744013 I  Starting HouseKeeper.
2021-02-14 13:58:45.850723 I  Bonjour: Service registration complete: name 'Mythfrontend on MythTv' type '_mythfrontend._tcp.' domain: 'local.'
2021-02-14 13:58:50.133350 I  TV::TV(): Creating TV object
2021-02-14 13:58:50.141249 I  TV::TV(): Finished creating TV object
2021-02-14 13:58:50.141287 N  Suspending idle timer
2021-02-14 13:58:50.143857 I  TV::Init(): Created TvPlayWindow.
2021-02-14 13:58:50.155969 I  TV::HandleStateChange(): Attempting to change from None to WatchingPreRecorded
2021-02-14 13:58:50.156671 I  FileRingBuf(/var/lib/mythtv/recordings/1411_20210213010000.ts): OpenFile(/var/lib/mythtv/recordings/1411_20210213010000.ts, 2000 ms)
2021-02-14 13:58:50.160144 I  TV::StartPlayer(): Elapsed time since TV constructor was called: 26 ms
2021-02-14 13:58:50.360888 N  AudioPlayer: Enabling Audio
2021-02-14 13:58:50.360910 I  Player(0): Opening '/var/lib/mythtv/recordings/1411_20210213010000.ts'
2021-02-14 13:58:50.366068 I  VDP: Accepting: cmp() w() h() framerate() codecs() dec(ffmpeg) cpus(1) skiploop(enabled) rend(opengl) deint(medium:shader,medium:shader)
2021-02-14 13:58:50.366551 I  AFD: PlayerFlags: 0xc0, AudioReadAhead: 100 msec
2021-02-14 13:58:50.366574 I  Player(0): Stretch Factor 1, allow passthru 
2021-02-14 13:58:50.367364 I  AFD: Buffer size: 32768 Streamed 0 Seekable 1 Available 327680
2021-02-14 13:58:50.373650 I  AFD: Stream #0: ID: 0x31 Codec ID: MPEG2VIDEO Type: Video(1280x720) Bitrate: 0
2021-02-14 13:58:50.373659 I  AFD: Stream #1: ID: 0x34 Codec ID: AC3 Type: Audio Bitrate: 384000
2021-02-14 13:58:50.373664 I  AFD: codec AC3 has 6 channels
2021-02-14 13:58:50.373666 I  AFD: Looking for decoder for AC3
2021-02-14 13:58:50.373850 I  AFD: Opened codec 0x55dfd0191840, id(AC3) type(Audio)
2021-02-14 13:58:50.374051 I  AFD: Stream #2: ID: 0x35 Codec ID: AC3 Type: Audio Bitrate: 192000
2021-02-14 13:58:50.374054 I  AFD: codec AC3 has 2 channels
2021-02-14 13:58:50.374057 I  AFD: Looking for decoder for AC3
2021-02-14 13:58:50.374214 I  AFD: Opened codec 0x55dfd0171380, id(AC3) type(Audio)
2021-02-14 13:58:50.374237 I  AFD: Trying to select best video track
2021-02-14 13:58:50.374258 I  AFD: Selected track #0: ID: 0x31 Codec ID: MPEG2VIDEO Profile: Main Type: Video(1280x720) Bitrate: 500000
2021-02-14 13:58:50.374297 I  AFD: Selected FPS: 59.9401 (Avg:59.9401 Mult:1 Codec:0 Container:90000 Estimated:59.9401)
2021-02-14 13:58:50.374327 I  VDP: LoadBestPreferences(1280x720, 59.940, mpeg2video)
2021-02-14 13:58:50.374343 I  VDP: LoadBestPreferences result: priority:1 width: height: fps: codecs:
2021-02-14 13:58:50.374349 I  VDP: decoder:ffmpeg renderer:opengl deint0:medium:shader deint1:medium:shader cpus:1
2021-02-14 13:58:50.374359 I  AFD: Using 1 CPUs for decoding
2021-02-14 13:58:50.374363 I  AFD: InitVideoCodec ID:MPEG2VIDEO Type:Video Size:1280x720
2021-02-14 13:58:50.374388 I  Player(0): SetFrameInterval Interval:16683 Speed:1 Scan:Progressive Scan (Multiplier: 1)
2021-02-14 13:58:50.374398 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2021-02-14 13:58:50.374429 I  AFD: Using ffmpeg for video decoding
2021-02-14 13:58:50.374443 I  AFD: Opened codec 0x55dfd0172e00, id(MPEG2VIDEO) type(Video)
2021-02-14 13:58:50.375151 I  AOBase: Opening audio device 'front:CARD=PCH,DEV=0' ch 2(6) sr 48000 sf signed 32 bit reenc 0
2021-02-14 13:58:50.376471 W  ALSA: Requested 500000us got 170666 buffer time
2021-02-14 13:58:50.376555 W  ALSA: Try to manually increase audio buffer with: echo 192 | sudo tee /proc/asound/card0/pcm0p/sub0/prealloc
2021-02-14 13:58:50.419250 E  ALSA: no playback control PCM found on mixer device default
2021-02-14 13:58:50.419281 E  ALSA: Unable to open audio mixer. Volume control disabled
2021-02-14 13:58:50.419408 I  Dec: Selected track #1 (type 2) in the Unknown language(0)
2021-02-14 13:58:50.419435 I  Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2021-02-14 13:58:50.466028 I  Dec: Position map filled from DB to: 219247
2021-02-14 13:58:50.476646 I  Dec: Duration map filled from DB to: 219247
2021-02-14 13:58:50.477270 I  Dec: SyncPositionMap prerecorded, from DB: 9267 entries
2021-02-14 13:58:50.477279 I  Dec: SyncPositionMap, new totframes: 219247, new length: 7315, posMap size: 9267
2021-02-14 13:58:50.478729 I  AFD: Position map found
2021-02-14 13:58:50.478736 I  AFD: Successfully opened decoder for file: "/var/lib/mythtv/recordings/1411_20210213010000.ts". novideo(0)
2021-02-14 13:58:50.480905 I  VideoOutput: Allowed renderers for MPEG-2 ffmpeg (Decoder: ffmpeg): 'opengl,opengl-yv12'
2021-02-14 13:58:50.480926 I  VDP: Safe renderers for 'ffmpeg': null,opengl,opengl-yv12
2021-02-14 13:58:50.480933 I  VideoOutput: Allowed renderers (filt: ffmpeg): opengl,opengl-yv12
2021-02-14 13:58:50.482115 I  VDP: Accepting: cmp() w() h() framerate() codecs() dec(ffmpeg) cpus(1) skiploop(enabled) rend(opengl) deint(medium:shader,medium:shader)
2021-02-14 13:58:50.482130 I  VDP: LoadBestPreferences(1280x720, 59.940, mpeg2video)
2021-02-14 13:58:50.482141 I  VDP: LoadBestPreferences result: priority:1 width: height: fps: codecs:
2021-02-14 13:58:50.482148 I  VDP: decoder:ffmpeg renderer:opengl deint0:medium:shader deint1:medium:shader cpus:1
2021-02-14 13:58:50.482156 I  VideoOutput: Preferred renderer: opengl
2021-02-14 13:58:50.482158 I  VideoOutput: Trying video renderer: 'opengl'
2021-02-14 13:58:50.487691 I  GLVid: Chroma upsampling filter disabled
2021-02-14 13:58:50.487725 I  Clearing OpenGL painter cache.
2021-02-14 13:58:50.489479 I  VideoWin: Window using screen DVI-0 1920x1080
2021-02-14 13:58:50.489492 I  VideoWin: Window Rect:  1920x1080+0+0
2021-02-14 13:58:50.489502 I  VideoWin: Display Rect: 1920x1080+0+0 Aspect: 1.77778
2021-02-14 13:58:50.489510 I  VideoWin: Video Rect:   1280x720+0+0 Aspect: 1.77778
2021-02-14 13:58:50.489528 I  ColourSpace: PictureAttributes: Brightness,Contrast,Colour,Hue,Range
2021-02-14 13:58:50.489548 I  VideoOutput: Display aspect ratio: 1.78061 (Detected)
2021-02-14 13:58:50.498333 I  Created 16 YUV420P (1280x720) video buffers
2021-02-14 13:58:50.499695 I  Player(0): Decoder thread starting.
2021-02-14 13:58:50.505988 I  OSD: Loaded window osd_message
2021-02-14 13:58:50.508345 I  OSD: Loaded window osd_input
2021-02-14 13:58:50.520382 I  OSD: Loaded window program_info
2021-02-14 13:58:50.524373 I  OSD: Loaded window browse_info
2021-02-14 13:58:50.527051 I  OSD: Loaded window osd_status
2021-02-14 13:58:50.529606 I  OSD: Loaded window osd_program_editor
2021-02-14 13:58:50.540304 I  OSD: Loaded window osd_debug
2021-02-14 13:58:50.540316 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
2021-02-14 13:58:50.540416 I  OSD: Created window aa_OSD_SUBTITLES
2021-02-14 13:58:50.540443 I  Player(0): ClearAfterSeek(0)
2021-02-14 13:58:50.540476 I  VideoOutput: SetDeinterlacing (Doublerate 1): Single Medium|CPU|GLSL Double Medium|CPU|GLSL
2021-02-14 13:58:50.540488 I  Player(0): Display Refresh Rate: 59.999 Video Frame Rate: 59.941
2021-02-14 13:58:50.540501 I  Player(0): SetFrameInterval Interval:16683 Speed:1 Scan:Interlaced Scan (Multiplier: 1)
2021-02-14 13:58:50.541413 I  playCtx: StartPlaying(): took 0 ms to start player.
2021-02-14 13:58:50.541431 I  TV::StartPlayer(): Created player.
2021-02-14 13:58:50.541486 I  TV::HandleStateChange(): Changing from None to WatchingPreRecorded
2021-02-14 13:58:50.542517 I  TV::HandleStateChange(): Main UI disabled.
2021-02-14 13:58:50.542561 I  TV::StartTV(): Entering main playback loop.
2021-02-14 13:58:50.546888 I  Player(0): Waiting for video buffers...
2021-02-14 13:58:50.565151 I  VideoOutput: Disabled all deinterlacing
2021-02-14 13:58:50.565235 W  GLVid: New frame format: None:None 1280x720 (Tex: 2D) -> YUV420P:YUY2 1280x720 (Tex: 2D)
2021-02-14 13:58:50.565422 I  GLVid: Created 1 input textures for 'opengl'
2021-02-14 13:58:50.565791 I  ColourSpace: Input : bt709(Guessed) Depth:8 Range:Limited
2021-02-14 13:58:50.565801 I  ColourSpace: Input : Primaries:bt709 Transfer: bt709
2021-02-14 13:58:50.565805 I  ColourSpace: Output: Range:Full Primaries: bt709
2021-02-14 13:58:50.569269 I  GLVid: Resizing from 1280x720 to 1920x1080 for Sampling
2021-02-14 13:58:50.581475 I  Player(0): AV Sync: Audio ahead by 170 ms
2021-02-14 13:58:50.598542 I  Player(0): AV Sync: Audio ahead by 155 ms
2021-02-14 13:58:50.608689 I  Player(0): AV Sync: Audio ahead by 141 ms
2021-02-14 13:58:50.616024 I  Player(0): AV Sync: Audio ahead by 133 ms
2021-02-14 13:58:50.624248 I  Player(0): AV Sync: Audio ahead by 124 ms
2021-02-14 13:58:50.637503 I  Player(0): AV Sync: Audio ahead by 120 ms
2021-02-14 13:58:50.654458 I  Player(0): AV Sync: Audio ahead by 121 ms
2021-02-14 13:58:50.670832 I  Player(0): AV Sync: Audio ahead by 121 ms
2021-02-14 13:58:50.687490 I  Player(0): AV Sync: Audio ahead by 120 ms
2021-02-14 13:58:50.704163 I  Player(0): AV Sync: Audio ahead by 120 ms
2021-02-14 13:58:50.720839 I  Player(0): Dropping frame: Video is behind by 34ms
2021-02-14 13:58:50.720899 I  Player(0): AV Sync: Audio ahead by 104 ms
2021-02-14 13:58:50.737440 I  Player(0): AV Sync: Audio ahead by 103 ms
2021-02-14 13:58:50.754127 I  Player(0): Dropping frame: Video is behind by 30ms
2021-02-14 13:58:50.754189 I  Player(0): AV Sync: Audio ahead by 87 ms
2021-02-14 13:58:50.770805 I  Player(0): AV Sync: Audio ahead by 87 ms
2021-02-14 13:58:50.787479 I  Player(0): AV Sync: Audio ahead by 87 ms
2021-02-14 13:58:50.804040 I  Player(0): Dropping frame: Video is behind by 30ms
2021-02-14 13:58:50.804101 I  Player(0): AV Sync: Audio ahead by 70 ms
2021-02-14 13:58:50.820745 I  Player(0): AV Sync: Audio ahead by 71 ms
2021-02-14 13:58:50.837423 I  Player(0): AV Sync: Audio ahead by 70 ms
2021-02-14 13:58:50.854276 I  Player(0): AV Sync: Audio ahead by 70 ms
2021-02-14 13:58:50.870785 I  Player(0): Dropping frame: Video is behind by 32ms
2021-02-14 13:58:50.870867 I  Player(0): AV Sync: Audio ahead by 54 ms
2021-02-14 13:58:50.887497 I  Player(0): AV Sync: Audio ahead by 53 ms
2021-02-14 13:58:50.904087 I  Player(0): AV Sync: Audio ahead by 54 ms
2021-02-14 13:58:50.920764 I  Player(0): AV Sync: Audio ahead by 54 ms
2021-02-14 13:58:50.937428 I  Player(0): Dropping frame: Video is behind by 30ms
2021-02-14 13:58:50.937489 I  Player(0): AV Sync: Audio ahead by 37 ms
2021-02-14 13:58:50.954166 I  Player(0): AV Sync: Audio ahead by 37 ms
2021-02-14 13:58:50.970820 I  Player(0): AV Sync: Audio ahead by 37 ms
2021-02-14 13:58:50.987479 I  Player(0): AV Sync: Audio ahead by 36 ms
2021-02-14 13:58:51.004745 I  Player(0): AV Sync: Audio ahead by 37 ms
2021-02-14 13:58:51.020760 I  Player(0): AV Sync: Audio ahead by 37 ms
2021-02-14 13:58:51.037481 I  Player(0): AV Sync: Audio ahead by 36 ms
2021-02-14 13:58:51.054187 I  Player(0): AV Sync: Audio ahead by 37 ms
2021-02-14 13:58:51.070876 I  Player(0): Dropping frame: Video is behind by 31ms
2021-02-14 13:58:51.270763 I  Player(0): Dropping frame: Video is behind by 30ms
2021-02-14 13:58:53.637517 I  Player(0): Dropping frame: Video is behind by 30ms
2021-02-14 13:58:53.855237 I  TV::HandleStateChange(): Attempting to change from WatchingPreRecorded to None
2021-02-14 13:58:53.855352 I  Player(0): StopPlaying - begin
2021-02-14 13:58:53.856863 I  VidOutGL: (1): UUUUUUUUUUUUuUuL
2021-02-14 13:58:53.856897 I  VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUuUuL
2021-02-14 13:58:53.856968 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA -- done
2021-02-14 13:58:53.857896 I  Player(0): Decoder thread exiting.
2021-02-14 13:58:53.857988 I  Player(0): Exited decoder loop.
2021-02-14 13:58:53.860867 I  VidOutGL: (1): AAAAAAAAAAAAAAAA
2021-02-14 13:58:53.860922 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA
2021-02-14 13:58:53.860969 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA -- done
2021-02-14 13:58:54.049451 I  Player(0): StopPlaying - end
2021-02-14 13:58:54.049463 I  TV::StopStuff(): Stopping ring buffer
2021-02-14 13:58:54.049586 I  TV::StopStuff(): Stopping player
2021-02-14 13:58:54.049611 I  TV::HandleStateChange(): Changing from WatchingPreRecorded to None
2021-02-14 13:58:54.049635 I  TV::StartTV(): Exiting main playback loop.
2021-02-14 13:58:54.049642 I  TV::StartTV(): -- process events 2 begin
2021-02-14 13:58:54.049931 I  TV::StartTV(): -- process events 2 end
2021-02-14 13:58:54.060189 I  TV::~TV(): -- lock
2021-02-14 13:58:54.060266 I  Player(0): StopPlaying - begin
2021-02-14 13:58:54.060289 I  Player(0): Exited decoder loop.
2021-02-14 13:58:54.060297 I  Player(0): StopPlaying - end
2021-02-14 13:58:54.062240 N  Resuming idle timer
2021-02-14 13:58:56.711579 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/remotecache
2021-02-14 13:58:56.712092 I  MythUIHelper: Kept 51 files, deleted 0 files, stat error on 0 files
2021-02-14 13:58:56.712132 I  MythUIHelper: Pruning cache directory: /home/dawson64/.mythtv/cache/thumbnails
2021-02-14 13:58:56.713354 I  MythUIHelper: Kept 121 files, deleted 0 files, stat error on 0 files
2021-02-14 13:58:56.715375 N  Resuming idle timer
2021-02-14 13:58:56.715440 N  Resuming idle timer
2021-02-14 13:58:56.718411 I  Bonjour: De-registering service '_mythfrontend._tcp.' on 'Mythfrontend on MythTv'
2021-02-14 13:58:56.730724 W  MythPainter: 45 images not yet de-allocated.
2021-02-14 13:58:56.730787 I  Clearing OpenGL painter cache.
2021-02-14 13:58:56.731360 I  OpenGL: MythRenderOpenGL closing
2021-02-14 13:58:56.737477 I  Display: Deleting
2021-02-14 13:58:56.739110 I  RAOP Device: Cleaning up.
2021-02-14 13:58:56.739144 I  AirPlay: Cleaning up.
2021-02-14 13:58:56.739164 I  Shutting down UPnP client...
2021-02-14 13:58:58.383243 I  PowerDBus: Closing interfaces
2021-02-14 13:58:58.383789 I  Waiting for threads to exit.
2021-02-14 13:59:01.078065 I  Exiting
2021-02-14 13:59:01.078471 I  Removing syslogging
I noticed the working recording has a MPEG2VIDEO codec
2021-02-14 13:58:50.373650 I AFD: Stream #0: ID: 0x31 Codec ID: MPEG2VIDEO Type: Video(1280x720) Bitrate: 0
But the other one does not.
2021-02-14 14:01:19.972450 I AFD: Stream #0: ID: 0x0 Codec ID: Unknown Codec ID Type: Video(1280x720) Bitrate: 0

I'm not sure why one would be different then the other or how to get the bad ones to work like the good ones. I think all my recording settings are the same so I'm not sure why one would be transcoded differently than another.

Thank you,
Dawson64
User avatar
heyted
Senior
Posts: 301
Joined: Sun Jun 08, 2014 2:14 am
Location: South Florida
Contact:
United States of America

Re: Playback choppy for some recordings after upgrade to v31

Post by heyted »

What graphics card are you using? Start Software & Updates, and click on the Additional Drivers tab. Are any proprietary drivers listed? Using the frontend Setup Wizard, have you tried testing all of the video playback profiles?
Ted | My blog
dawson64
Newcomer
Posts: 4
Joined: Sun Feb 14, 2021 6:46 pm
United States of America

Re: Playback choppy for some recordings after upgrade to v31

Post by dawson64 »

I think I could try to describe the issue better. I notice that Fox and sometimes CBS have the issue other channels seem to be just fine. When I play the recording, there is about a half second pause between each image, then it freezes on that image and another half second later it shows the next image. During that time the audio is playing just fine and the image loading is in sync with the audio.

I'm using a Radeon HD 6450 with a DVI to HDMI cable.

There is a proprietary audio driver listed: Intel Corporation 6 Series/C200 Series Chipset Family High Definition Audio Controller

I had tried the backend setup wizard to test some of the profiles. The frontend made it a lot easier.
I tried all of the profiles with the problematic recording and nothing seemed to help.
I tested the profiles with the high def sample video.
The VDPAU Profiles didn't work very well with the sample.
The rest seemed to play fine: High Quality, Normal, NVDEC Normal, the OpenGL profiles, Slim, V4L2, VAAPI.

Thanks for your time!
User avatar
heyted
Senior
Posts: 301
Joined: Sun Jun 08, 2014 2:14 am
Location: South Florida
Contact:
United States of America

Re: Playback choppy for some recordings after upgrade to v31

Post by heyted »

I'd try the proprietary driver. It appears the issue is not MythTV related. If no one here can help, you may want to ask over on the Ubuntu forum.
Ted | My blog
User avatar
heyted
Senior
Posts: 301
Joined: Sun Jun 08, 2014 2:14 am
Location: South Florida
Contact:
United States of America

Re: Playback choppy for some recordings after upgrade to v31

Post by heyted »

An old mailing list thread that may help:
https://lists.archive.carbon60.com/myth ... dec;#47911
Ted | My blog
User avatar
kmdewaal
Developer
Posts: 644
Joined: Wed Dec 07, 2016 8:01 pm
Netherlands

Re: Playback choppy for some recordings after upgrade to v31

Post by kmdewaal »

I think this is not a playback problem but a recording problem because of the AFD video codec message and because VLC cannot play it as well.
Before digging deeper the first thing to do is to make sure the configuration of tuner cards etc is correct by redoing that part of the installation.
This can be done as follows:
- create a new video source
- delete all capture cards
- create new capture card(s)
- input connection: connect capture cards to new video source
- scan for channels
- if you have two capture cards then do a scan on each capture card to make sure that it works
It could just be that now everything works. If not, we can dig deeper.

The Wiki has the details about the channel scanning in https://www.mythtv.org/wiki/Channel_Scanning
dawson64
Newcomer
Posts: 4
Joined: Sun Feb 14, 2021 6:46 pm
United States of America

Re: Playback choppy for some recordings after upgrade to v31

Post by dawson64 »

VLC shows a good recording is using MPEG-1/2 Video (mpgv) and A52 Audio (aka AC3) (a52).
The bad video playback is using Nuppel Video (RJPG) and MPEG Audio layer 1/2 (mpga) (audio works fine). I was able to use VLC to convert this to mpeg and play it back in VLC to watch this recording.

It looked like the proprietary driver was enabled for the HDA audio. I tried disabling it but it didn't make any difference in the playback.

I noticed that the recordings having an issue had transcoding turned on under post processing. Other recordings on the same channel had that turned off and appeared fine. I will run a recording test tonight with transcoding on & then transcoding off to see if that helps.

@kmdewall - thanks for the idea! I will check out redoing the tuner cards tonight or tomorrow. I have another HD Homerun that isn't hooked up, so I could also try adding that.
User avatar
kmdewaal
Developer
Posts: 644
Joined: Wed Dec 07, 2016 8:01 pm
Netherlands

Re: Playback choppy for some recordings after upgrade to v31

Post by kmdewaal »

If I understand it correct now, this means that the recordings that fail to play are all from the analog days and recorded the Nuppel Video format.
So there is no issue with new recordings.
Support for the Nuppel Video format is fading away... I suggest that you convert all the Nuppel Video recordings that you care about to a more popular format.
dawson64
Newcomer
Posts: 4
Joined: Sun Feb 14, 2021 6:46 pm
United States of America

Re: Playback choppy for some recordings after upgrade to v31

Post by dawson64 »

My test recordings is helpful, but I don't fully understand why new recordings are using that old format.
I recorded 4 shows from FOX last night with post processing for Transcoding off & on. The 2 shows with transcoding on do not play well at all. The 2 shows with transcoding off play just fine. The transcoder command is set to 'mythtranscode'. I recall trying to figure out how to use transcoding so there is a chance I could have changed the transcoding process over the years.

I'm not sure if I should try to fix the transcoder or just change the backend so transcoding is not allowed. I may have to try to research this again and see if I can set the transcoding settings back to default or something that saves space.

I'll go through and make sure transcoding is off for my upcoming recordings.
I haven't tested the capture cards but I'm not sure I need to at this point.

Thank you for your ideas! They helped me get unstuck.
Post Reply