[Solved] Upgraded 0.27 -> 0.28. LiveTV Playback drops frames

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

Moderator: Forum Moderators

Post Reply
harpax
Newcomer
Posts: 12
Joined: Mon Feb 10, 2014 11:47 pm
Canada

[Solved] Upgraded 0.27 -> 0.28. LiveTV Playback drops frames

Post by harpax » Mon Jan 11, 2016 2:06 am

I just recently upgraded my MythTV system from Fedora 21 to Fedora 23 (Clean install) as well us upgrading MythTV from 0.27 to 0.28-pre. I did the MythTV upgrade at the same time as I know 0.28 is expected to be released very soon and I heard the master branch is quite stable at the moment.

The only issue I'm having with 0.28 that I have been unable to solve is frame drops during playback with LiveTV. I use an HD-PVR (v1) for recording and although livetv is watchable, the frequent dropped frames cause the playback to be slightly choppy. Watching LiveTV with verbose playback logging on mythfrontend reveals where the frames are getting dropped (see below). I've tried multiple playback profiles, both hardware and software, low quality and high quality - it makes no difference. I've also played with some audio settings and HD-PVR settings to no avail. This was not an issue with F21 & 0.27 nor are there any issues in 0.28 with playback of recordings or videos (both work just fine) - the issue seems specific to LiveTV.

I've pasted the output of "mythfrontend -v playback" below for hardware (VAAPI) playback. The hardware is a core i7 w/ Intel HD 5500. VAAPI compiled in. Any suggestions? I don't know why A/V sync would be falling behind that it thinks it needs to drop frames to maintain it (which is my best guess at what is going on here). The hardware & setup has not changed just the software (OS and MythTV).

Code: Select all

[mythtv@mythbox ~]$ mythfrontend -v playback
2016-01-10 20:36:24.501098 I  Setup Interrupt handler
2016-01-10 20:36:24.501119 I  Setup Terminated handler
2016-01-10 20:36:24.501123 I  Setup Segmentation fault handler
2016-01-10 20:36:24.501126 I  Setup Aborted handler
2016-01-10 20:36:24.501129 I  Setup Bus error handler
2016-01-10 20:36:24.501132 I  Setup Floating point exception handler
2016-01-10 20:36:24.501135 I  Setup Illegal instruction handler
2016-01-10 20:36:24.501139 I  Setup Real-time signal 0 handler
2016-01-10 20:36:24.501145 I  Setup User defined signal 1 handler
2016-01-10 20:36:24.501148 I  Setup User defined signal 2 handler
2016-01-10 20:36:24.501229 C  mythfrontend version: master [v0.28-pre-3422-gab4c608-dirty] www.mythtv.org
2016-01-10 20:36:24.501235 C  Qt version: compile: 5.5.1, runtime: 5.5.1
2016-01-10 20:36:24.501237 N  Enabled verbose msgs:  general playback
2016-01-10 20:36:24.501249 N  Setting Log Level to LOG_INFO
2016-01-10 20:36:24.511685 N  Using runtime prefix = /usr/local
2016-01-10 20:36:24.511703 N  Using configuration directory = /home/mythtv/.mythtv
2016-01-10 20:36:24.511734 I  Added logging to the console
2016-01-10 20:36:24.511771 I  Assumed character encoding: en_US.UTF-8
2016-01-10 20:36:24.512086 N  Empty LocalHostName.
2016-01-10 20:36:24.512091 I  Using localhost value of mythbox.sejr.ca
2016-01-10 20:36:24.517875 N  Setting QT default locale to EN_US
2016-01-10 20:36:24.517887 I  Current locale EN_US
2016-01-10 20:36:24.517917 N  Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2016-01-10 20:36:24.521553 I  ScreenSaverDBus: Created for DBus service: org.freedesktop.ScreenSaver
2016-01-10 20:36:24.522085 I  ScreenSaverDBus: Created for DBus service: org.freedesktop.PowerManagement.Inhibit
2016-01-10 20:36:24.522406 W  ScreenSaverDBus: Could not connect to dbus: The name org.mate.SessionManager was not provided by any .service files
2016-01-10 20:36:24.522679 W  ScreenSaverDBus: Could not connect to dbus: The name org.gnome.SessionManager was not provided by any .service files
2016-01-10 20:36:24.522874 I  Starting process manager
2016-01-10 20:36:24.522903 I  Starting process signal handler
2016-01-10 20:36:24.523869 I  Starting IO manager (write)
2016-01-10 20:36:24.523919 I  Starting IO manager (read)
2016-01-10 20:36:24.573936 I  ScreenSaverX11Private: DPMS is active.
2016-01-10 20:36:24.586051 N  Desktop video mode: 1920x1080 60.000 Hz
2016-01-10 20:36:24.617599 I  max_width: 1920 max_height: 1080
2016-01-10 20:36:24.654563 I  Listening on TCP 127.0.0.1:6547
2016-01-10 20:36:24.654592 I  Listening on TCP [::1]:6547
2016-01-10 20:36:24.654659 I  Listening on TCP [fe80::feaa:14ff:feda:d825%eth0]:6547
2016-01-10 20:36:25.584238 N  QT_QPA_PLATFORM=xcb
2016-01-10 20:36:25.588994 I  Loading en_us translation for module mythfrontend
2016-01-10 20:36:25.589735 N  Desktop video mode: 1920x1080 60.000 Hz
2016-01-10 20:36:25.592438 I  max_width: 1920 max_height: 1080
2016-01-10 20:36:25.592464 I  Using 1920x1080 60.000 Hz
2016-01-10 20:36:25.592470 I  SwitchToGUI: Switched to 1920x1080 60.000 Hz
2016-01-10 20:36:25.598400 I  LIRC: Successfully initialized '/run/lirc/lircd0' using '/home/mythtv/.lircrc' config
2016-01-10 20:36:25.598438 I  No joystick configuration found, not enabling joystick control
2016-01-10 20:36:25.598441 I  UDPListener: Enabling
2016-01-10 20:36:25.598766 I  Binding to UDP 127.0.0.1:6948
2016-01-10 20:36:25.598789 I  Binding to UDP [::1]:6948
2016-01-10 20:36:25.598822 I  Binding to UDP [fe80::feaa:14ff:feda:d825%eth0]:6948
2016-01-10 20:36:25.619692 I  Using Frameless Window
2016-01-10 20:36:25.619704 I  Using Full Screen Window
2016-01-10 20:36:25.628112 I  UI Screen Resolution: 1920 x 1080
2016-01-10 20:36:25.632677 W  OpenGL: Could not determine whether Sync to VBlank is enabled.
2016-01-10 20:36:25.635795 I  Trying the OpenGL 1.2 render
2016-01-10 20:36:25.636590 I  OpenGL painter using existing OpenGL context.
2016-01-10 20:36:25.643937 I  OpenGL1: Fragment program support available
2016-01-10 20:36:25.643972 I  OpenGL: OpenGL vendor  : Intel Open Source Technology Center
2016-01-10 20:36:25.643975 I  OpenGL: OpenGL renderer: Mesa DRI Intel(R) HD Graphics 5500 (Broadwell GT2) 
2016-01-10 20:36:25.643978 I  OpenGL: OpenGL version : 3.0 Mesa 11.1.0 (git-525f3c2)
2016-01-10 20:36:25.643982 I  OpenGL: Max texture size: 8192 x 8192
2016-01-10 20:36:25.643984 I  OpenGL: Max texture units: 8
2016-01-10 20:36:25.643992 I  OpenGL: Direct rendering: Yes
2016-01-10 20:36:25.643997 I  OpenGL: Extensions Supported: 1a1f
2016-01-10 20:36:25.643999 I  OpenGL: PixelBufferObject support available
2016-01-10 20:36:25.644001 I  OpenGL: Initialised MythRenderOpenGL
2016-01-10 20:36:25.790598 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 127.0.0.1:6543 (try 1 of 1)
2016-01-10 20:36:25.792449 I  MythCoreContext::CheckProtoVersion(): Using protocol version 88 XmasGift
2016-01-10 20:36:25.932460 I  MythUIWebBrowser: Loading css from - file:///usr/local/share/mythtv/themes/default/htmls/mythbrowser.css
2016-01-10 20:36:25.933332 I  MythUIWebBrowser: enabling plugins
2016-01-10 20:36:25.958346 E  RAOP Conn: Failed to read key from: /home/mythtv/.mythtv/RAOPKey.rsa
2016-01-10 20:36:25.958358 E  RAOP Device: Aborting startup - no key found.
2016-01-10 20:36:25.958673 I  AirPlay: Created airplay objects.
2016-01-10 20:36:25.959701 I  Current MythTV Schema Version (DBSchemaVer): 1342
2016-01-10 20:36:25.965854 I  FilterManager: GetFilterInfo(greedyhdeint) returning: 0x2a162e0
2016-01-10 20:36:25.965863 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0x2ad8720
2016-01-10 20:36:25.966777 I  VDP: decoder<->render support: ffmpeg      null xlib xshm xv-blit opengl opengl-lite nullvaapi openglvaapi
2016-01-10 20:36:25.966785 I  VDP: decoder<->render support: vaapi       nullvaapi openglvaapi
2016-01-10 20:36:25.970737 N  VDP: Ignoring profile item 14 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,nullvaapi,openglvaapi))
2016-01-10 20:36:25.970778 N  VDP: Ignoring profile item 15 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,nullvaapi,openglvaapi))
2016-01-10 20:36:25.971517 N  VDP: Ignoring profile item 18 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,nullvaapi,openglvaapi))
2016-01-10 20:36:25.971548 N  VDP: Ignoring profile item 19 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,nullvaapi,openglvaapi))
2016-01-10 20:36:25.973869 N  VDP: Ignoring profile item 22 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,nullvaapi,openglvaapi))
2016-01-10 20:36:25.973900 N  VDP: Ignoring profile item 23 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,nullvaapi,openglvaapi))
2016-01-10 20:36:25.975102 N  VDP: Ignoring profile item 24 (decoder vdpau is not supported (supported: ffmpeg,vaapi))
2016-01-10 20:36:25.975601 N  VDP: Ignoring profile item 25 (decoder vdpau is not supported (supported: ffmpeg,vaapi))
2016-01-10 20:36:25.975629 N  VDP: Ignoring profile item 26 (decoder vdpau is not supported (supported: ffmpeg,vaapi))
2016-01-10 20:36:25.975870 I  Listening on TCP 127.0.0.1:5100
2016-01-10 20:36:25.975904 I  Listening on TCP [::1]:5100
2016-01-10 20:36:25.975956 I  Listening on TCP [fe80::feaa:14ff:feda:d825%eth0]:5100
2016-01-10 20:36:25.976144 N  VDP: Ignoring profile item 27 (decoder vdpau is not supported (supported: ffmpeg,vaapi))
2016-01-10 20:36:26.384102 E  RAOP Conn: Failed to read key from: /home/mythtv/.mythtv/RAOPKey.rsa
2016-01-10 20:36:26.421545 N  Registering Internal as a media playback plugin.
2016-01-10 20:36:26.422307 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:26.764138 I  Bonjour: Service registration complete: name 'MythTV on mythbox.sejr.ca' type '_airplay._tcp.' domain: 'local.'
2016-01-10 20:36:26.923000 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:27.423740 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:27.924663 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:28.425306 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:28.925942 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:29.426714 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:29.928294 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:30.428963 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:30.929618 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2016-01-10 20:36:31.438747 I  Loading en_us translation for module mytharchive
2016-01-10 20:36:31.441814 N  Registering WebBrowser as a media playback plugin.
2016-01-10 20:36:31.441857 E  Error Loading en_us translation for module mythbrowser
2016-01-10 20:36:31.449540 I  Loading en_us translation for module mythgallery
2016-01-10 20:36:31.452589 E  Error Loading en_us translation for module mythgame
2016-01-10 20:36:31.456583 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1023
2016-01-10 20:36:31.461929 I  Loading en_us translation for module mythmusic
2016-01-10 20:36:31.464071 I  Loading en_us translation for module mythnetvision
2016-01-10 20:36:31.466501 E  Error Loading en_us translation for module mythnews
2016-01-10 20:36:31.469391 E  Error Loading en_us translation for module mythweather
2016-01-10 20:36:31.469978 I  Listening on TCP 127.0.0.1:6546
2016-01-10 20:36:31.470000 I  Listening on TCP [::1]:6546
2016-01-10 20:36:31.470035 I  Listening on TCP [fe80::feaa:14ff:feda:d825%eth0]:6546
2016-01-10 20:36:31.530501 N  Found mainmenu.xml for theme 'Terra'
2016-01-10 20:36:31.532182 I  Checking for theme updates every hour
2016-01-10 20:36:31.532321 I  Registering HouseKeeperTask 'HardwareProfiler'.
2016-01-10 20:36:31.532885 I  Starting HouseKeeper.
2016-01-10 20:36:31.774907 I  Bonjour: Service registration complete: name 'Mythfrontend on mythbox.sejr.ca' type '_mythfrontend._tcp.' domain: 'local.'
2016-01-10 20:36:33.738478 I  TV::TV(): Creating TV object
2016-01-10 20:36:33.759862 I  TV::TV(): Finished creating TV object
2016-01-10 20:36:33.759873 N  Suspending idle timer
2016-01-10 20:36:33.763000 I  TV::Init(): Created TvPlayWindow.
2016-01-10 20:36:33.777132 I  TV::HandleStateChange(): Attempting to change from None to WatchingLiveTV
2016-01-10 20:36:33.777760 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: ::1:6543 (try 1 of 1)
2016-01-10 20:36:33.860450 I  LiveTVChain(live-mythbox.sejr.ca-2016-01-11T01:36:33Z): ReloadAll(): Added 1 recording(s)
2016-01-10 20:36:33.860473 I  LiveTVChain(live-mythbox.sejr.ca-2016-01-11T01:36:33Z): LiveTVChain has 1 entries
*  DUMMY: 1501 (01:36:33 to 03:00:00)

2016-01-10 20:36:33.862463 I  TV::HandleStateChange(): playbackURL(/mythtvmedia2/recordings/1501_20160111013633.ts) inputtype(DUMMY)
2016-01-10 20:36:33.862801 I  TV::StartRecorder(): Took 0 ms to start recorder.
2016-01-10 20:36:33.862838 I  TV::StartPlayer(): Elapsed time since TV constructor was called: 124 ms
2016-01-10 20:36:33.893055 N  AudioPlayer: Enabling Audio
2016-01-10 20:36:33.893099 I  Player(0): SetFrameInterval ps:1 scan:3
2016-01-10 20:36:33.893114 I  Player(0): detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2016-01-10 20:36:33.894099 I  Player(0): Stretch Factor 1, allow passthru 
2016-01-10 20:36:33.894566 I  VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,opengl-lite,openglvaapi
2016-01-10 20:36:33.894596 I  VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,opengl-lite,openglvaapi
2016-01-10 20:36:33.895451 I  VDP: Accepting: cmp(> 0 0) dec(vaapi) cpus(2) skiploop(enabled) rend(openglvaapi) osd(opengl2) osdfade(enabled) deint(vaapibobdeint,vaapionefield) filt()
2016-01-10 20:36:33.895479 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(opengldoubleratekerneldeint,openglkerneldeint) filt()
2016-01-10 20:36:33.895491 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-01-10 20:36:33.895508 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-01-10 20:36:33.895519 I  VDP: LoadBestPreferences(720x576, 60)
2016-01-10 20:36:33.895535 I  VideoOutput: Preferred renderer: openglvaapi
2016-01-10 20:36:33.895554 I  VideoOutput: Trying video renderer: 'openglvaapi'
2016-01-10 20:36:33.899291 I  VDP: Accepting: cmp(> 0 0) dec(vaapi) cpus(2) skiploop(enabled) rend(openglvaapi) osd(opengl2) osdfade(enabled) deint(vaapibobdeint,vaapionefield) filt()
2016-01-10 20:36:33.899311 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(opengldoubleratekerneldeint,openglkerneldeint) filt()
2016-01-10 20:36:33.899318 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-01-10 20:36:33.899329 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-01-10 20:36:33.899350 I  VideoOutWindow::SetPIPState. pip_state: 0]
2016-01-10 20:36:33.899353 I  VDP: LoadBestPreferences(2048x2048, 25)
2016-01-10 20:36:33.899364 I  Display Rect  left: 60, top: 0, width: 1800, height: 1080, aspect: 1.33333
2016-01-10 20:36:33.899369 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-01-10 20:36:33.899373 I  VDP: LoadBestPreferences(720x576, 25)
2016-01-10 20:36:33.899380 I  Display Rect  left: 60, top: 0, width: 1800, height: 1080, aspect: 1.33333
2016-01-10 20:36:33.899384 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-01-10 20:36:33.899387 I  VDP: SetVideoRenderer(openglvaapi)
2016-01-10 20:36:33.899391 I  VDP: SetVideoRender(openglvaapi) == GetVideoRenderer()
2016-01-10 20:36:33.904791 I  VidOutGL: Using main UI render context
2016-01-10 20:36:33.905284 I  Trying to match best refresh rate 25.000Hz
2016-01-10 20:36:33.905313 I  Changing to 1920x1080 24.000 Hz
2016-01-10 20:36:33.937939 I  SwitchToVideo: Video size 720 x 576: 
    Switched to displaying resolution 1920 x 1080, 508mm x 285mm
2016-01-10 20:36:33.938743 I  Display Rect  left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-01-10 20:36:33.938754 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-01-10 20:36:33.938777 I  VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2016-01-10 20:36:33.938784 I  VideoOutput: Actual display dimensions: 508x285 mm  Aspect: 1.78246
2016-01-10 20:36:33.938790 I  VideoOutput: Estimated window dimensions: 508x285 mm  Aspect: 1.78246
2016-01-10 20:36:33.938796 I  VidOutGL: Using main UI painter
2016-01-10 20:36:33.938806 I  Display Rect  left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-01-10 20:36:33.938813 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-01-10 20:36:33.939359 I  Over/underscan. V: 0, H: 0
2016-01-10 20:36:33.939371 I  Display Rect  left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-01-10 20:36:33.939379 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-01-10 20:36:33.939399 I  Player(0): LoadFilters(''..) -> 0x0
2016-01-10 20:36:33.942216 I  OSD: Loaded window osd_message
2016-01-10 20:36:33.944605 I  Player(0): Decoder thread starting.
2016-01-10 20:36:33.945024 I  OSD: Loaded window osd_input
2016-01-10 20:36:33.961569 I  OSD: Loaded window program_info
2016-01-10 20:36:33.965523 I  OSD: Loaded window browse_info
2016-01-10 20:36:33.967537 I  OSD: Loaded window osd_status
2016-01-10 20:36:33.969972 I  OSD: Loaded window osd_program_editor
2016-01-10 20:36:33.979416 I  OSD: Loaded window osd_debug
2016-01-10 20:36:33.979426 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
2016-01-10 20:36:33.979513 I  OSD: Created window aa_OSD_SUBTITLES
2016-01-10 20:36:33.979543 I  Player(0): ClearAfterSeek(0)
2016-01-10 20:36:34.059584 I  Player(0): Video timing method: DRM
2016-01-10 20:36:34.059601 I  Player(0): Display Refresh Rate: 24.000 Video Frame Rate: 25.000
2016-01-10 20:36:34.059611 I  Player(0): SetFrameInterval ps:1 scan:1
2016-01-10 20:36:34.101867 I  playCtx: StartPlaying(): took 0 ms to start player.
2016-01-10 20:36:34.101881 I  TV::StartPlayer(): Created player.
2016-01-10 20:36:34.101926 I  TV::HandleStateChange(): Changing from None to WatchingLiveTV
2016-01-10 20:36:34.101932 I  TV::HandleStateChange(): State is LiveTV & mctx == ctx
2016-01-10 20:36:34.102629 I  TV::HandleStateChange(): UpdateOSDInput done
2016-01-10 20:36:34.102636 I  TV::HandleStateChange(): UpdateLCD done
2016-01-10 20:36:34.102822 I  TV::HandleStateChange(): ITVRestart done
2016-01-10 20:36:34.103284 I  TV::HandleStateChange(): Main UI disabled.
2016-01-10 20:36:34.103303 I  TV::StartTV(): Entering main playback loop.
2016-01-10 20:36:34.120511 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.ScreenSaver. cookie 7714. nom nom
2016-01-10 20:36:34.120862 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.PowerManagement.Inhibit. cookie 10. nom nom
2016-01-10 20:36:34.121029 I  ScreenSaverX11Private: DPMS Deactivated 1
2016-01-10 20:36:45.350744 I  LiveTVChain(live-mythbox.sejr.ca-2016-01-11T01:36:33Z): ReloadAll(): Added 1 recording(s)
2016-01-10 20:36:45.350779 I  LiveTVChain(live-mythbox.sejr.ca-2016-01-11T01:36:33Z): LiveTVChain has 2 entries
*  DUMMY: 1501 (01:36:33 to 01:36:45)
   HDPVR: 1501 (01:36:45 to 03:00:00) discontinuous

2016-01-10 20:36:45.350860 I  LiveTVChain(live-mythbox.sejr.ca-2016-01-11T01:36:33Z): SwitchTo(1)
2016-01-10 20:36:45.350870 I  Player(0): JumpToProgram - start
2016-01-10 20:36:45.353210 I  Player(0): LoadFilters(''..) -> 0x0
2016-01-10 20:36:45.354786 I  FileRingBuf(/mythtvmedia2/recordings/1501_20160111013633.ts): OpenFile(/mythtvmedia3/recordings/1501_20160111013645.ts, 10000 ms)
2016-01-10 20:36:46.178664 I  Player(0): Stretch Factor 1, allow passthru 
2016-01-10 20:36:46.179562 I  AFD: Buffer size: 32768 streamed 1 seekable 1
libva info: VA-API version 0.38.1
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/i965_drv_video.so
libva info: Found init function __vaDriverInit_0_38
libva info: va_openDriver() returns 0
2016-01-10 20:36:46.277525 I  AFD: File successfully opened after 97ms
2016-01-10 20:36:46.277560 I  AFD: Stream #0, has id 0x1011 codec id H264, type Video, bitrate 0 at 0x700c760
2016-01-10 20:36:46.277565 I  AFD: Stream #1, has id 0x1100 codec id AC3, type Audio, bitrate 384000 at 0x703f180
2016-01-10 20:36:46.277568 I  AFD: codec AC3 has 6 channels
2016-01-10 20:36:46.277570 I  AFD: Looking for decoder for AC3
2016-01-10 20:36:46.277754 I  AFD: Opened codec 0x5b107a0, id(AC3) type(Audio)
2016-01-10 20:36:46.277907 I  AFD: Trying to select best video track
2016-01-10 20:36:46.277917 I  AFD: Selected track #0 (id 0x1011 codec id H264, type Video, bitrate 500000 at 0x700c760)
2016-01-10 20:36:46.278716 I  VDP: Accepting: cmp(> 0 0) dec(vaapi) cpus(2) skiploop(enabled) rend(openglvaapi) osd(opengl2) osdfade(enabled) deint(vaapibobdeint,vaapionefield) filt()
2016-01-10 20:36:46.278733 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(opengldoubleratekerneldeint,openglkerneldeint) filt()
2016-01-10 20:36:46.278740 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-01-10 20:36:46.278750 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-01-10 20:36:46.278757 I  VDP: LoadBestPreferences(1920x1080, 60)
2016-01-10 20:36:46.279923 I  VAAPI: Version: 0.38
2016-01-10 20:36:46.279931 I  VAAPI: Driver : Intel i965 driver for Intel(R) Broadwell - 1.6.2
2016-01-10 20:36:46.279935 I  VAAPI: Created VAAPI X11 display
2016-01-10 20:36:46.279950 I  VAAPI: Profile: MPEG2Simple Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279957 I  VAAPI: Profile: MPEG2Main Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279963 I  VAAPI: Profile: H264ConstrainedBase Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279967 I  VAAPI: Profile: H264Main Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279970 I  VAAPI: Profile: H264High Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279973 I  VAAPI: Profile: Unknown Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279976 I  VAAPI: Profile: H264StereoHigh Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.279979 I  VAAPI: Profile: VC1Simple Entrypoints: VLD 
2016-01-10 20:36:46.279982 I  VAAPI: Profile: VC1Main Entrypoints: VLD 
2016-01-10 20:36:46.279984 I  VAAPI: Profile: VC1Advanced Entrypoints: VLD 
2016-01-10 20:36:46.279988 I  VAAPI: Profile: None Entrypoints: VideoProc (UNSUPPORTED) 
2016-01-10 20:36:46.279991 I  VAAPI: Profile: Unknown Entrypoints: VLD 
2016-01-10 20:36:46.279993 I  VAAPI: Profile: Unknown Entrypoints: VLD 
2016-01-10 20:36:46.279996 I  VAAPI: Profile: Unknown Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.280000 I  VAAPI: Profile: H264StereoHigh Entrypoints: VLD EncSlice (UNSUPPORTED) 
2016-01-10 20:36:46.280003 I  VAAPI: Desired profile for 'H.264 VAAPI': H264High
2016-01-10 20:36:46.280006 I  VAAPI: Found profile H264High with entry VLD 
2016-01-10 20:36:46.280012 I  VAAPI: Created context (1920x1080->1920x1080)
2016-01-10 20:36:46.280152 I  VAAPI: Deleting VAAPI display.
2016-01-10 20:36:46.280156 I  VAAPI: Deleted context
2016-01-10 20:36:46.280168 I  AFD: Using 1 CPUs for decoding
2016-01-10 20:36:46.280174 I  AFD: InitVideoCodec() 0x706a3c0 id(H264) type (Video).
2016-01-10 20:36:46.280195 I  AFD: Selected FPS is 29.97 (avg 31.1331 codec 29.97 container 90000 estimated 29.97)
2016-01-10 20:36:46.280203 I  Player(0): SetFrameInterval ps:1 scan:3
2016-01-10 20:36:46.280848 I  VDP: Accepting: cmp(> 0 0) dec(vaapi) cpus(2) skiploop(enabled) rend(openglvaapi) osd(opengl2) osdfade(enabled) deint(vaapibobdeint,vaapionefield) filt()
2016-01-10 20:36:46.280865 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(opengldoubleratekerneldeint,openglkerneldeint) filt()
2016-01-10 20:36:46.280871 I  VDP: LoadBestPreferences(2048x2048, 0)
2016-01-10 20:36:46.280880 I  VDP: LoadBestPreferences(2048x2048, 60)
2016-01-10 20:36:46.280887 I  VDP: LoadBestPreferences(1920x1080, 60)
2016-01-10 20:36:46.280901 I  VDP: LoadBestPreferences(720x576, 29.97)
2016-01-10 20:36:46.280910 I  VidOutGLVAAPI: InputChanged(1920,1080,1.77778) None->H.264 VAAPI
2016-01-10 20:36:46.280917 I  Snapping height to avoid scaling: height: 1080, top: 0
2016-01-10 20:36:46.280920 I  Snapping width to avoid scaling: width: 1920, left: 0
2016-01-10 20:36:46.280924 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.280929 I  Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.280933 I  VDP: LoadBestPreferences(1920x1088, 29.97)
2016-01-10 20:36:46.280939 I  Snapping height to avoid scaling: height: 1080, top: 0
2016-01-10 20:36:46.280941 I  Snapping width to avoid scaling: width: 1920, left: 0
2016-01-10 20:36:46.280945 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.280949 I  Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.280952 I  VDP: SetVideoRenderer(openglvaapi)
2016-01-10 20:36:46.280955 I  VDP: SetVideoRender(openglvaapi) == GetVideoRenderer()
2016-01-10 20:36:46.280989 I  VidOutGL: Re-using context
2016-01-10 20:36:46.281308 I  Trying to match best refresh rate 29.970Hz
2016-01-10 20:36:46.281321 I  Changing to 1920x1080 60.000 Hz
libva info: VA-API version 0.38.1
libva info: va_getDriverName() returns 0
libva info: Trying to open /usr/lib64/dri/i965_drv_video.so
libva info: Found init function __vaDriverInit_0_38
libva info: va_openDriver() returns 0
2016-01-10 20:36:46.395143 I  SwitchToVideo: Video size 1920 x 1080: 
    Switched to displaying resolution 1920 x 1080, 508mm x 285mm
2016-01-10 20:36:46.395814 I  Snapping height to avoid scaling: height: 1080, top: 0
2016-01-10 20:36:46.395820 I  Snapping width to avoid scaling: width: 1920, left: 0
2016-01-10 20:36:46.395830 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.395836 I  Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.395848 I  VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2016-01-10 20:36:46.395854 I  VideoOutput: Actual display dimensions: 508x285 mm  Aspect: 1.78246
2016-01-10 20:36:46.395860 I  VideoOutput: Estimated window dimensions: 508x285 mm  Aspect: 1.78246
2016-01-10 20:36:46.395864 I  VidOutGL: Using main UI painter
2016-01-10 20:36:46.395896 I  GLVid: Viewport: 1920x1080
2016-01-10 20:36:46.395902 I  ColourSpace: PictureAttributes: 
2016-01-10 20:36:46.411212 I  GLVid: Creating resize filter.
2016-01-10 20:36:46.411236 I  GLVid: Using raw RGBA input textures.
2016-01-10 20:36:46.411249 I  GLVid: MMX: 1 PBO: 1
2016-01-10 20:36:46.411261 I  Snapping height to avoid scaling: height: 1080, top: 0
2016-01-10 20:36:46.411264 I  Snapping width to avoid scaling: width: 1920, left: 0
2016-01-10 20:36:46.411271 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.411276 I  Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-01-10 20:36:46.412182 I  VAAPI: Created VAAPI GLX display
2016-01-10 20:36:46.412191 I  VAAPI: Desired profile for 'H.264 VAAPI': H264High
2016-01-10 20:36:46.412194 I  VAAPI: Found profile H264High with entry VLD 
2016-01-10 20:36:46.412199 I  VAAPI: Created context (1920x1080->1920x1080)
2016-01-10 20:36:46.412232 I  VAAPI: Created 24 buffers
2016-01-10 20:36:46.412245 I  ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, 
2016-01-10 20:36:46.412570 I  Player(0): Refresh rate has changed from 41667 to 16667
2016-01-10 20:36:46.412579 I  Player(0): ClearAfterSeek(1)
2016-01-10 20:36:46.412597 I  Player(0): LoadFilters(''..) -> 0x0
2016-01-10 20:36:46.412607 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
2016-01-10 20:36:46.412612 I  Player(0): Enabled deinterlacing
2016-01-10 20:36:46.412665 I  AFD: Using vaapi for video decoding
2016-01-10 20:36:46.412748 I  AFD: Opened codec 0x706a3c0, id(H264) type(Video)
2016-01-10 20:36:46.412848 I  AOBase: Opening audio device 'hdmi:CARD=HDMI,DEV=0' ch 2(6) sr 48000 sf signed 16 bit reenc 0
2016-01-10 20:36:46.416359 I  Dec: Selected track #1 (type 2) in the Unknown language(0)
2016-01-10 20:36:46.416371 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2016-01-10 20:36:46.416947 I  Dec: Position map filled from DB to: 0
2016-01-10 20:36:46.416952 I  Dec: Duration map filled from DB to: 0
2016-01-10 20:36:46.416956 I  Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-01-10 20:36:46.416964 I  Player(0): Filling position map from 1 to end
2016-01-10 20:36:46.417436 I  Dec: Position map filled from Encoder to: 0
2016-01-10 20:36:46.417445 I  Dec: Duration map filled from Encoder to: 0
2016-01-10 20:36:46.417450 I  Dec: SyncPositionMap watchingrecording total: 1 entries
2016-01-10 20:36:46.417455 I  Dec: SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1
2016-01-10 20:36:46.417877 I  AFD: Partial position map found
2016-01-10 20:36:46.417881 I  AFD: Successfully opened decoder for file: "/mythtvmedia3/recordings/1501_20160111013645.ts". novideo(0)
2016-01-10 20:36:46.419202 I  Player(0): Play(  1.0, normal 1, unpause audio 1)
2016-01-10 20:36:46.419886 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2016-01-10 20:36:46.419892 I  Player(0): Play speed: rate: 29.97 speed: 1 skip: 1 => new interval 33366
2016-01-10 20:36:46.419906 I  Player(0): Stretch Factor 1, allow passthru 
2016-01-10 20:36:46.419920 I  Player(0): JumpToProgram - end
2016-01-10 20:36:46.419931 I  Player(0): Waiting for video buffers...
2016-01-10 20:36:46.420573 I  Dec: Position map filled from DB to: 0
2016-01-10 20:36:46.420580 I  Dec: Duration map filled from DB to: 0
2016-01-10 20:36:46.420584 I  Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-01-10 20:36:46.420591 I  Player(0): Filling position map from 1 to end
2016-01-10 20:36:46.421058 I  Dec: Position map filled from Encoder to: 0
2016-01-10 20:36:46.421063 I  Dec: Duration map filled from Encoder to: 0
2016-01-10 20:36:46.421067 I  Dec: SyncPositionMap watchingrecording total: 1 entries
2016-01-10 20:36:46.493980 I  Player(0): Enabled deinterlacing
2016-01-10 20:36:46.497349 I  VAAPI: Number of VAAPI GLX surfaces: 1
2016-01-10 20:36:46.526751 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:46.660832 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:46.794112 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:46.927446 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:47.094116 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:47.277478 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:47.410808 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:47.627367 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:47.760795 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:47.927498 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:48.110820 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:48.244137 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:48.460827 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:48.594139 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:48.760768 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:48.944149 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:49.110815 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:49.244147 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:49.427459 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:49.594144 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:49.777471 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:49.777790 I  Player(0): FPS:   30.76 Mean: 32504 Std.Dev: 19594 CPUs: 10% 8% 7% 7% 
2016-01-10 20:36:49.994101 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:50.127353 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:50.260727 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:50.477418 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:50.610803 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:50.777398 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:50.960814 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:51.094130 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:51.310773 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:51.444089 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:51.577388 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:51.794091 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:51.927456 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:52.094123 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:52.310779 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:52.444115 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:52.577423 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:52.794084 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:52.927333 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:53.110784 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:53.111041 I  Player(0): FPS:   30.00 Mean: 33328 Std.Dev: 20195 CPUs: 11% 10% 12% 3% 
2016-01-10 20:36:53.277436 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:53.410802 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:53.594051 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:53.810739 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:53.944041 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:54.077403 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:54.294108 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:54.427407 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:54.594111 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:54.777373 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:54.944054 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:55.110657 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:55.327296 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:55.460719 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:55.643989 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:55.777373 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:55.994070 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:56.127369 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:56.293982 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:56.461019 I  Player(0): FPS:   29.85 Mean: 33495 Std.Dev: 20029 CPUs: 15% 7% 15% 3% 
2016-01-10 20:36:56.477389 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:56.643974 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:56.777434 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:56.993918 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:57.143935 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:57.310752 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:57.494027 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:57.627363 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:57.793945 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:57.977414 I  Player(0): A/V predict drop frame, dropping frame to catch up.
2016-01-10 20:36:58.095281 I  TV::HandleStateChange(): Attempting to change from WatchingLiveTV to None
2016-01-10 20:36:58.095327 I  Player(0): StopPlaying - begin
2016-01-10 20:36:58.144649 I  Player(0): Decoder thread exiting.
2016-01-10 20:36:58.144749 I  Player(0): Exited decoder loop.
2016-01-10 20:36:58.155064 I  VAAPI: Deleting VAAPI display.
2016-01-10 20:36:58.155083 I  VAAPI: Deleted context
2016-01-10 20:36:58.155122 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAUUUUUUUUUUUUUUUP
2016-01-10 20:36:58.155162 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAP -- done
2016-01-10 20:36:58.155203 I  GLVid: Removing resize filter
2016-01-10 20:36:58.155258 I  Using 1920x1080 60.000 Hz
2016-01-10 20:36:58.155264 I  SwitchToGUI: Switched to 1920x1080 60.000 Hz
2016-01-10 20:36:58.598997 I  Player(0): StopPlaying - end
2016-01-10 20:36:58.599009 I  TV::StopStuff(): Stopping ring buffer
2016-01-10 20:36:58.604266 I  TV::StopStuff(): Stopping player
2016-01-10 20:36:58.604273 I  TV::StopStuff(): stopping recorder
2016-01-10 20:36:58.812478 I  TV::HandleStateChange(): Changing from WatchingLiveTV to None
2016-01-10 20:36:58.812703 I  TV::StartTV(): Exiting main playback loop.
2016-01-10 20:36:58.812712 I  TV::StartTV(): -- process events 2 begin
2016-01-10 20:36:58.812789 I  ScreenSaverDBus: Screensaver uninhibited via org.freedesktop.ScreenSaver
2016-01-10 20:36:58.812811 I  ScreenSaverDBus: Screensaver uninhibited via org.freedesktop.PowerManagement.Inhibit
2016-01-10 20:36:58.812959 I  ScreenSaverX11Private: DPMS Reactivated 1
2016-01-10 20:36:58.813147 I  TV::StartTV(): -- process events 2 end
2016-01-10 20:36:58.825237 I  TV::~TV(): -- lock
2016-01-10 20:36:58.826246 I  Player(0): StopPlaying - begin
2016-01-10 20:36:58.826260 I  Player(0): Exited decoder loop.
2016-01-10 20:36:58.826266 I  Player(0): StopPlaying - end
2016-01-10 20:36:58.828292 N  Resuming idle timer
2016-01-10 20:37:00.520869 N  Resuming idle timer
2016-01-10 20:37:00.520899 N  Resuming idle timer
2016-01-10 20:37:00.522279 I  Bonjour: De-registering service '_mythfrontend._tcp.' on 'Mythfrontend on mythbox.sejr.ca'
2016-01-10 20:37:00.522381 I  RAOP Device: Cleaning up.
2016-01-10 20:37:00.522387 I  AirPlay: Cleaning up.
2016-01-10 20:37:00.522469 I  Bonjour: De-registering service '_airplay._tcp.' on 'MythTV on mythbox.sejr.ca'
2016-01-10 20:37:00.522608 I  Shutting down UPnP client...
2016-01-10 20:37:01.933498 I  OpenGL1: Deleting OpenGL Resources
2016-01-10 20:37:01.933610 I  OpenGL: Deleting OpenGL Resources
2016-01-10 20:37:01.935843 I  Waiting for threads to exit.
Last edited by harpax on Mon Jan 11, 2016 3:24 am, edited 1 time in total.

harpax
Newcomer
Posts: 12
Joined: Mon Feb 10, 2014 11:47 pm
Canada

Re: Upgraded 0.27 -> 0.28-pre. LiveTV Playback drops frames

Post by harpax » Mon Jan 11, 2016 3:23 am

Got this figured out. I was reading though the source code and I found where the avsync predictor gets enabled in mythplayer.cpp:

avsync_predictor_enabled = ((frame_interval-(frame_interval/200)) < refreshrate);

I noticed this was a function of the refresh rate. It appears the default for KDE when F23 was installed was to set the refresh rate to 24Hz for my TV. This is great for Blu-Ray etc playback but not so good for 29.97fps video playback. Myth changes the refresh rate to 24 Hz anyway when a Blu-Ray is started and changes it to 60Hz when a video is started, but does not appear to change it when LiveTV is started. This was turning on the predictor and causing the problem (playing 29.97 fps video with a 24Hz refresh rate). I set the default refresh rate to "Auto" in KDE which set it to 60Hz and the issue is gone.

Kudos to the devs for making the source clear and easy to read!

Post Reply