Opened 3 years ago

Last modified 3 years ago

#12837 new Bug Report - General

livetv: looped image playback after channel/program change

Reported by: warpme@… Owned by: JYA
Priority: minor Milestone: unknown
Component: MythTV - Video Playback Version: Master Head
Severity: medium Keywords:
Cc: Ticket locked: no

Description

I have this issue since long time (I believe it appeared as 0.27 release times). I happens randomly (once per 10 or so livetv channel or program changes) and I can catch this only on Nvidia gfx

Symptoms:
when user is changing livetv channel - sometimes new tv channel playback is looped image. (see on link with video). Around 0:06 new channel should start to show its content - but instead old channel looped images are going.

Issue can be seen also on program transitions in livetv history: around 0:00:19 user asks to jump to livetv previous program. Around 0:00:21 old program is displayed; around 0:00:25 - after program transition there is again looped image

MOV video with issue: http://warped.homenet.org/looped-playback.mov

FE log looks like below. I'm ready to recompile FE with added logging/debugging code.

mythtv is current master. Distro is minimyth2.

2016-07-29 08:28:34.682568 I Setup Interrupt handler
2016-07-29 08:28:34.682777 I Setup Terminated handler
2016-07-29 08:28:34.682804 I Setup Segmentation fault handler
2016-07-29 08:28:34.682828 I Setup Aborted handler
2016-07-29 08:28:34.682853 I Setup Bus error handler
2016-07-29 08:28:34.682878 I Setup Floating point exception handler
2016-07-29 08:28:34.682902 I Setup Illegal instruction handler
2016-07-29 08:28:34.682932 I Setup Real-time signal 0 handler
2016-07-29 08:28:34.682965 I Setup User defined signal 1 handler
2016-07-29 08:28:34.682989 I Setup User defined signal 2 handler
2016-07-29 08:28:34.683012 I Setup Hangup handler
2016-07-29 08:28:34.683428 C mythfrontend version: master [v29-pre-132-gd43d8b1] www.mythtv.org
2016-07-29 08:28:34.683462 C Qt version: compile: 5.4.1, runtime: 5.4.1
2016-07-29 08:28:34.683475 N Enabled verbose msgs: general
2016-07-29 08:28:34.683547 N Setting Log Level to LOG_INFO
2016-07-29 08:28:34.695183 I Added logging to the console
2016-07-29 08:28:34.695270 N Using runtime prefix = /usr
2016-07-29 08:28:34.695286 N Using configuration directory = /home/minimyth/.mythtv
2016-07-29 08:28:34.695530 I Assumed character encoding: en_US.UTF-8
2016-07-29 08:28:34.698364 N Empty LocalHostName.
2016-07-29 08:28:34.698428 I Using localhost value of FE-Sleepingroom
2016-07-29 08:28:34.699016 I Testing network connectivity to \'192.168.1.254\'
2016-07-29 08:28:34.710023 I Starting IO manager (read)
2016-07-29 08:28:34.710074 I Starting IO manager (write)
2016-07-29 08:28:34.712779 I Starting process signal handler
2016-07-29 08:28:34.713210 I Starting process manager
2016-07-29 08:28:34.867936 N Setting QT default locale to pl_US
2016-07-29 08:28:34.868014 I Current locale pl_US
2016-07-29 08:28:34.868248 E No locale defaults file for pl_US, skipping
2016-07-29 08:28:34.914052 I ScreenSaverX11Private: XScreenSaver support enabled
2016-07-29 08:28:34.916127 I ScreenSaverX11Private: DPMS is disabled.
2016-07-29 08:28:34.965253 N Desktop video mode: 1920x1080 50.000 Hz
2016-07-29 08:28:35.954133 I Listening on TCP 127.0.0.1:6547
2016-07-29 08:28:35.954583 I Listening on TCP 192.168.1.128:6547
2016-07-29 08:28:36.113616 I Loading pl translation for module mythfrontend
2016-07-29 08:28:36.115230 N Desktop video mode: 1920x1080 50.000 Hz
2016-07-29 08:28:36.115656 I Using 1920x1080 50.000 Hz
2016-07-29 08:28:36.115686 I SwitchToGUI: Switched to 1920x1080 50.000 Hz
2016-07-29 08:28:36.127283 I LIRC: Successfully initialized \'/dev/lircd\' using \'/home/minimyth/.mythtv/lircrc\' config
2016-07-29 08:28:36.127402 I UDPListener: Enabling
2016-07-29 08:28:36.128021 I Binding to UDP 127.0.0.1:6948
2016-07-29 08:28:36.128134 I Binding to UDP 192.168.1.128:6948
2016-07-29 08:28:36.128225 I Binding to UDP 192.168.1.255:6948
2016-07-29 08:28:36.152401 I Using Frameless Window
2016-07-29 08:28:36.152419 I Using Full Screen Window
2016-07-29 08:28:36.193757 I UI Screen Resolution: 1920 x 1080
2016-07-29 08:28:36.365650 I OpenGL: Sync to VBlank is enabled (good!)
2016-07-29 08:28:36.440766 I Trying the OpenGL 2.0 render
2016-07-29 08:28:36.441225 I OpenGL painter using existing OpenGL context.
2016-07-29 08:28:36.486540 I OpenGL2: GLSL supported
2016-07-29 08:28:36.486689 I OpenGL: OpenGL vendor : NVIDIA Corporation
2016-07-29 08:28:36.486702 I OpenGL: OpenGL renderer: ION/PCIe/SSE2
2016-07-29 08:28:36.486713 I OpenGL: OpenGL version : 3.3.0 NVIDIA 340.96
2016-07-29 08:28:36.486730 I OpenGL: Max texture size: 8192 x 8192
2016-07-29 08:28:36.486741 I OpenGL: Max texture units: 4
2016-07-29 08:28:36.486752 I OpenGL: Direct rendering: Yes
2016-07-29 08:28:36.486788 I OpenGL: Extensions Supported: 1e3b
2016-07-29 08:28:36.486798 I OpenGL: PixelBufferObject support available
2016-07-29 08:28:36.490218 I OpenGL: Initialised MythRenderOpenGL
2016-07-29 08:28:40.073479 I MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.1.254:6543 (try 1 of 1)
2016-07-29 08:28:40.078193 I MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2016-07-29 08:28:40.873755 I MythUIWebBrowser: Loading css from - file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css
2016-07-29 08:28:40.877545 I MythUIWebBrowser: enabling plugins
2016-07-29 08:28:41.006708 I RAOP Device: Created RAOP device objects.
2016-07-29 08:28:41.006933 I AirPlay: Created airplay objects.
2016-07-29 08:28:41.007646 I Listening on TCP 127.0.0.1:5100
2016-07-29 08:28:41.007769 I Listening on TCP 192.168.1.128:5100
2016-07-29 08:28:41.008493 I Listening on TCP 127.0.0.1:5000
2016-07-29 08:28:41.008612 I Listening on TCP 192.168.1.128:5000
2016-07-29 08:28:41.008678 I RAOP Device: Listening for connections on port 5000
2016-07-29 08:28:41.016614 I Current MythTV Schema Version (DBSchemaVer): 1346
2016-07-29 08:28:41.029264 I Registering service 345e4e554c65@MythTV on FE-Sleepingroom._raop._tcp port 5000 TXT tp=UDPsm=falsesv=falseek=1et=0,1cn=0,1ch=2ss=16sr=44100pw=falsevn=3	txtvers=1md=0,1,2vs=115.2da=trueam=MythTV,1
2016-07-29 08:28:41.820834 N Registering Internal as a media playback plugin.
2016-07-29 08:28:41.841974 N Registering WebBrowser as a media playback plugin.
2016-07-29 08:28:41.842156 I Loading pl translation for module mythbrowser
2016-07-29 08:28:41.849486 I Loading pl translation for module mythgame
2016-07-29 08:28:41.878242 I Current MythMusic Schema Version (MusicDBSchemaVer): 1024
2016-07-29 08:28:41.883304 I Loading pl translation for module mythmusic
2016-07-29 08:28:41.888579 I Loading pl translation for module mythnetvision
2016-07-29 08:28:41.892683 I Loading pl translation for module mythnews
2016-07-29 08:28:41.897240 I Loading pl translation for module mythweather
2016-07-29 08:28:41.903618 I Connecting to zm server: 192.168.1.254:6548 (try 1 of 2)
2016-07-29 08:28:42.053270 I Bonjour: Service registration complete: name \'MythTV on FE-Sleepingroom\' type \'_airplay._tcp.\' domain: \'local.\'
2016-07-29 08:28:42.086278 I Bonjour: Service registration complete: name \'345e4e554c65@MythTV on FE-Sleepingroom\' type \'_raop._tcp.\' domain: \'local.\'
2016-07-29 08:28:42.907844 I Using protocol version 11
2016-07-29 08:28:42.948439 N Monitor: Salon (5) is using 3 bytes per pixel
2016-07-29 08:28:42.948889 I Loading pl translation for module mythzoneminder
2016-07-29 08:28:42.950187 I Listening on TCP 127.0.0.1:6546
2016-07-29 08:28:42.950305 I Listening on TCP 192.168.1.128:6546
2016-07-29 08:28:43.119233 N Found mainmenu.xml for theme \'Default\'
2016-07-29 08:28:43.120302 I Registering HouseKeeperTask \'HardwareProfiler\'.
2016-07-29 08:28:43.124364 I Starting HouseKeeper.
2016-07-29 08:28:43.687220 I Bonjour: Service registration complete: name \'Mythfrontend on FE-Sleepingroom\' type \'_mythfrontend._tcp.\' domain: \'local.\'
2016-07-29 08:28:46.154456 I TV::TV(): Creating TV object
2016-07-29 08:28:46.457623 N Suspending idle timer
2016-07-29 08:28:46.459464 I TV::Init(): Created TvPlayWindow.
2016-07-29 08:28:46.529972 I TV::HandleStateChange(): Attempting to change from None to WatchingLiveTV
2016-07-29 08:28:46.530040 I MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.1.254:6543 (try 1 of 1)
2016-07-29 08:28:48.389500 I TV::HandleStateChange(): playbackURL(myth://mythtv/23806_20160729062848.ts) inputtype(DUMMY)
2016-07-29 08:28:48.622346 N AudioPlayer: Enabling Audio
2016-07-29 08:28:48.625143 I Clearing OpenGL painter cache.
2016-07-29 08:28:48.628928 I Using 1920x1080 50.000 Hz
2016-07-29 08:28:48.693703 I VDPAU: Created 2 output surfaces.
2016-07-29 08:28:48.693757 I VDPAU: Version 1
2016-07-29 08:28:48.693779 I VDPAU: Information NVIDIA VDPAU Driver Shared Library 340.96 Sun Nov 8 22:09:55 PST 2015
2016-07-29 08:28:48.693809 I VDPAU: Created VDPAU render device 1920x1080
2016-07-29 08:28:49.130898 I Player(0): Video timing method: USleep with busy wait
2016-07-29 08:28:49.131279 I TV::StartPlayer(): Created player.
2016-07-29 08:28:49.131418 I TV::HandleStateChange(): Changing from None to WatchingLiveTV
2016-07-29 08:28:49.131443 I TV::HandleStateChange(): State is LiveTV & mctx == ctx
2016-07-29 08:28:49.136638 I TV::HandleStateChange(): UpdateOSDInput done
2016-07-29 08:28:49.136688 I TV::HandleStateChange(): UpdateLCD done
2016-07-29 08:28:49.139880 I TV::HandleStateChange(): ITVRestart done
2016-07-29 08:28:49.145981 I TV::HandleStateChange(): Main UI disabled.
2016-07-29 08:28:49.146064 I TV::StartTV(): Entering main playback loop.
2016-07-29 08:28:49.262487 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-07-29 08:28:52.886397 I AFD: codec MP2 has 2 channels
2016-07-29 08:28:52.886849 I AFD: Opened codec 0x2c43200, id(MP2) type(Audio)
2016-07-29 08:28:52.887040 I AFD: codec MP2 has 2 channels
2016-07-29 08:28:52.887404 I AFD: Opened codec 0x2cef440, id(MP2) type(Audio)
2016-07-29 08:28:52.887523 I AFD: Opened codec 0x3151f60, id(DVB_SUBTITLE) type(Subtitle)
2016-07-29 08:28:52.889801 W MythPainter: 41 images not yet de-allocated.
2016-07-29 08:28:52.889849 I VDPAU Painter: Clearing VDPAU painter cache.
2016-07-29 08:28:52.946280 I Clearing OpenGL painter cache.
2016-07-29 08:28:52.951012 I Using 1920x1080 50.000 Hz
2016-07-29 08:28:53.013170 I VDPAU: Created 2 output surfaces.
2016-07-29 08:28:53.013210 I VDPAU: Created VDPAU render device 1920x1080
2016-07-29 08:28:53.302787 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:28:53.303260 I AFD: Opened codec 0x2ce51a0, id(H264) type(Video)
2016-07-29 08:28:53.464794 I AOBase: Opening audio device \'hdmi:CARD=NVidia,DEV=1\' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2016-07-29 08:28:53.669953 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-07-29 08:28:53.809357 N Player(0): Waited 105ms for video buffers AAAAAAAAALLLLL
2016-07-29 08:28:53.849813 I VidOutVDPAU: Added 2 new buffers. New buffer size 16 (4 decode and 12 process)
2016-07-29 08:28:53.914989 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-07-29 08:30:01.366031 E RingBuf(myth://mythtv/23806_20160729063000.ts): RingBuffer::Reset() nonzero readpos. toAdjust: 1 readpos: 130692 readAdjust: 52154208
2016-07-29 08:33:04.947876 I Received notification \'TOK FM\', timeout 10
2016-07-29 08:33:04.948434 I Received notification \'CBS Action\', timeout 10
2016-07-29 08:33:04.948780 I Received notification \'Ale Kino+ HD\', timeout 10
2016-07-29 08:33:04.949192 I Received notification \'TVN24 HD (LiveTV)\', timeout 10
2016-07-29 08:33:04.998982 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.016903 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.032497 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.045207 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.061430 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.072278 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.082144 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:33:05.092127 N Custom notification style request: \'tuners\', will load: \'notification-image-tuners\'
2016-07-29 08:35:02.130097 I RingBuf(myth://mythtv/23806_20160729063000.ts): Checking to see if there\'s a new livetv program to switch to..
2016-07-29 08:35:02.133870 I RingBuf(myth://mythtv/23806_20160729063000.ts): Checking to see if there\'s a new livetv program to switch to..
2016-07-29 08:35:02.137088 E RingBuf(myth://mythtv/23806_20160729063000.ts): ReadPriv(..32768, normal) -- timed out waiting for data (3 ms)
2016-07-29 08:35:02.307584 E decoding error
eno: Unknown error 541478725 (541478725)
2016-07-29 08:35:02.340457 N Player(0): LiveTV forcing JumpTo 1
2016-07-29 08:35:02.340537 E decoding error
eno: Unknown error 541478725 (541478725)
2016-07-29 08:35:02.371649 E decoding error
eno: Unknown error 541478725 (541478725)
2016-07-29 08:35:02.530066 N Player(0): Waited 103ms for video buffers AAAFFLuLLAAAAAAA
2016-07-29 08:35:02.639152 N Player(0): Waited 103ms for video buffers AAAFFLdLLAAAAAAA
2016-07-29 08:35:02.741552 N Player(0): Waited 205ms for video buffers AAAFFLdLLAAAAAAA
2016-07-29 08:35:02.843980 N Player(0): Waited 307ms for video buffers AAAFFLdLLAAAAAAA
2016-07-29 08:37:14.118568 W RingBuf(myth://mythtv/12414_20160729063712.ts): Not starting read ahead thread, already running
2016-07-29 08:37:14.753099 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:37:15.225164 I AFD: codec MP2 has 2 channels
2016-07-29 08:37:15.225515 I AFD: Opened codec 0x4678940, id(MP2) type(Audio)
2016-07-29 08:37:15.227777 I AFD: Opened codec 0x476ca80, id(H264) type(Video)
2016-07-29 08:37:15.258440 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-07-29 08:37:15.489269 N Player(0): Waited 103ms for video buffers AAFAAAAAFAAAAAAA
2016-07-29 08:37:15.592334 N Player(0): Waited 206ms for video buffers AAFALLAAFAAALAAA
2016-07-29 08:38:02.666061 W RingBuf(myth://mythtv/8006_20160729063802.ts): Not starting read ahead thread, already running
2016-07-29 08:38:03.469993 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:38:03.625551 I AFD: codec MP2 has 2 channels
2016-07-29 08:38:03.625904 I AFD: Opened codec 0x4771080, id(MP2) type(Audio)
2016-07-29 08:38:03.625949 I AFD: codec MP2 has 2 channels
2016-07-29 08:38:03.626254 I AFD: Opened codec 0x2d68e00, id(MP2) type(Audio)
2016-07-29 08:38:03.626349 I AFD: Opened codec 0x2c65dc0, id(DVB_SUBTITLE) type(Subtitle)
2016-07-29 08:38:03.632497 W MythPainter: 13 images not yet de-allocated.
2016-07-29 08:38:03.632538 I VDPAU Painter: Clearing VDPAU painter cache.
2016-07-29 08:38:03.736599 I Clearing OpenGL painter cache.
2016-07-29 08:38:03.741914 I Using 1920x1080 50.000 Hz
2016-07-29 08:38:03.802925 I VDPAU: Created 2 output surfaces.
2016-07-29 08:38:03.802962 I VDPAU: Created VDPAU render device 1920x1080
2016-07-29 08:38:03.872219 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:38:03.872487 I AFD: Opened codec 0x4678940, id(MPEG2VIDEO) type(Video)
2016-07-29 08:38:03.968646 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-07-29 08:38:04.201703 N Player(0): Waited 104ms for video buffers AAAAAAAAAAAAAA
2016-07-29 08:38:04.442193 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-07-29 08:39:16.245221 W RingBuf(myth://mythtv/3104_20160729063915.ts): Not starting read ahead thread, already running
2016-07-29 08:39:16.537858 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:39:17.479916 I AFD: codec MP2 has 2 channels
2016-07-29 08:39:17.480269 I AFD: Opened codec 0x4678940, id(MP2) type(Audio)
2016-07-29 08:39:17.484739 W MythPainter: 9 images not yet de-allocated.
2016-07-29 08:39:17.484770 I VDPAU Painter: Clearing VDPAU painter cache.
2016-07-29 08:39:17.558125 I Clearing OpenGL painter cache.
2016-07-29 08:39:17.563034 I Using 1920x1080 50.000 Hz
2016-07-29 08:39:17.625136 I VDPAU: Created 2 output surfaces.
2016-07-29 08:39:17.625173 I VDPAU: Created VDPAU render device 1920x1080
2016-07-29 08:39:18.056695 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:39:18.057275 I AFD: Opened codec 0x2cebca0, id(H264) type(Video)
2016-07-29 08:39:18.137424 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-07-29 08:39:18.375888 N Player(0): Waited 105ms for video buffers AAAAAAAAAAAAAA
2016-07-29 08:39:18.451907 I VidOutVDPAU: Added 1 new buffers. New buffer size 15 (3 decode and 12 process)
2016-07-29 08:39:18.480892 N Player(0): Waited 210ms for video buffers AAAAAAAAALLLLAA
2016-07-29 08:39:18.577511 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-07-29 08:39:38.148277 W RingBuf(myth://mythtv/7370_20160729063936.ts): Not starting read ahead thread, already running
2016-07-29 08:39:38.953528 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-07-29 08:39:39.581067 I AFD: codec MP2 has 2 channels
2016-07-29 08:39:39.581418 I AFD: Opened codec 0x2cac960, id(MP2) type(Audio)
2016-07-29 08:39:39.583623 I AFD: Opened codec 0x4766740, id(H264) type(Video)
2016-07-29 08:39:39.614376 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-07-29 08:39:39.846761 N Player(0): Waited 103ms for video buffers AAAAAAAAAAAAAAA
2016-07-29 08:39:39.903676 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.904229 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.904653 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.904882 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.905086 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.905265 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.906140 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.906662 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.907263 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.907772 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.908088 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.908330 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.913588 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.913715 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.915078 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.915194 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.917559 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.917895 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.919879 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.920045 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.921396 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.921527 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.922303 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.922498 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.924797 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.925170 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.926577 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.926757 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.927554 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.927677 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.929708 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.929844 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.931345 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:39.931572 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:40.034293 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:40.034495 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:40.094902 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:40.095034 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-07-29 08:39:40.134558 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)

Change History (5)

comment:1 Changed 3 years ago by warped@…

I found channel change combination giving me 100% reproducible issue. Below is log from channel change across 4 channels and last change ended with looped playback.

2016-08-03 09:43:15.144767 I Setup Interrupt handler
2016-08-03 09:43:15.144927 I Setup Terminated handler
2016-08-03 09:43:15.144953 I Setup Segmentation fault handler
2016-08-03 09:43:15.144977 I Setup Aborted handler
2016-08-03 09:43:15.145001 I Setup Bus error handler
2016-08-03 09:43:15.145025 I Setup Floating point exception handler
2016-08-03 09:43:15.145048 I Setup Illegal instruction handler
2016-08-03 09:43:15.145077 I Setup Real-time signal 0 handler
2016-08-03 09:43:15.145110 I Setup User defined signal 1 handler
2016-08-03 09:43:15.145134 I Setup User defined signal 2 handler
2016-08-03 09:43:15.145156 I Setup Hangup handler
2016-08-03 09:43:15.145563 C mythfrontend version: master [v29-pre-132-gd43d8b1] www.mythtv.org
2016-08-03 09:43:15.145598 C Qt version: compile: 5.4.1, runtime: 5.4.1
2016-08-03 09:43:15.145611 N Enabled verbose msgs: general
2016-08-03 09:43:15.145683 N Setting Log Level to LOG_INFO
2016-08-03 09:43:15.157279 I Added logging to the console
2016-08-03 09:43:15.157346 N Using runtime prefix = /usr
2016-08-03 09:43:15.157365 N Using configuration directory = /home/minimyth/.mythtv
2016-08-03 09:43:15.157616 I Assumed character encoding: en_US.UTF-8
2016-08-03 09:43:15.159811 N Empty LocalHostName.
2016-08-03 09:43:15.159847 I Using localhost value of FE-Livingroom
2016-08-03 09:43:15.160159 I Testing network connectivity to \'192.168.1.254\'
2016-08-03 09:43:15.160939 I Starting process manager
2016-08-03 09:43:15.161227 I Starting process signal handler
2016-08-03 09:43:15.161257 I Starting IO manager (write)
2016-08-03 09:43:15.161257 I Starting IO manager (read)
2016-08-03 09:43:15.277305 N Setting QT default locale to pl_US
2016-08-03 09:43:15.277365 I Current locale pl_US
2016-08-03 09:43:15.277581 E No locale defaults file for pl_US, skipping
2016-08-03 09:43:15.362134 I ScreenSaverX11Private: XScreenSaver support enabled
2016-08-03 09:43:15.363622 I ScreenSaverX11Private: DPMS is disabled.
2016-08-03 09:43:15.397348 N Desktop video mode: 1920x1080 50.000 Hz
2016-08-03 09:43:15.597082 I Listening on TCP 127.0.0.1:6547
2016-08-03 09:43:15.597331 I Listening on TCP 192.168.1.129:6547
2016-08-03 09:43:15.784881 I Loading pl translation for module mythfrontend
2016-08-03 09:43:15.786483 N Desktop video mode: 1920x1080 50.000 Hz
2016-08-03 09:43:15.786837 I Using 1920x1080 50.000 Hz
2016-08-03 09:43:15.786864 I SwitchToGUI: Switched to 1920x1080 50.000 Hz
2016-08-03 09:43:15.796793 I LIRC: Successfully initialized \'/dev/lircd\' using \'/home/minimyth/.mythtv/lircrc\' config
2016-08-03 09:43:15.796923 I UDPListener: Enabling
2016-08-03 09:43:15.797462 I Binding to UDP 127.0.0.1:6948
2016-08-03 09:43:15.797561 I Binding to UDP 192.168.1.129:6948
2016-08-03 09:43:15.797641 I Binding to UDP 192.168.1.255:6948
2016-08-03 09:43:15.815013 I Using Frameless Window
2016-08-03 09:43:15.815025 I Using Full Screen Window
2016-08-03 09:43:15.849313 I UI Screen Resolution: 1920 x 1080
2016-08-03 09:43:15.996454 I OpenGL: Sync to VBlank is enabled (good!)
2016-08-03 09:43:16.134847 I Trying the OpenGL 2.0 render
2016-08-03 09:43:16.135369 I OpenGL painter using existing OpenGL context.
2016-08-03 09:43:16.184139 I OpenGL2: GLSL supported
2016-08-03 09:43:16.184288 I OpenGL: OpenGL vendor : NVIDIA Corporation
2016-08-03 09:43:16.184301 I OpenGL: OpenGL renderer: ION/PCIe/SSE2
2016-08-03 09:43:16.184312 I OpenGL: OpenGL version : 3.3.0 NVIDIA 340.96
2016-08-03 09:43:16.184328 I OpenGL: Max texture size: 8192 x 8192
2016-08-03 09:43:16.184339 I OpenGL: Max texture units: 4
2016-08-03 09:43:16.184350 I OpenGL: Direct rendering: Yes
2016-08-03 09:43:16.184386 I OpenGL: Extensions Supported: 1e3b
2016-08-03 09:43:16.184396 I OpenGL: PixelBufferObject support available
2016-08-03 09:43:16.188063 I OpenGL: Initialised MythRenderOpenGL
2016-08-03 09:43:19.759195 I MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.1.254:6543 (try 1 of 1)
2016-08-03 09:43:19.763317 I MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2016-08-03 09:43:20.226520 I MythUIWebBrowser: Loading css from - file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css
2016-08-03 09:43:20.229828 I MythUIWebBrowser: enabling plugins
2016-08-03 09:43:20.339491 I Current MythTV Schema Version (DBSchemaVer): 1346
2016-08-03 09:43:20.607729 N Registering Internal as a media playback plugin.
2016-08-03 09:43:20.623930 N Registering WebBrowser as a media playback plugin.
2016-08-03 09:43:20.624162 I Loading pl translation for module mythbrowser
2016-08-03 09:43:20.633291 I Loading pl translation for module mythgame
2016-08-03 09:43:20.674070 I Current MythMusic Schema Version (MusicDBSchemaVer): 1024
2016-08-03 09:43:20.679001 I Loading pl translation for module mythmusic
2016-08-03 09:43:20.686603 I Loading pl translation for module mythnetvision
2016-08-03 09:43:20.692517 I Loading pl translation for module mythnews
2016-08-03 09:43:20.697591 I Loading pl translation for module mythweather
2016-08-03 09:43:20.704224 I Connecting to zm server: 192.168.1.254:6548 (try 1 of 2)
2016-08-03 09:43:21.706699 I Using protocol version 11
2016-08-03 09:43:21.745116 N Monitor: Salon (5) is using 3 bytes per pixel
2016-08-03 09:43:21.745576 I Loading pl translation for module mythzoneminder
2016-08-03 09:43:21.746849 I Listening on TCP 127.0.0.1:6546
2016-08-03 09:43:21.746965 I Listening on TCP 192.168.1.129:6546
2016-08-03 09:43:21.964757 N Found mainmenu.xml for theme \'Default\'
2016-08-03 09:43:21.965824 I Registering HouseKeeperTask \'HardwareProfiler\'.
2016-08-03 09:43:21.967619 I Starting HouseKeeper.
2016-08-03 09:43:22.393323 I Bonjour: Service registration complete: name \'Mythfrontend on FE-Livingroom\' type \'_mythfrontend._tcp.\' domain: \'local.\'
2016-08-03 09:43:59.854715 I NetworkControl: New connection established.
2016-08-03 09:43:59.856330 N Verbose loglevel changed, new loglevel is: debug
2016-08-03 09:43:59.857409 I NetworkControl: Client Socket disconnected
2016-08-03 09:44:08.954494 I NetworkControl: New connection established.
2016-08-03 09:44:08.955885 N Verbose mask changed, new level is: general playback audio
2016-08-03 09:44:08.956588 I NetworkControl: Client Socket disconnected
2016-08-03 09:44:14.475108 I ScreenSaverX11Private: Calling xscreensaver-command -deactivate
2016-08-03 09:44:14.491864 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 0) called from UI thread
2016-08-03 09:44:14.499034 I TV::TV(): Creating TV object
2016-08-03 09:44:14.671213 I TV::TV(): Finished creating TV object
2016-08-03 09:44:14.671240 D TV::StartTV(): -- begin
2016-08-03 09:44:14.671264 N Suspending idle timer
2016-08-03 09:44:14.671322 D TV::Init(): -- begin
2016-08-03 09:44:14.671442 D TV::Init(): Hack for ticket12311: Re-Adjusted Screen size. Original: 1920x1200. New: 1920x1080
2016-08-03 09:44:14.672877 I TV::Init(): Created TvPlayWindow.
2016-08-03 09:44:14.674355 D TV::DrawUnusedRects(): -- begin
2016-08-03 09:44:14.674384 D TV::DrawUnusedRects(): -- end
2016-08-03 09:44:14.691605 D TV::Init(): -- end
2016-08-03 09:44:14.691786 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 0) called from UI thread
2016-08-03 09:44:14.696647 D TV::StartTV(): tv->LiveTV() -- begin
2016-08-03 09:44:14.696750 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 4294967295) called from UI thread
2016-08-03 09:44:14.700936 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_RECORDER_FROM_NUM,17) called from UI thread
2016-08-03 09:44:14.701622 D LiveTVChain(): ctor
2016-08-03 09:44:14.702636 D TV::HandleStateChange(): (0) -- begin
2016-08-03 09:44:14.702671 I TV::HandleStateChange(): Attempting to change from None to WatchingLiveTV
2016-08-03 09:44:14.702732 I MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.1.254:6543 (try 1 of 1)
2016-08-03 09:44:14.706114 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 17) called from UI thread
2016-08-03 09:44:14.712153 D TV::HandleStateChange(): Spawning LiveTV Recorder -- begin
2016-08-03 09:44:16.668667 D TV::HandleStateChange(): Spawning LiveTV Recorder -- end
2016-08-03 09:44:16.669631 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:16.669711 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 1 entries
* DUMMY: 23806 (07:44:16 to 07:45:00)

2016-08-03 09:44:16.675585 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_CHECKFILE,0...) called from UI thread
2016-08-03 09:44:16.676594 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_HOSTNAME) called from UI thread
2016-08-03 09:44:16.676978 I TV::HandleStateChange(): playbackURL(myth://mythtv/23806_20160803074416.ts) inputtype(DUMMY)
2016-08-03 09:44:16.677173 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_FILE_EXISTS,23806_20160803074416.ts) called from UI thread
2016-08-03 09:44:16.678110 I TV::StartRecorder(): Took 0 ms to start recorder.
2016-08-03 09:44:16.678147 D TV::StartPlayer(): (0, WatchingLiveTV, main) -- begin
2016-08-03 09:44:16.678238 I TV::StartPlayer(): Elapsed time since TV constructor was called: 2179 ms
2016-08-03 09:44:16.680219 D FilterManager: filters[adjust] = 0x2755830
2016-08-03 09:44:16.681129 D FilterManager: filters[bobdeint] = 0x266afa0
2016-08-03 09:44:16.682010 D FilterManager: filters[crop] = 0x2755ab0
2016-08-03 09:44:16.682820 D FilterManager: filters[denoise3d] = 0x2706670
2016-08-03 09:44:16.683585 D FilterManager: filters[fieldorderdoubleprocessdeint] = 0x2706320
2016-08-03 09:44:16.684318 D FilterManager: filters[forceyv12] = 0x2400e10
2016-08-03 09:44:16.684345 D FilterManager: filters[forceyuv422p] = 0x26c7f60
2016-08-03 09:44:16.684364 D FilterManager: filters[forcergb24] = 0x2748a10
2016-08-03 09:44:16.684381 D FilterManager: filters[forceargb32] = 0x2748b40
2016-08-03 09:44:16.685416 D FilterManager: filters[greedyhdeint] = 0x26c7f30
2016-08-03 09:44:16.685444 D FilterManager: filters[greedyhdoubleprocessdeint] = 0x2170b40
2016-08-03 09:44:16.686314 D FilterManager: filters[invert] = 0x2690440
2016-08-03 09:44:16.687095 D FilterManager: filters[ivtc] = 0x251fa00
2016-08-03 09:44:16.688730 D FilterManager: filters[kerneldeint] = 0x26c6e70
2016-08-03 09:44:16.688758 D FilterManager: filters[kerneldoubleprocessdeint] = 0x26b60a0
2016-08-03 09:44:16.689770 D FilterManager: filters[linearblend] = 0x26c6ea0
2016-08-03 09:44:16.690625 D FilterManager: filters[onefield] = 0x23cf610
2016-08-03 09:44:16.691658 D FilterManager: filters[postprocess] = 0x2699fc0
2016-08-03 09:44:16.692497 D FilterManager: filters[quickdnr] = 0x2d39870
2016-08-03 09:44:16.693281 D FilterManager: filters[vflip] = 0x26eb4d0
2016-08-03 09:44:16.694402 D FilterManager: filters[yadifdeint] = 0x241ce00
2016-08-03 09:44:16.694435 D FilterManager: filters[yadifdoubleprocessdeint] = 0x266c4e0
2016-08-03 09:44:16.812480 I ALSA: OpenDevice iec958:CARD=Intel,DEV=0
2016-08-03 09:44:16.822528 I AOS: Sample rate 44100 is supported
2016-08-03 09:44:16.822561 I AOS: Sample rate 48000 is supported
2016-08-03 09:44:16.822591 I AOS: Sample rate 96000 is supported
2016-08-03 09:44:16.822666 I AOS: Format signed 16 bit is supported
2016-08-03 09:44:16.822691 I AOS: Format signed 24 bit MSB is supported
2016-08-03 09:44:16.822704 I AOS: Format signed 32 bit is supported
2016-08-03 09:44:16.822758 I AOS: 2 channel(s) are supported
2016-08-03 09:44:16.874150 I AOS: may be AC3 or DTS capable
2016-08-03 09:44:16.874180 I AOS: 6 channel(s) are supported
2016-08-03 09:44:16.874330 N AudioPlayer: Enabling Audio
2016-08-03 09:44:16.874484 I Player(0): SetFrameInterval ps:1 scan:3
2016-08-03 09:44:16.874527 I Player(0): detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2016-08-03 09:44:16.874911 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:16.875005 I VideoOutput: Allowed renderers: opengl,opengl-lite,vdpau,openglvaapi
2016-08-03 09:44:16.875058 I VideoOutput: Allowed renderers (filt: dummy): opengl,opengl-lite,vdpau,openglvaapi
2016-08-03 09:44:16.875236 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:16.875267 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:16.875318 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:16.875371 I VDP: LoadBestPreferences(720x576, 60)
2016-08-03 09:44:16.875464 I VideoOutput: Preferred renderer: vdpau
2016-08-03 09:44:16.875513 I VideoOutput: Trying video renderer: \'vdpau\'
2016-08-03 09:44:16.877298 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2016-08-03 09:44:16.877333 D ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2016-08-03 09:44:16.877360 D ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2016-08-03 09:44:16.877383 D ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2016-08-03 09:44:16.877417 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2016-08-03 09:44:16.877443 D ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2016-08-03 09:44:16.877468 D ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2016-08-03 09:44:16.877492 D ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2016-08-03 09:44:16.877523 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 0
2016-08-03 09:44:16.877547 D ColourSpace: 1.1644 0.0000 1.3416 -0.7465
2016-08-03 09:44:16.877572 D ColourSpace: 1.1644 -0.3289 -0.6828 0.4348
2016-08-03 09:44:16.877595 D ColourSpace: 1.1644 1.6954 0.0000 -0.9241
2016-08-03 09:44:16.877627 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 0
2016-08-03 09:44:16.877652 D ColourSpace: 1.1644 0.0000 1.3416 -0.7465
2016-08-03 09:44:16.877682 D ColourSpace: 1.1644 -0.3289 -0.6828 0.4348
2016-08-03 09:44:16.877706 D ColourSpace: 1.1644 1.6954 0.0000 -0.9241
2016-08-03 09:44:16.877737 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 1
2016-08-03 09:44:16.877762 D ColourSpace: 1.0000 0.0000 1.1785 -0.5916
2016-08-03 09:44:16.877786 D ColourSpace: 1.0000 -0.2890 -0.5998 0.4461
2016-08-03 09:44:16.877809 D ColourSpace: 1.0000 1.4893 0.0000 -0.7476
2016-08-03 09:44:16.878008 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:16.878031 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:16.878071 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:16.878130 I VideoOutWindow::SetPIPState. pip_state: 0]
2016-08-03 09:44:16.878150 I VDP: LoadBestPreferences(2048x2048, 25)
2016-08-03 09:44:16.878203 I Clearing OpenGL painter cache.
2016-08-03 09:44:16.880274 I Display Rect left: 60, top: 0, width: 1800, height: 1080, aspect: 1.33333
2016-08-03 09:44:16.880306 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:16.880330 I VDP: LoadBestPreferences(720x576, 25)
2016-08-03 09:44:16.880385 I Display Rect left: 60, top: 0, width: 1800, height: 1080, aspect: 1.33333
2016-08-03 09:44:16.880408 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:16.880430 I VDP: SetVideoRenderer(vdpau)
2016-08-03 09:44:16.880447 I VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2016-08-03 09:44:16.881739 I Trying to match best refresh rate 25.000Hz
2016-08-03 09:44:16.881783 I Using 1920x1080 50.000 Hz
2016-08-03 09:44:16.881825 I SwitchToVideo: Video size 720 x 576: 
Already displaying resolution 1920 x 1080, 650mm x 365mm
2016-08-03 09:44:16.881849 I VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2016-08-03 09:44:16.881874 I VideoOutput: Actual display dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:16.881894 I VideoOutput: Estimated window dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:16.881952 I VidOutVDPAU: VDPAU Sharpen 1.00
2016-08-03 09:44:16.993937 I VDPAU: Created 2 output surfaces.
2016-08-03 09:44:16.993983 I VDPAU: Set colorkey to 0x20202
2016-08-03 09:44:16.994009 I VDPAU: Version 1
2016-08-03 09:44:16.994029 I VDPAU: Information NVIDIA VDPAU Driver Shared Library 340.96 Sun Nov 8 22:09:55 PST 2015
2016-08-03 09:44:16.994056 I VDPAU: HQ scaling level 1 of 9 available.
2016-08-03 09:44:16.994072 I VDPAU: MPEG4 hardware acceleration supported.
2016-08-03 09:44:16.994085 I VDPAU: HEVC hardware acceleration not supported.
2016-08-03 09:44:16.994100 I VDPAU: Created VDPAU render device 1920x1080
2016-08-03 09:44:16.994149 I VidOutVDPAU: Created VDPAU osd (1920x1080)
2016-08-03 09:44:17.023757 I ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels, 
2016-08-03 09:44:17.023790 I VidOutVDPAU: Using ITU BT.601 colorspace
2016-08-03 09:44:17.023857 I Display Rect left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-08-03 09:44:17.023882 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:17.023896 I VidOutVDPAU: Created VDPAU context (software decode)
2016-08-03 09:44:17.023934 I Over/underscan. V: 0, H: 0
2016-08-03 09:44:17.023960 I Display Rect left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-08-03 09:44:17.023984 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:17.024027 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:17.024085 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:17.024130 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:17.026230 I Player(0): Decoder thread starting.
2016-08-03 09:44:17.066070 I OSD: Loaded window osd_message
2016-08-03 09:44:17.079406 I OSD: Loaded window osd_input
2016-08-03 09:44:17.204528 I OSD: Loaded window program_info
2016-08-03 09:44:17.222298 I OSD: Loaded window browse_info
2016-08-03 09:44:17.268016 I OSD: Loaded window osd_status
2016-08-03 09:44:17.380637 I OSD: Loaded window osd_program_editor
2016-08-03 09:44:17.429732 I OSD: Loaded window osd_debug
2016-08-03 09:44:17.429781 I OSD: Loaded OSD: size 1920x1080 offset 0+0
2016-08-03 09:44:17.430109 I OSD: Created window aa_OSD_SUBTITLES
2016-08-03 09:44:17.430239 I Player(0): ClearAfterSeek(0)
2016-08-03 09:44:17.431913 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:44:17.434309 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:17.434458 E VSYNC: DRMVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2016-08-03 09:44:17.434595 E VSYNC: RTCVideoSync: Could not open /dev/rtc: 
eno: No such file or directory (2)
2016-08-03 09:44:17.435946 I Player(0): Video timing method: USleep with busy wait
2016-08-03 09:44:17.435993 I Player(0): Display Refresh Rate: 50.000 Video Frame Rate: 25.000
2016-08-03 09:44:17.436017 I Player(0): SetFrameInterval ps:1 scan:1
2016-08-03 09:44:17.436393 I playCtx: StartPlaying(): took 0 ms to start player.
2016-08-03 09:44:17.436435 I TV::StartPlayer(): Created player.
2016-08-03 09:44:17.436504 D TV::StartPlayer(): (0, WatchingLiveTV, main) -- end ok
2016-08-03 09:44:17.436597 I TV::HandleStateChange(): Changing from None to WatchingLiveTV
2016-08-03 09:44:17.436621 I TV::HandleStateChange(): State is LiveTV & mctx == ctx
2016-08-03 09:44:17.438886 I TV::HandleStateChange(): UpdateOSDInput done
2016-08-03 09:44:17.438931 I TV::HandleStateChange(): UpdateLCD done
2016-08-03 09:44:17.439682 I TV::HandleStateChange(): ITVRestart done
2016-08-03 09:44:17.445459 D TV::DrawUnusedRects(): -- begin
2016-08-03 09:44:17.445499 D TV::DrawUnusedRects(): -- end
2016-08-03 09:44:17.445654 I TV::HandleStateChange(): Main UI disabled.
2016-08-03 09:44:17.445674 D TV::HandleStateChange(): (0) -- end
2016-08-03 09:44:17.445728 D TV::StartTV(): tv->LiveTV() -- end
2016-08-03 09:44:17.445752 I TV::StartTV(): Entering main playback loop.
2016-08-03 09:44:17.451127 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_SG_FILEQUERY,mythtv...) called from UI thread
2016-08-03 09:44:17.505788 D ScreenSaverX11Private: ResetTimer -- begin
2016-08-03 09:44:17.505805 D ScreenSaverX11Private: StopTimer
2016-08-03 09:44:17.505866 D ScreenSaverX11Private: StartTimer
2016-08-03 09:44:17.505913 D ScreenSaverX11Private: ResetTimer -- end
2016-08-03 09:44:17.553070 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-08-03 09:44:17.608000 D TV::DrawUnusedRects(): -- begin
2016-08-03 09:44:17.608022 D TV::DrawUnusedRects(): -- end
2016-08-03 09:44:19.698603 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:19.698676 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 2 entries
* DUMMY: 23806 (07:44:16 to 07:44:19)
DVB: 23806 (07:44:19 to 07:45:00) discontinuous

2016-08-03 09:44:19.698793 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): JumpToNext: 1 -> 0
2016-08-03 09:44:19.698816 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(1)
2016-08-03 09:44:19.698904 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@1: \'23806_20160803074419\')
2016-08-03 09:44:19.698926 I Player(0): JumpToProgram - start
2016-08-03 09:44:19.702835 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 1 -> ProgramInfo(23806_20160803074419.ts): channame(TVN24 HD) startts(Wed Aug 3 07:35:00 2016 GMT) endts(Wed Aug 3 07:45:00 2016 GMT)
recstartts(Wed Aug 3 07:44:19 2016 GMT) recendts(Wed Aug 3 07:45:00 2016 GMT)
title(Wstajesz i wiesz)
2016-08-03 09:44:19.705752 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:19.705785 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:19.705827 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:19.705858 I AOBase: Pause 1
2016-08-03 09:44:19.707938 I FileRingBuf(myth://mythtv/23806_20160803074416.ts): OpenFile(myth://mythtv/23806_20160803074419.ts, 10000 ms)
2016-08-03 09:44:20.329945 D AFD: PlayerFlags: 0xc0
2016-08-03 09:44:20.329988 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:20.330014 D AFD: OpenFile -- begin
2016-08-03 09:44:20.330748 I AFD: Buffer size: 32768 streamed 1 seekable 1
2016-08-03 09:44:20.485335 D AFD: unspecified size
2016-08-03 09:44:20.485496 D AFD: Invalid streams found in ram data after 154ms, retrying in 50ms
2016-08-03 09:44:20.535640 I AFD: Buffer size: 32768 streamed 1 seekable 1
2016-08-03 09:44:20.720211 I AFD: File successfully opened after 389ms
2016-08-03 09:44:20.720399 I AFD: Stream #0, has id 0x217 codec id H264, type Video, bitrate 0 at 0x2e08ec0
2016-08-03 09:44:20.720426 I AFD: Stream #1, has id 0x352 codec id MP2, type Audio, bitrate 192000 at 0x3263280
2016-08-03 09:44:20.720442 I AFD: codec MP2 has 2 channels
2016-08-03 09:44:20.720453 I AFD: Looking for decoder for MP2
2016-08-03 09:44:20.720857 I AFD: Opened codec 0x2e10ba0, id(MP2) type(Audio)
2016-08-03 09:44:20.721083 I AFD: Audio Track #1, of type (Normalny) is A/V stream #1 (id=0x352) and has 2 channels in the Polish language(7368556).
2016-08-03 09:44:20.721111 I AFD: Stream #2, has id 0x353 codec id MP2, type Audio, bitrate 192000 at 0x2d1da00
2016-08-03 09:44:20.721126 I AFD: codec MP2 has 2 channels
2016-08-03 09:44:20.721137 I AFD: Looking for decoder for MP2
2016-08-03 09:44:20.721489 I AFD: Opened codec 0x2d642a0, id(MP2) type(Audio)
2016-08-03 09:44:20.721563 I AFD: Audio Track #2, of type (Audio Description) is A/V stream #2 (id=0x353) and has 2 channels in the Unknown language(7303783).
2016-08-03 09:44:20.721592 I AFD: Stream #3, has id 0x17d8 codec id DVB_SUBTITLE, type Subtitle, bitrate 0 at 0x2dfa900
2016-08-03 09:44:20.721605 I AFD: subtitle codec (Subtitle)
2016-08-03 09:44:20.721616 I AFD: Looking for decoder for DVB_SUBTITLE
2016-08-03 09:44:20.721707 I AFD: Opened codec 0x2d84de0, id(DVB_SUBTITLE) type(Subtitle)
2016-08-03 09:44:20.721733 I AFD: Subtitle track #1 is A/V stream #3 and is in the Polish language(7368556).
2016-08-03 09:44:20.721744 I AFD: Trying to select best video track
2016-08-03 09:44:20.721821 I AFD: Selected track #0 (id 0x217 codec id H264, type Video, bitrate 500000 at 0x2e08ec0)
2016-08-03 09:44:20.721947 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:20.721971 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:20.722012 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:20.722050 I VDP: LoadBestPreferences(1920x1080, 60)
2016-08-03 09:44:20.722143 I AFD: Using 1 CPUs for decoding
2016-08-03 09:44:20.722162 I AFD: InitVideoCodec() 0x2d26580 id(H264) type (Video).
2016-08-03 09:44:20.722213 I AFD: Selected FPS is 25 (avg 50 codec 25 container 90000 estimated 50)
2016-08-03 09:44:20.722239 I Player(0): SetFrameInterval ps:1 scan:3
2016-08-03 09:44:20.722330 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:20.722350 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:20.722387 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:20.722423 I VDP: LoadBestPreferences(1920x1080, 60)
2016-08-03 09:44:20.722475 I VidOutVDPAU: InputChanged(1920,1080,1.77778) \'None\'->\'H.264 VDPAU\'
2016-08-03 09:44:20.723628 I VidOutVDPAU: DiscardFrames(1)
2016-08-03 09:44:20.723699 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAA
2016-08-03 09:44:20.723745 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2016-08-03 09:44:20.723786 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2016-08-03 09:44:20.724942 W MythPainter: 41 images not yet de-allocated.
2016-08-03 09:44:20.724991 I VDPAU Painter: Clearing VDPAU painter cache.
2016-08-03 09:44:20.787322 I Clearing OpenGL painter cache.
2016-08-03 09:44:20.787374 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:20.787399 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:20.787444 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:20.787480 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:20.787516 I VDP: LoadBestPreferences(1920x1088, 25)
2016-08-03 09:44:20.787584 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:20.787605 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:20.787644 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:20.787681 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:20.787707 I VDP: SetVideoRenderer(vdpau)
2016-08-03 09:44:20.787733 I VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2016-08-03 09:44:20.792291 I Trying to match best refresh rate 25.000Hz
2016-08-03 09:44:20.792372 I Using 1920x1080 50.000 Hz
2016-08-03 09:44:20.792424 I SwitchToVideo: Video size 1920 x 1080: 
Already displaying resolution 1920 x 1080, 650mm x 365mm
2016-08-03 09:44:20.792453 I VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2016-08-03 09:44:20.792480 I VideoOutput: Actual display dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:20.792504 I VideoOutput: Estimated window dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:20.792568 I VidOutVDPAU: VDPAU Sharpen 1.00
2016-08-03 09:44:20.873296 I VDPAU: Created 2 output surfaces.
2016-08-03 09:44:20.873330 I VDPAU: Set colorkey to 0x20202
2016-08-03 09:44:20.873351 I VDPAU: Created VDPAU render device 1920x1080
2016-08-03 09:44:20.873396 I VidOutVDPAU: Created VDPAU osd (1920x1080)
2016-08-03 09:44:21.163092 I ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels, 
2016-08-03 09:44:21.163116 I VidOutVDPAU: Using ITU BT.709 colorspace
2016-08-03 09:44:21.163167 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 1
2016-08-03 09:44:21.163199 D ColourSpace: 1.0000 0.0000 1.3189 -0.6620
2016-08-03 09:44:21.163228 D ColourSpace: 1.0000 -0.1571 -0.3918 0.2755
2016-08-03 09:44:21.163254 D ColourSpace: 1.0000 1.5587 0.0000 -0.7824
2016-08-03 09:44:21.163278 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:21.163294 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:21.163320 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:21.163344 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:21.163358 I VidOutVDPAU: Created VDPAU context (GPU decode)
2016-08-03 09:44:21.163385 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:44:21.166387 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:21.166415 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:21.166430 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:21.166459 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:21.166483 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:21.167843 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-08-03 09:44:21.167898 I Player(0): ClearAfterSeek(1)
2016-08-03 09:44:21.167921 I VidOutVDPAU: ClearAfterSeek()
2016-08-03 09:44:21.167944 I VidOutVDPAU: DiscardFrames(0)
2016-08-03 09:44:21.168029 I VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAA
2016-08-03 09:44:21.168064 I VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAA -- done
2016-08-03 09:44:21.168094 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2016-08-03 09:44:21.168133 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:21.168160 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:21.168205 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:21.168248 I Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 1080) ->Interlaced Scan
2016-08-03 09:44:21.168270 I Player(0): Enabled deinterlacing
2016-08-03 09:44:21.168323 I AFD: Using vdpau for video decoding
2016-08-03 09:44:21.168556 I AFD: Opened codec 0x2d26580, id(H264) type(Video)
2016-08-03 09:44:21.201690 I AFD: Trying to select audio track (w/lang)
2016-08-03 09:44:21.201817 I AFD: Selected track 1: Polish MP2 2ch (A/V Stream #1)
2016-08-03 09:44:21.201948 I AFD: Audio data is planar
2016-08-03 09:44:21.202052 I AFD: Initializing audio parms from audio track #1
2016-08-03 09:44:21.202121 I AFD: Audio format changed 
from id(NONE) -1Hz -1/-1ch -1bps (profile 0) to id( MP2) 48000Hz 2/2ch 16bps (profile 0)
2016-08-03 09:44:21.202166 I AOBase: Killing AudioOutputDSP
2016-08-03 09:44:21.202219 I AOBase: Original codec was MP2, signed 16 bit, 48 kHz, 2 channels
2016-08-03 09:44:21.202263 I AOBase: enc(0), passthru(0), features (AC3,DTS) configured_channels(2), 2 channels supported(1) max_channels(6)
2016-08-03 09:44:21.202295 I AOBase: Opening audio device \'iec958:CARD=Intel,DEV=0\' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2016-08-03 09:44:21.202308 I ALSA: OpenDevice iec958:CARD=Intel,DEV=0
2016-08-03 09:44:21.203643 I ALSA: SetParameters(format=2, channels=2, rate=48000, buffer_time=500000, period_time=4)
2016-08-03 09:44:21.204036 I ALSA: Buffer size range from 64 to 16384
2016-08-03 09:44:21.204053 I ALSA: Period size range from 32 to 8192
2016-08-03 09:44:21.204123 W ALSA: Requested 500000us got 341333 buffer time
2016-08-03 09:44:21.204367 I ALSA: Hardware audio buffer cur: 64 need: 128 max allowed: 32768
2016-08-03 09:44:21.204422 W ALSA: Try to manually increase audio buffer with: echo 128 | sudo tee /proc/asound/card0/pcm1p/sub0/prealloc
2016-08-03 09:44:21.204445 I ALSA: Buffer time = 341333 us
2016-08-03 09:44:21.204524 I ALSA: Period time = 4 periods
2016-08-03 09:44:21.204845 I ALSA: Buffer size = 16384 | Period size = 4096
2016-08-03 09:44:21.205107 I AOBase: Audio fragment size: 8192
2016-08-03 09:44:21.205156 I AOBase: Audio Stretch Factor: 1
2016-08-03 09:44:21.205263 I AOBase: Ending Reconfigure()
2016-08-03 09:44:21.205322 I Dec: Selected track #1 (type 2) in the Unknown language(0)
2016-08-03 09:44:21.205351 I Dec: Selected track #1 (type 3) in the Polish language(7368556)
2016-08-03 09:44:21.205374 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2016-08-03 09:44:21.205517 I AOBase: kickoffOutputAudioLoop: pid = 9017
2016-08-03 09:44:21.205608 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:44:21.207408 I Dec: Position map filled from DB to: 0
2016-08-03 09:44:21.207430 I Dec: Duration map filled from DB to: 0
2016-08-03 09:44:21.207447 I Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-08-03 09:44:21.207480 I Player(0): Filling position map from 1 to end
2016-08-03 09:44:21.208522 I Dec: Position map filled from Encoder to: 0
2016-08-03 09:44:21.208561 I Dec: Duration map filled from Encoder to: 0
2016-08-03 09:44:21.208577 I Dec: SyncPositionMap watchingrecording total: 1 entries
2016-08-03 09:44:21.208601 I Dec: SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1
2016-08-03 09:44:21.210181 I AFD: Partial position map found
2016-08-03 09:44:21.210208 I AFD: Successfully opened decoder for file: "myth://mythtv/23806_20160803074419.ts". novideo(0)
2016-08-03 09:44:21.214122 I Using file start
2016-08-03 09:44:21.214927 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-08-03 09:44:21.352977 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2016-08-03 09:44:21.353034 I Player(0): Play( 1.0, normal 1, unpause audio 1)
2016-08-03 09:44:21.357397 I Dec: Position map filled from DB to: 0
2016-08-03 09:44:21.357418 I Dec: Duration map filled from DB to: 0
2016-08-03 09:44:21.357434 I Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-08-03 09:44:21.357456 I Player(0): Filling position map from 1 to end
2016-08-03 09:44:21.358577 I Dec: Position map filled from Encoder to: 24
2016-08-03 09:44:21.358594 I Dec: Duration map filled from Encoder to: 24
2016-08-03 09:44:21.358609 I Dec: SyncPositionMap watchingrecording total: 2 entries
2016-08-03 09:44:21.358640 I Dec: SyncPositionMap, new totframes: 24, new length: 0, posMap size: 2
2016-08-03 09:44:21.429888 I VidOutVDPAU: Added 2 new buffers. New buffer size 16 (4 decode and 12 process)
2016-08-03 09:44:21.453147 W Player(0): Waited 100ms for decoder to unpause
2016-08-03 09:44:21.464954 I VidOutVDPAU: Created VDPAU decoder (4 ref frames)
2016-08-03 09:44:21.553256 W Player(0): Waited 100ms for decoder to unpause
2016-08-03 09:44:21.653359 W Player(0): Waited 100ms for decoder to unpause
2016-08-03 09:44:21.682609 I AOBase: Pause 0
2016-08-03 09:44:21.682672 I Player(0): Play speed: rate: 25 speed: 1 skip: 1 => new interval 40000
2016-08-03 09:44:21.682710 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:21.682796 I Player(0): JumpToProgram - end
2016-08-03 09:44:21.696078 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-08-03 09:44:21.789726 I ALSA: WriteAudio: buffer underrun
2016-08-03 09:44:21.813940 D Running HouseKeeper.
2016-08-03 09:44:21.813964 D Checking to run HardwareProfiler
2016-08-03 09:44:21.814065 I Player(0): Video is 3.05 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:21.814086 I AOBase: Pause 1
2016-08-03 09:44:21.814193 I Player(0): Video is 5.0875 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:21.814208 I AOBase: Pause 1
2016-08-03 09:44:21.814279 I Player(0): Video is 6.36562 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:21.814293 I AOBase: Pause 1
2016-08-03 09:44:21.814346 I Player(0): Waiting for video buffers...
2016-08-03 09:44:21.820086 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:21.840096 I Player(0): Video is 7.0742 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:21.840173 I Player(0): Waiting for video buffers...
2016-08-03 09:44:21.860795 I Player(0): Video is 5.49315 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:21.860895 I Player(0): Waiting for video buffers...
2016-08-03 09:44:21.876383 I Player(0): Video is 4.06985 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:21.876462 I Player(0): Waiting for video buffers...
2016-08-03 09:44:21.922592 I AOBase: Pause 0
2016-08-03 09:44:21.931569 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:44:22.122855 I Player(0): Waiting for video buffers...
2016-08-03 09:44:22.253861 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.273848 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.273909 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.293839 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.293866 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.313816 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.313876 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.333800 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.333827 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.353818 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.353874 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.353964 I Player(0): Waiting for video buffers...
2016-08-03 09:44:22.428239 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.431193 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.431245 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.444867 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.444895 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.464909 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.464961 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.564918 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.584912 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.584966 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.724886 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.744878 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.744937 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.764860 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.764887 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.784913 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.784973 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.804878 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.804906 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.824900 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.824960 I VidOutVDPAU: Frame G is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.825054 I Player(0): Waiting for video buffers...
2016-08-03 09:44:22.921099 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.941040 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:22.941093 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.202420 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.222456 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.222508 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.242491 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.242518 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.262423 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.262483 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.282520 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.282548 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.302466 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.302527 I VidOutVDPAU: Frame H is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.302641 I Player(0): Waiting for video buffers...
2016-08-03 09:44:23.393729 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.413700 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.413770 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.522433 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.682488 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.702457 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.702509 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.722402 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:23.722429 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.002597 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.162447 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.182480 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.182541 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.202431 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.202458 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.222493 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.222546 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.242416 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.242442 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.262473 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.262531 I VidOutVDPAU: Frame e is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.262620 I Player(0): Waiting for video buffers...
2016-08-03 09:44:24.358784 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.378806 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.378867 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.398737 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.398765 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.418808 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.418868 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.418962 I Player(0): Waiting for video buffers...
2016-08-03 09:44:24.504038 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.522414 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.522467 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.522595 I Player(0): Waiting for video buffers...
2016-08-03 09:44:24.548012 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.548041 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.568039 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.568094 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.661990 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.681983 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.682036 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.822036 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.842045 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.842099 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.862018 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.862043 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.882021 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.882074 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:24.882161 I Player(0): Waiting for video buffers...
2016-08-03 09:44:25.002044 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.021983 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.022031 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.042056 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.042089 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.142203 I Player(0): Waiting for video buffers...
2016-08-03 09:44:25.162132 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.182069 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.182142 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.182241 I Player(0): Waiting for video buffers...
2016-08-03 09:44:25.202080 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.202109 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.222138 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.322094 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.482067 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.502088 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.502160 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.622161 I Player(0): Waiting for video buffers...
2016-08-03 09:44:25.642082 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.662080 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.662136 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.682195 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.682229 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:25.782864 I Player(0): FPS: 25.19 Mean: 39692 Std.Dev: 17642 CPUs: 6% 5% 5% 5% 
2016-08-03 09:44:25.962135 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.122138 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.142072 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.142128 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.162070 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.162100 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.442432 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.462464 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.462608 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.462728 I Player(0): Waiting for video buffers...
2016-08-03 09:44:26.496611 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.496641 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.502232 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.502291 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.502410 I Player(0): Waiting for video buffers...
2016-08-03 09:44:26.522075 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.522104 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.602121 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.622127 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.622197 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.642103 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:26.642131 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.062220 I Player(0): Waiting for video buffers...
2016-08-03 09:44:27.082128 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.102128 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.102185 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.122309 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.122345 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.142118 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.142188 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.162167 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.162197 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.182123 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.182196 I VidOutVDPAU: Frame d is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.182297 I Player(0): Waiting for video buffers...
2016-08-03 09:44:27.240441 I Player(0): Waiting for video buffers...
2016-08-03 09:44:27.258454 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.262074 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.262142 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.262239 I Player(0): Waiting for video buffers...
2016-08-03 09:44:27.282203 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.282246 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.302152 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.302213 I VidOutVDPAU: Frame D is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.562201 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.582211 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.582268 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.602362 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.602425 I VidOutVDPAU: Frame B is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.722205 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.742423 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:27.742510 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.042230 I VidOutVDPAU: Frame A is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.203743 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.222316 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.222372 I VidOutVDPAU: Frame c is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.362277 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.382247 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.382304 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.402274 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.402303 I VidOutVDPAU: Frame E is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.502429 I Player(0): Waiting for video buffers...
2016-08-03 09:44:28.531322 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.542363 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.542436 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.542586 I Player(0): Waiting for video buffers...
2016-08-03 09:44:28.562215 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.562246 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.582256 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.582314 I VidOutVDPAU: Frame h is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.682337 I VidOutVDPAU: Frame F is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.842720 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.862242 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.862300 I VidOutVDPAU: Frame g is in use by avlib and so is being held for later discarding.
2016-08-03 09:44:28.942544 I TV::CommitQueuedInput(): livetv(1) qchannum(222) qchanid(0)
2016-08-03 09:44:28.949154 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 17) called from UI thread
2016-08-03 09:44:28.954860 I AOBase: Pause 1
2016-08-03 09:44:28.954889 D TV::PauseLiveTV(): player ctx 0
2016-08-03 09:44:28.986285 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:29.068809 E AO: audio decode error: Invalid data found when processing input (0)
2016-08-03 09:44:29.068831 E AFD: Unknown audio decoding error
2016-08-03 09:44:29.754859 D TV::UnpauseLiveTV(): player ctx 0
2016-08-03 09:44:29.755970 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:29.756062 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 3 entries
DUMMY: 23806 (07:44:16 to 07:44:19)
* DVB: 23806 (07:44:19 to 07:44:29) discontinuous
DUMMY: 8006 (07:44:29 to 08:00:00) discontinuous

2016-08-03 09:44:29.760347 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(-1)
2016-08-03 09:44:29.760396 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@2: \'8006_20160803074429\')
2016-08-03 09:44:29.760432 I Player(0): Play( 1.0, normal 1, unpause audio 0)
2016-08-03 09:44:29.867536 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_SG_FILEQUERY,mythtv...) called from UI thread
2016-08-03 09:44:29.873578 I AOBase: Pause 1
2016-08-03 09:44:29.874343 I Player(0): JumpToProgram - start
2016-08-03 09:44:29.877562 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 2 -> ProgramInfo(8006_20160803074429.ts): channame(TVN BiS) startts(Wed Aug 3 05:00:00 2016 GMT) endts(Wed Aug 3 08:00:00 2016 GMT)
recstartts(Wed Aug 3 07:44:29 2016 GMT) recendts(Wed Aug 3 08:00:00 2016 GMT)
title(Poranek na BiŚ)
2016-08-03 09:44:29.880173 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:29.880203 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:29.880245 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:29.880263 I AOBase: Pause 1
2016-08-03 09:44:29.883834 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:29.899162 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(2)
2016-08-03 09:44:29.899226 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@2: \'8006_20160803074429\')
2016-08-03 09:44:29.905383 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:29.905414 I Player(0): ClearAfterSeek(1)
2016-08-03 09:44:29.905428 I VidOutVDPAU: ClearAfterSeek()
2016-08-03 09:44:29.905440 I VidOutVDPAU: DiscardFrames(0)
2016-08-03 09:44:29.905488 I VideoBuffers::DiscardFrames(0): DDAUAUUAFFUUAFUD
2016-08-03 09:44:29.905535 I VideoBuffers::DiscardFrames(0): DDAAAAAAFFAAAFAD -- done
2016-08-03 09:44:29.905566 I VidOutVDPAU: DiscardFrames() 3: DDAAAAAAFFAAAFAD -- done()
2016-08-03 09:44:29.905590 I Dec: Reset: Video 1, Seek 1, File 1
2016-08-03 09:44:29.905632 I Player(0): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2016-08-03 09:44:29.908286 I VDP: GetFilteredDeint(vdpauadvanced) : vdpau -> \'vdpauadvanced\'
2016-08-03 09:44:29.911211 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:32.785733 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:32.785841 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 4 entries
DUMMY: 23806 (07:44:16 to 07:44:19)
DVB: 23806 (07:44:19 to 07:44:29) discontinuous
* DUMMY: 8006 (07:44:29 to 07:44:32) discontinuous
DVB: 8006 (07:44:32 to 08:00:00) discontinuous

2016-08-03 09:44:32.786012 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): JumpToNext: 1 -> 0
2016-08-03 09:44:32.786028 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(3)
2016-08-03 09:44:32.786066 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@3: \'8006_20160803074432\')
2016-08-03 09:44:32.786083 I Player(0): JumpToProgram - start
2016-08-03 09:44:32.789765 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 3 -> ProgramInfo(8006_20160803074432.ts): channame(TVN BiS) startts(Wed Aug 3 05:00:00 2016 GMT) endts(Wed Aug 3 08:00:00 2016 GMT)
recstartts(Wed Aug 3 07:44:32 2016 GMT) recendts(Wed Aug 3 08:00:00 2016 GMT)
title(Poranek na BiŚ)
2016-08-03 09:44:32.792663 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:32.792694 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:32.792735 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:32.792884 I FileRingBuf(myth://mythtv/23806_20160803074419.ts): OpenFile(myth://mythtv/8006_20160803074432.ts, 10000 ms)
2016-08-03 09:44:33.450199 W RingBuf(myth://mythtv/8006_20160803074432.ts): Not starting read ahead thread, already running
2016-08-03 09:44:34.064989 D AFD: PlayerFlags: 0xc0
2016-08-03 09:44:34.065033 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:34.065055 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-08-03 09:44:34.065072 D AFD: OpenFile -- begin
2016-08-03 09:44:34.065574 I AFD: Buffer size: 32768 streamed 1 seekable 1
2016-08-03 09:44:34.231952 I AFD: File successfully opened after 166ms
2016-08-03 09:44:34.232005 I AFD: Stream #0, has id 0x20b codec id MPEG2VIDEO, type Video, bitrate 0 at 0x2cd36e0
2016-08-03 09:44:34.232031 I AFD: Stream #1, has id 0x2f8 codec id MP2, type Audio, bitrate 192000 at 0x2cd3320
2016-08-03 09:44:34.232046 I AFD: codec MP2 has 2 channels
2016-08-03 09:44:34.232058 I AFD: Looking for decoder for MP2
2016-08-03 09:44:34.232399 I AFD: Opened codec 0x2e27bc0, id(MP2) type(Audio)
2016-08-03 09:44:34.232482 I AFD: Audio Track #1, of type (Normalny) is A/V stream #1 (id=0x2f8) and has 2 channels in the Polish language(7368556).
2016-08-03 09:44:34.232507 I AFD: Stream #2, has id 0x2f9 codec id MP2, type Audio, bitrate 192000 at 0x2e045c0
2016-08-03 09:44:34.232522 I AFD: codec MP2 has 2 channels
2016-08-03 09:44:34.232534 I AFD: Looking for decoder for MP2
2016-08-03 09:44:34.232837 I AFD: Opened codec 0x32aa120, id(MP2) type(Audio)
2016-08-03 09:44:34.232897 I AFD: Audio Track #2, of type (Normalny) is A/V stream #2 (id=0x2f9) and has 2 channels in the Unknown language(7303783).
2016-08-03 09:44:34.232925 I AFD: Stream #3, has id 0x1786 codec id DVB_SUBTITLE, type Subtitle, bitrate 0 at 0x2d16420
2016-08-03 09:44:34.232938 I AFD: subtitle codec (Subtitle)
2016-08-03 09:44:34.232948 I AFD: Looking for decoder for DVB_SUBTITLE
2016-08-03 09:44:34.233026 I AFD: Opened codec 0x311b1e0, id(DVB_SUBTITLE) type(Subtitle)
2016-08-03 09:44:34.233054 I AFD: Subtitle track #1 is A/V stream #3 and is in the Polish language(7368556).
2016-08-03 09:44:34.233065 I AFD: Trying to select best video track
2016-08-03 09:44:34.233100 I AFD: Selected track #0 (id 0x20b codec id MPEG2VIDEO, type Video, bitrate 500000 at 0x2cd36e0)
2016-08-03 09:44:34.233223 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:34.233248 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:34.233290 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:34.233327 I VDP: LoadBestPreferences(720x576, 60)
2016-08-03 09:44:34.233378 I AFD: Using 1 CPUs for decoding
2016-08-03 09:44:34.233396 I AFD: InitVideoCodec() 0x2d642a0 id(MPEG2VIDEO) type (Video).
2016-08-03 09:44:34.233447 I AFD: Selected FPS is 25 (avg 25 codec 25 container 90000 estimated 25)
2016-08-03 09:44:34.233471 I Player(0): SetFrameInterval ps:0 scan:3
2016-08-03 09:44:34.233556 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:34.233575 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:34.233611 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:34.233647 I VDP: LoadBestPreferences(720x576, 60)
2016-08-03 09:44:34.233694 I VidOutVDPAU: InputChanged(720,576,1.25) \'H.264 VDPAU\'->\'MPEG2 VDPAU\'
2016-08-03 09:44:34.235022 I VidOutVDPAU: DiscardFrames(1)
2016-08-03 09:44:34.235067 I VideoBuffers::DiscardFrames(1): AAAAAAAAFFAAAFAA
2016-08-03 09:44:34.235111 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAA -- done
2016-08-03 09:44:34.235141 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done()
2016-08-03 09:44:34.239237 W MythPainter: 9 images not yet de-allocated.
2016-08-03 09:44:34.239262 I VDPAU Painter: Clearing VDPAU painter cache.
2016-08-03 09:44:34.327593 I Clearing OpenGL painter cache.
2016-08-03 09:44:34.327721 I Display Rect left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-08-03 09:44:34.327768 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:34.327811 I VDP: LoadBestPreferences(720x576, 25)
2016-08-03 09:44:34.327893 I Display Rect left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-08-03 09:44:34.327932 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:34.327997 I VDP: SetVideoRenderer(vdpau)
2016-08-03 09:44:34.328030 I VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2016-08-03 09:44:34.332912 I Trying to match best refresh rate 25.000Hz
2016-08-03 09:44:34.332978 I Using 1920x1080 50.000 Hz
2016-08-03 09:44:34.333012 I SwitchToVideo: Video size 720 x 576: 
Already displaying resolution 1920 x 1080, 650mm x 365mm
2016-08-03 09:44:34.333038 I VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2016-08-03 09:44:34.333064 I VideoOutput: Actual display dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:34.333088 I VideoOutput: Estimated window dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:34.333153 I VidOutVDPAU: VDPAU Sharpen 1.00
2016-08-03 09:44:34.414607 I VDPAU: Created 2 output surfaces.
2016-08-03 09:44:34.414674 I VDPAU: Set colorkey to 0x20202
2016-08-03 09:44:34.414697 I VDPAU: Created VDPAU render device 1920x1080
2016-08-03 09:44:34.414741 I VidOutVDPAU: Created VDPAU osd (1920x1080)
2016-08-03 09:44:34.480541 I ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels, 
2016-08-03 09:44:34.480565 I VidOutVDPAU: Using ITU BT.601 colorspace
2016-08-03 09:44:34.480611 I Display Rect left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-08-03 09:44:34.480637 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:34.480651 I VidOutVDPAU: Created VDPAU context (GPU decode)
2016-08-03 09:44:34.480679 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:44:34.483829 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:34.483873 I Display Rect left: 285, top: 0, width: 1350, height: 1080, aspect: 1.77778
2016-08-03 09:44:34.483899 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25
2016-08-03 09:44:34.485279 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-08-03 09:44:34.485331 I Player(0): ClearAfterSeek(1)
2016-08-03 09:44:34.485354 I VidOutVDPAU: ClearAfterSeek()
2016-08-03 09:44:34.485377 I VidOutVDPAU: DiscardFrames(0)
2016-08-03 09:44:34.485429 I VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAA
2016-08-03 09:44:34.485462 I VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAA -- done
2016-08-03 09:44:34.485492 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2016-08-03 09:44:34.485531 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:34.485557 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:34.485602 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:34.485645 I Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2016-08-03 09:44:34.485683 I AFD: Using vdpau for video decoding
2016-08-03 09:44:34.485741 I AFD: Opened codec 0x2d642a0, id(MPEG2VIDEO) type(Video)
2016-08-03 09:44:34.544436 I AFD: Trying to select audio track (w/lang)
2016-08-03 09:44:34.544495 I AFD: Selected track 1: Polish MP2 2ch (A/V Stream #1)
2016-08-03 09:44:34.544509 I AFD: Audio data is planar
2016-08-03 09:44:34.544529 I AFD: Initializing audio parms from audio track #1
2016-08-03 09:44:34.544582 I AFD: Audio format changed 
from id(NONE) -1Hz -1/-1ch -1bps (profile 0) to id( MP2) 48000Hz 2/2ch 16bps (profile 0)
2016-08-03 09:44:34.544604 I AOBase: Reconfigure(): No change -> exiting
2016-08-03 09:44:34.544657 I Dec: Selected track #1 (type 2) in the Unknown language(0)
2016-08-03 09:44:34.544683 I Dec: Selected track #1 (type 3) in the Polish language(7368556)
2016-08-03 09:44:34.544703 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2016-08-03 09:44:34.546370 I Dec: Position map filled from DB to: 12
2016-08-03 09:44:34.546400 I Dec: Duration map filled from DB to: 12
2016-08-03 09:44:34.546418 I Dec: SyncPositionMap watchingrecording, from DB: 2 entries
2016-08-03 09:44:34.546442 I Player(0): Filling position map from 13 to end
2016-08-03 09:44:34.547363 I Dec: Position map filled from Encoder to: 24
2016-08-03 09:44:34.547383 I Dec: Duration map filled from Encoder to: 24
2016-08-03 09:44:34.547402 I Dec: SyncPositionMap watchingrecording total: 3 entries
2016-08-03 09:44:34.547423 I Dec: SyncPositionMap, new totframes: 24, new length: 0, posMap size: 3
2016-08-03 09:44:34.548583 I AFD: Partial position map found
2016-08-03 09:44:34.548609 I AFD: Successfully opened decoder for file: "myth://mythtv/8006_20160803074432.ts". novideo(0)
2016-08-03 09:44:34.552481 I Using file start
2016-08-03 09:44:34.553239 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-08-03 09:44:34.700700 I Player(0): Play( 1.0, normal 1, unpause audio 1)
2016-08-03 09:44:34.701184 I AOBase: Pause 0
2016-08-03 09:44:34.701190 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2016-08-03 09:44:34.701241 I Player(0): Play speed: rate: 25 speed: 1 skip: 1 => new interval 40000
2016-08-03 09:44:34.702927 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:44:34.703972 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:44:34.704675 I Dec: Position map filled from DB to: 12
2016-08-03 09:44:34.704708 I Dec: Duration map filled from DB to: 12
2016-08-03 09:44:34.704732 I Dec: SyncPositionMap watchingrecording, from DB: 2 entries
2016-08-03 09:44:34.704767 I Player(0): Filling position map from 13 to end
2016-08-03 09:44:34.706337 I Dec: Position map filled from Encoder to: 36
2016-08-03 09:44:34.706364 I Dec: Duration map filled from Encoder to: 36
2016-08-03 09:44:34.706389 I Dec: SyncPositionMap watchingrecording total: 4 entries
2016-08-03 09:44:34.706418 I Dec: SyncPositionMap, new totframes: 36, new length: 1, posMap size: 4
2016-08-03 09:44:34.707217 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:34.707266 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:34.707299 I Player(0): JumpToProgram - end
2016-08-03 09:44:34.707325 I Player(0): Waiting for video buffers...
2016-08-03 09:44:34.810752 N Player(0): Waited 103ms for video buffers AAAAAAAAAAAAAA
2016-08-03 09:44:34.890578 I VidOutVDPAU: Created VDPAU decoder (2 ref frames)
2016-08-03 09:44:34.893852 I Player(0): Video Aspect ratio changed from 1.25 to 1.77778
2016-08-03 09:44:34.893902 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:34.893934 I Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2016-08-03 09:44:34.916835 I OSD: Loaded window osd_message
2016-08-03 09:44:34.933812 I OSD: Loaded window osd_input
2016-08-03 09:44:34.962423 I OSD: Loaded window program_info
2016-08-03 09:44:34.980318 I OSD: Loaded window browse_info
2016-08-03 09:44:34.996404 I OSD: Loaded window osd_status
2016-08-03 09:44:35.013577 I OSD: Loaded window osd_program_editor
2016-08-03 09:44:35.037704 I OSD: Loaded window osd_debug
2016-08-03 09:44:35.037755 I OSD: Loaded OSD: size 1920x1080 offset 0+0
2016-08-03 09:44:35.037843 I EnableCaptions(0) msg: WłÄ
cz
2016-08-03 09:44:35.043825 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-08-03 09:44:35.055066 I Player(0): Video is 4.96875 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055094 I AOBase: Pause 1
2016-08-03 09:44:35.055182 I Player(0): Video is 6.2578 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055197 I AOBase: Pause 1
2016-08-03 09:44:35.055272 I Player(0): Video is 6.9746 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055286 I AOBase: Pause 1
2016-08-03 09:44:35.055360 I Player(0): Video is 7.2622 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055374 I AOBase: Pause 1
2016-08-03 09:44:35.055448 I Player(0): Video is 7.2279 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055462 I AOBase: Pause 1
2016-08-03 09:44:35.055536 I Player(0): Video is 6.95218 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055550 I AOBase: Pause 1
2016-08-03 09:44:35.055623 I Player(0): Video is 6.49538 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055637 I AOBase: Pause 1
2016-08-03 09:44:35.055710 I Player(0): Video is 5.90277 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055724 I AOBase: Pause 1
2016-08-03 09:44:35.055798 I Player(0): Video is 5.20832 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055812 I AOBase: Pause 1
2016-08-03 09:44:35.055886 I Player(0): Video is 4.43748 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.055901 I AOBase: Pause 1
2016-08-03 09:44:35.055956 I Player(0): Waiting for video buffers...
2016-08-03 09:44:35.061253 I Player(0): Video is 3.60935 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:35.061277 I AOBase: Pause 1
2016-08-03 09:44:35.061388 I AOBase: Pause 0
2016-08-03 09:44:35.092492 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:44:35.382562 I Player(0): FPS: 10.42 Mean: 95991 Std.Dev: 607248 CPUs: 8% 3% 10% 4% 
2016-08-03 09:44:39.382752 I Player(0): FPS: 25.00 Mean: 39996 Std.Dev: 277 CPUs: 3% 3% 4% 3% 
2016-08-03 09:44:40.423000 I TV::CommitQueuedInput(): livetv(1) qchannum(21) qchanid(0)
2016-08-03 09:44:40.431792 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 17) called from UI thread
2016-08-03 09:44:40.437995 I AOBase: Pause 1
2016-08-03 09:44:40.438023 D TV::PauseLiveTV(): player ctx 0
2016-08-03 09:44:40.504794 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:41.278559 D TV::UnpauseLiveTV(): player ctx 0
2016-08-03 09:44:41.279645 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:41.279778 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 5 entries
DUMMY: 23806 (07:44:16 to 07:44:19)
DVB: 23806 (07:44:19 to 07:44:29) discontinuous
DUMMY: 8006 (07:44:29 to 07:44:32) discontinuous
* DVB: 8006 (07:44:32 to 07:44:41) discontinuous
DUMMY: 3104 (07:44:41 to 08:00:00) discontinuous

2016-08-03 09:44:41.284294 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(-1)
2016-08-03 09:44:41.284345 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@4: \'3104_20160803074441\')
2016-08-03 09:44:41.284381 I Player(0): Play( 1.0, normal 1, unpause audio 0)
2016-08-03 09:44:41.341855 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_SG_FILEQUERY,mythtv...) called from UI thread
2016-08-03 09:44:41.353674 I AOBase: Pause 1
2016-08-03 09:44:41.354825 I Player(0): JumpToProgram - start
2016-08-03 09:44:41.358045 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:41.358208 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 4 -> ProgramInfo(3104_20160803074441.ts): channame(Viasat History HD) startts(Wed Aug 3 06:55:00 2016 GMT) endts(Wed Aug 3 08:00:00 2016 GMT)
recstartts(Wed Aug 3 07:44:41 2016 GMT) recendts(Wed Aug 3 08:00:00 2016 GMT)
title(Jerozolima: jak powstawało Miasto Święte)
2016-08-03 09:44:41.360997 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:41.361028 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:41.361072 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:41.361091 I AOBase: Pause 1
2016-08-03 09:44:41.374457 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(4)
2016-08-03 09:44:41.374502 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@4: \'3104_20160803074441\')
2016-08-03 09:44:41.380092 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:41.380118 I Player(0): ClearAfterSeek(1)
2016-08-03 09:44:41.380132 I VidOutVDPAU: ClearAfterSeek()
2016-08-03 09:44:41.380145 I VidOutVDPAU: DiscardFrames(0)
2016-08-03 09:44:41.380190 I VideoBuffers::DiscardFrames(0): UAUUADUUUDUDUU
2016-08-03 09:44:41.380229 I VideoBuffers::DiscardFrames(0): AAAAADAAADADAA -- done
2016-08-03 09:44:41.380259 I VidOutVDPAU: DiscardFrames() 3: AAAAADAAADADAA -- done()
2016-08-03 09:44:41.380285 I Dec: Reset: Video 1, Seek 1, File 1
2016-08-03 09:44:41.380327 I Player(0): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2016-08-03 09:44:41.382496 I VDP: GetFilteredDeint(vdpauadvanced) : vdpau -> \'vdpauadvanced\'
2016-08-03 09:44:41.384680 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:41.443417 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:43.240356 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:43.240506 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 6 entries
DUMMY: 23806 (07:44:16 to 07:44:19)
DVB: 23806 (07:44:19 to 07:44:29) discontinuous
DUMMY: 8006 (07:44:29 to 07:44:32) discontinuous
DVB: 8006 (07:44:32 to 07:44:41) discontinuous
* DUMMY: 3104 (07:44:41 to 07:44:43) discontinuous
DVB: 3104 (07:44:43 to 08:00:00) discontinuous

2016-08-03 09:44:43.240676 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): JumpToNext: 1 -> 0
2016-08-03 09:44:43.240693 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(5)
2016-08-03 09:44:43.240732 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@5: \'3104_20160803074443\')
2016-08-03 09:44:43.240749 I Player(0): JumpToProgram - start
2016-08-03 09:44:43.244748 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 5 -> ProgramInfo(3104_20160803074443.ts): channame(Viasat History HD) startts(Wed Aug 3 06:55:00 2016 GMT) endts(Wed Aug 3 08:00:00 2016 GMT)
recstartts(Wed Aug 3 07:44:43 2016 GMT) recendts(Wed Aug 3 08:00:00 2016 GMT)
title(Jerozolima: jak powstawało Miasto Święte)
2016-08-03 09:44:43.247710 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:43.247743 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:43.247784 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:43.247949 I FileRingBuf(myth://mythtv/8006_20160803074432.ts): OpenFile(myth://mythtv/3104_20160803074443.ts, 10000 ms)
2016-08-03 09:44:43.914775 W RingBuf(myth://mythtv/3104_20160803074443.ts): Not starting read ahead thread, already running
2016-08-03 09:44:44.459406 D AFD: PlayerFlags: 0xc0
2016-08-03 09:44:44.459452 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:44.459474 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-08-03 09:44:44.459492 D AFD: OpenFile -- begin
2016-08-03 09:44:44.460002 I AFD: Buffer size: 32768 streamed 1 seekable 1
2016-08-03 09:44:45.254873 I AFD: File successfully opened after 795ms
2016-08-03 09:44:45.254926 I AFD: Stream #0, has id 0x191 codec id H264, type Video, bitrate 0 at 0x2d65160
2016-08-03 09:44:45.254953 I AFD: Stream #1, has id 0x192 codec id MP2, type Audio, bitrate 192000 at 0x3797d80
2016-08-03 09:44:45.254970 I AFD: codec MP2 has 2 channels
2016-08-03 09:44:45.254983 I AFD: Looking for decoder for MP2
2016-08-03 09:44:45.255329 I AFD: Opened codec 0x32a98c0, id(MP2) type(Audio)
2016-08-03 09:44:45.255419 I AFD: Audio Track #1, of type (Normalny) is A/V stream #1 (id=0x192) and has 2 channels in the Undetermined language(7695972).
2016-08-03 09:44:45.255434 I AFD: Trying to select best video track
2016-08-03 09:44:45.255473 I AFD: Selected track #0 (id 0x191 codec id H264, type Video, bitrate 500000 at 0x2d65160)
2016-08-03 09:44:45.255600 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:45.255625 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:45.255669 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:45.255707 I VDP: LoadBestPreferences(1920x1080, 60)
2016-08-03 09:44:45.255761 I AFD: Using 1 CPUs for decoding
2016-08-03 09:44:45.255779 I AFD: InitVideoCodec() 0x3280160 id(H264) type (Video).
2016-08-03 09:44:45.255830 I AFD: Selected FPS is 25 (avg 25 codec 25 container 90000 estimated 50)
2016-08-03 09:44:45.255855 I Player(0): SetFrameInterval ps:0 scan:3
2016-08-03 09:44:45.255942 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:44:45.255962 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:44:45.255999 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:44:45.256036 I VDP: LoadBestPreferences(1920x1080, 60)
2016-08-03 09:44:45.256085 I VidOutVDPAU: InputChanged(1920,1080,1.77778) \'MPEG2 VDPAU\'->\'H.264 VDPAU\'
2016-08-03 09:44:45.257282 I VidOutVDPAU: DiscardFrames(1)
2016-08-03 09:44:45.257323 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA
2016-08-03 09:44:45.257358 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA -- done
2016-08-03 09:44:45.257387 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2016-08-03 09:44:45.260662 W MythPainter: 9 images not yet de-allocated.
2016-08-03 09:44:45.260689 I VDPAU Painter: Clearing VDPAU painter cache.
2016-08-03 09:44:45.347195 I Clearing OpenGL painter cache.
2016-08-03 09:44:45.347262 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:45.347288 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:45.347338 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.347380 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.347418 I VDP: LoadBestPreferences(1920x1088, 25)
2016-08-03 09:44:45.347486 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:45.347508 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:45.347547 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.347585 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.347607 I VDP: SetVideoRenderer(vdpau)
2016-08-03 09:44:45.347639 I VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2016-08-03 09:44:45.352347 I Trying to match best refresh rate 25.000Hz
2016-08-03 09:44:45.352428 I Using 1920x1080 50.000 Hz
2016-08-03 09:44:45.352481 I SwitchToVideo: Video size 1920 x 1080: 
Already displaying resolution 1920 x 1080, 650mm x 365mm
2016-08-03 09:44:45.352510 I VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2016-08-03 09:44:45.352538 I VideoOutput: Actual display dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:45.352563 I VideoOutput: Estimated window dimensions: 650x365 mm Aspect: 1.78082
2016-08-03 09:44:45.352628 I VidOutVDPAU: VDPAU Sharpen 1.00
2016-08-03 09:44:45.433443 I VDPAU: Created 2 output surfaces.
2016-08-03 09:44:45.433478 I VDPAU: Set colorkey to 0x20202
2016-08-03 09:44:45.433499 I VDPAU: Created VDPAU render device 1920x1080
2016-08-03 09:44:45.433545 I VidOutVDPAU: Created VDPAU osd (1920x1080)
2016-08-03 09:44:45.723458 I ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels, 
2016-08-03 09:44:45.723481 I VidOutVDPAU: Using ITU BT.709 colorspace
2016-08-03 09:44:45.723531 D ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 1
2016-08-03 09:44:45.723560 D ColourSpace: 1.0000 0.0000 1.3189 -0.6620
2016-08-03 09:44:45.723588 D ColourSpace: 1.0000 -0.1571 -0.3918 0.2755
2016-08-03 09:44:45.723615 D ColourSpace: 1.0000 1.5587 0.0000 -0.7824
2016-08-03 09:44:45.723639 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:45.723654 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:45.723681 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.723706 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.723720 I VidOutVDPAU: Created VDPAU context (GPU decode)
2016-08-03 09:44:45.723748 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:44:45.727001 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:45.727033 I Snapping height to avoid scaling: height: 1080, top: 0
2016-08-03 09:44:45.727049 I Snapping width to avoid scaling: width: 1920, left: 0
2016-08-03 09:44:45.727080 I Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.727107 I Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2016-08-03 09:44:45.746154 I OSD: Loaded window osd_message
2016-08-03 09:44:45.760006 I OSD: Loaded window osd_input
2016-08-03 09:44:45.787570 I OSD: Loaded window program_info
2016-08-03 09:44:45.806356 I OSD: Loaded window browse_info
2016-08-03 09:44:45.823194 I OSD: Loaded window osd_status
2016-08-03 09:44:45.841851 I OSD: Loaded window osd_program_editor
2016-08-03 09:44:45.865868 I OSD: Loaded window osd_debug
2016-08-03 09:44:45.865953 I OSD: Loaded OSD: size 1920x1080 offset 0+0
2016-08-03 09:44:45.866043 I EnableCaptions(0) msg: WłÄ
cz
2016-08-03 09:44:45.866062 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-08-03 09:44:45.866078 I Player(0): ClearAfterSeek(1)
2016-08-03 09:44:45.866088 I VidOutVDPAU: ClearAfterSeek()
2016-08-03 09:44:45.866100 I VidOutVDPAU: DiscardFrames(0)
2016-08-03 09:44:45.866138 I VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAA
2016-08-03 09:44:45.866167 I VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAA -- done
2016-08-03 09:44:45.866195 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2016-08-03 09:44:45.866229 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:45.866252 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:45.866293 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:45.866334 I Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 1080) ->Interlaced Scan
2016-08-03 09:44:45.866368 I AFD: Using vdpau for video decoding
2016-08-03 09:44:45.866790 I AFD: Opened codec 0x3280160, id(H264) type(Video)
2016-08-03 09:44:45.881891 I AFD: Selected track 1: Undetermined MP2 2ch (A/V Stream #1)
2016-08-03 09:44:45.881910 I AFD: Audio data is planar
2016-08-03 09:44:45.881932 I AFD: Initializing audio parms from audio track #1
2016-08-03 09:44:45.881985 I AFD: Audio format changed 
from id(NONE) -1Hz -1/-1ch -1bps (profile 0) to id( MP2) 48000Hz 2/2ch 16bps (profile 0)
2016-08-03 09:44:45.882010 I AOBase: Reconfigure(): No change -> exiting
2016-08-03 09:44:45.882038 I Dec: Selected track #1 (type 2) in the Unknown language(0)
2016-08-03 09:44:45.882060 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2016-08-03 09:44:45.883975 I Dec: Position map filled from DB to: 0
2016-08-03 09:44:45.883999 I Dec: Duration map filled from DB to: 0
2016-08-03 09:44:45.884018 I Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-08-03 09:44:45.884041 I Player(0): Filling position map from 1 to end
2016-08-03 09:44:45.885187 I Dec: Position map filled from Encoder to: 40
2016-08-03 09:44:45.885209 I Dec: Duration map filled from Encoder to: 40
2016-08-03 09:44:45.885227 I Dec: SyncPositionMap watchingrecording total: 2 entries
2016-08-03 09:44:45.885249 I Dec: SyncPositionMap, new totframes: 40, new length: 1, posMap size: 2
2016-08-03 09:44:45.886313 I AFD: Partial position map found
2016-08-03 09:44:45.886341 I AFD: Successfully opened decoder for file: "myth://mythtv/3104_20160803074443.ts". novideo(0)
2016-08-03 09:44:45.890112 I Using file start
2016-08-03 09:44:45.890827 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-08-03 09:44:46.038883 I Player(0): Play( 1.0, normal 1, unpause audio 1)
2016-08-03 09:44:46.039687 I AOBase: Pause 0
2016-08-03 09:44:46.039689 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2016-08-03 09:44:46.039738 I Player(0): Play speed: rate: 25 speed: 1 skip: 1 => new interval 40000
2016-08-03 09:44:46.042027 I Dec: Position map filled from DB to: 0
2016-08-03 09:44:46.042058 I Dec: Duration map filled from DB to: 0
2016-08-03 09:44:46.042083 I Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-08-03 09:44:46.042113 I Player(0): Filling position map from 1 to end
2016-08-03 09:44:46.043453 I Dec: Position map filled from Encoder to: 40
2016-08-03 09:44:46.043480 I Dec: Duration map filled from Encoder to: 40
2016-08-03 09:44:46.043508 I Dec: SyncPositionMap watchingrecording total: 2 entries
2016-08-03 09:44:46.044082 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:44:46.047114 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:46.047164 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:46.047198 I Player(0): JumpToProgram - end
2016-08-03 09:44:46.047226 I Player(0): Waiting for video buffers...
2016-08-03 09:44:46.050720 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:44:46.054658 D LastPlayPos frame=147
2016-08-03 09:44:46.151824 N Player(0): Waited 104ms for video buffers AAAAAAAAAAAAAA
2016-08-03 09:44:46.230329 I VidOutVDPAU: Added 1 new buffers. New buffer size 15 (3 decode and 12 process)
2016-08-03 09:44:46.256059 N Player(0): Waited 208ms for video buffers LLLLAAAAAAAAAAA
2016-08-03 09:44:46.266144 I VidOutVDPAU: Created VDPAU decoder (3 ref frames)
2016-08-03 09:44:46.358754 I VDPAU: Added 2 output surfaces (total 4, max 4)
2016-08-03 09:44:46.362997 I ALSA: WriteAudio: buffer underrun
2016-08-03 09:44:46.395825 I Player(0): Video is 4.3203 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:46.395848 I AOBase: Pause 1
2016-08-03 09:44:46.395912 I Player(0): Waiting for video buffers...
2016-08-03 09:44:46.401383 I Player(0): Video is 5.36522 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:46.401406 I AOBase: Pause 1
2016-08-03 09:44:46.401493 I Player(0): Waiting for video buffers...
2016-08-03 09:44:46.403485 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:46.412095 I Player(0): Video is 5.9364 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:46.412199 I Player(0): Waiting for video buffers...
2016-08-03 09:44:46.427853 I Player(0): Video is 4.8023 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:46.427931 I Player(0): Waiting for video buffers...
2016-08-03 09:44:46.443517 I Player(0): Video is 3.70797 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:44:46.443596 I Player(0): Waiting for video buffers...
2016-08-03 09:44:46.464283 I AOBase: Pause 0
2016-08-03 09:44:46.475282 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:44:49.184848 I Player(0): FPS: 10.20 Mean: 98015 Std.Dev: 593034 CPUs: 11% 3% 15% 3% 
2016-08-03 09:44:52.624511 I ScreenSaverX11Private: Calling xscreensaver-command -deactivate
2016-08-03 09:44:53.184845 I Player(0): FPS: 25.00 Mean: 39993 Std.Dev: 69 CPUs: 10% 2% 5% 3% 
2016-08-03 09:44:54.824741 I TV::CommitQueuedInput(): livetv(1) qchannum(266) qchanid(0)
2016-08-03 09:44:54.831062 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(GET_FREE_INPUT_INFO 17) called from UI thread
2016-08-03 09:44:54.837882 I AOBase: Pause 1
2016-08-03 09:44:54.837904 D TV::PauseLiveTV(): player ctx 0
2016-08-03 09:44:54.895218 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:44:55.546709 D TV::UnpauseLiveTV(): player ctx 0
2016-08-03 09:44:55.548026 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:44:55.548219 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 7 entries
DUMMY: 23806 (07:44:16 to 07:44:19)
DVB: 23806 (07:44:19 to 07:44:29) discontinuous
DUMMY: 8006 (07:44:29 to 07:44:32) discontinuous
DVB: 8006 (07:44:32 to 07:44:41) discontinuous
DUMMY: 3104 (07:44:41 to 07:44:43) discontinuous
* DVB: 3104 (07:44:43 to 07:44:55) discontinuous
DUMMY: 7370 (07:44:55 to 08:00:00) discontinuous

2016-08-03 09:44:55.552641 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(-1)
2016-08-03 09:44:55.552690 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@6: \'7370_20160803074455\')
2016-08-03 09:44:55.552738 I Player(0): Play( 1.0, normal 1, unpause audio 0)
2016-08-03 09:44:55.606876 D MythCoreContext::SendReceiveStringList(): SendReceiveStringList(QUERY_SG_FILEQUERY,mythtv...) called from UI thread
2016-08-03 09:44:55.617300 I AOBase: Pause 1
2016-08-03 09:44:55.618237 I Player(0): JumpToProgram - start
2016-08-03 09:44:55.622032 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 6 -> ProgramInfo(7370_20160803074455.ts): channame(Viasat Explore HD) startts(Wed Aug 3 06:55:00 2016 GMT) endts(Wed Aug 3 08:00:00 2016 GMT)
recstartts(Wed Aug 3 07:44:55 2016 GMT) recendts(Wed Aug 3 08:00:00 2016 GMT)
title(Transporty gigantĂłw)
2016-08-03 09:44:55.624745 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:44:55.624780 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:44:55.624823 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:44:55.624842 I AOBase: Pause 1
2016-08-03 09:44:55.640158 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(6)
2016-08-03 09:44:55.640209 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@6: \'7370_20160803074455\')
2016-08-03 09:44:55.646438 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:44:55.646464 I Player(0): ClearAfterSeek(1)
2016-08-03 09:44:55.646478 I VidOutVDPAU: ClearAfterSeek()
2016-08-03 09:44:55.646491 I VidOutVDPAU: DiscardFrames(0)
2016-08-03 09:44:55.646539 I VideoBuffers::DiscardFrames(0): UUDUUDAFUAUUFDA
2016-08-03 09:44:55.646581 I VideoBuffers::DiscardFrames(0): AADAADAFAAAAFDA -- done
2016-08-03 09:44:55.646612 I VidOutVDPAU: DiscardFrames() 3: AADAADAFAAAAFDA -- done()
2016-08-03 09:44:55.646644 I Dec: Reset: Video 1, Seek 1, File 1
2016-08-03 09:44:55.646688 I Player(0): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2016-08-03 09:44:55.649381 I VDP: GetFilteredDeint(vdpauadvanced) : vdpau -> \'vdpauadvanced\'
2016-08-03 09:44:55.652305 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:44:55.662328 I AOBase: OutputAudioLoop: audio paused
2016-08-03 09:45:08.442746 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): ReloadAll(): Added 1 recording(s)
2016-08-03 09:45:08.442934 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): LiveTVChain has 8 entries
DUMMY: 23806 (07:44:16 to 07:44:19)
DVB: 23806 (07:44:19 to 07:44:29) discontinuous
DUMMY: 8006 (07:44:29 to 07:44:32) discontinuous
DVB: 8006 (07:44:32 to 07:44:41) discontinuous
DUMMY: 3104 (07:44:41 to 07:44:43) discontinuous
DVB: 3104 (07:44:43 to 07:44:55) discontinuous
* DUMMY: 7370 (07:44:55 to 07:45:08) discontinuous
DVB: 7370 (07:45:08 to 08:00:00) discontinuous

2016-08-03 09:45:08.443129 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): JumpToNext: 1 -> 0
2016-08-03 09:45:08.443146 I LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): SwitchTo(7)
2016-08-03 09:45:08.443185 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): Entry@7: \'7370_20160803074508\')
2016-08-03 09:45:08.443202 I Player(0): JumpToProgram - start
2016-08-03 09:45:08.446834 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): DoGetNextProgram: 7 -> ProgramInfo(7370_20160803074508.ts): channame(Viasat Explore HD) startts(Wed Aug 3 06:55:00 2016 GMT) endts(Wed Aug 3 08:00:00 2016 GMT)
recstartts(Wed Aug 3 07:45:08 2016 GMT) recendts(Wed Aug 3 08:00:00 2016 GMT)
title(Transporty gigantĂłw)
2016-08-03 09:45:08.449816 D Player(0): InitFilters() vo \'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2016-08-03 09:45:08.449849 I FilterManager: GetFilterInfo(convert) returning: 0x0
2016-08-03 09:45:08.449892 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2016-08-03 09:45:08.450070 I FileRingBuf(myth://mythtv/3104_20160803074443.ts): OpenFile(myth://mythtv/7370_20160803074508.ts, 10000 ms)
2016-08-03 09:45:10.083724 W RingBuf(myth://mythtv/7370_20160803074508.ts): Not starting read ahead thread, already running
2016-08-03 09:45:11.024361 D AFD: PlayerFlags: 0xc0
2016-08-03 09:45:11.024405 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:45:11.024428 N Player(0): Forcing decode extra audio option on (Video method requires it).
2016-08-03 09:45:11.024445 D AFD: OpenFile -- begin
2016-08-03 09:45:11.024992 I AFD: Buffer size: 32768 streamed 1 seekable 1
2016-08-03 09:45:11.753406 I AFD: File successfully opened after 728ms
2016-08-03 09:45:11.753458 I AFD: Stream #0, has id 0x202 codec id H264, type Video, bitrate 0 at 0x2d6c1c0
2016-08-03 09:45:11.753483 I AFD: Stream #1, has id 0x266 codec id MP2, type Audio, bitrate 192000 at 0x3794220
2016-08-03 09:45:11.753499 I AFD: codec MP2 has 2 channels
2016-08-03 09:45:11.753511 I AFD: Looking for decoder for MP2
2016-08-03 09:45:11.753850 I AFD: Opened codec 0x3136e60, id(MP2) type(Audio)
2016-08-03 09:45:11.753945 I AFD: Audio Track #1, of type (Normalny) is A/V stream #1 (id=0x266) and has 2 channels in the Polish language(7368556).
2016-08-03 09:45:11.753959 I AFD: Trying to select best video track
2016-08-03 09:45:11.753999 I AFD: Selected track #0 (id 0x202 codec id H264, type Video, bitrate 500000 at 0x2d6c1c0)
2016-08-03 09:45:11.754171 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2016-08-03 09:45:11.754196 I VDP: LoadBestPreferences(2048x2048, 0)
2016-08-03 09:45:11.754239 I VDP: LoadBestPreferences(2048x2048, 60)
2016-08-03 09:45:11.754278 I VDP: LoadBestPreferences(1920x1080, 60)
2016-08-03 09:45:11.754331 I AFD: Using 1 CPUs for decoding
2016-08-03 09:45:11.754349 I AFD: InitVideoCodec() 0x2cd9ee0 id(H264) type (Video).
2016-08-03 09:45:11.754401 I AFD: Selected FPS is 25 (avg 25 codec 25 container 90000 estimated 50)
2016-08-03 09:45:11.754426 I Player(0): SetFrameInterval ps:0 scan:3
2016-08-03 09:45:11.754457 I VidOutVDPAU: InputChanged(1920,1080,1.77778) \'H.264 VDPAU\'->\'H.264 VDPAU\'
2016-08-03 09:45:11.755933 I Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 1080) ->Interlaced Scan
2016-08-03 09:45:11.756009 I AFD: Using vdpau for video decoding
2016-08-03 09:45:11.756481 I AFD: Opened codec 0x2cd9ee0, id(H264) type(Video)
2016-08-03 09:45:11.756549 I AFD: Selected track 1: Polish MP2 2ch (A/V Stream #1)
2016-08-03 09:45:11.756578 I AFD: Audio data is planar
2016-08-03 09:45:11.756602 I AFD: Initializing audio parms from audio track #1
2016-08-03 09:45:11.756654 I AFD: Audio format changed 
from id(NONE) -1Hz -1/-1ch -1bps (profile 0) to id( MP2) 48000Hz 2/2ch 16bps (profile 0)
2016-08-03 09:45:11.756678 I AOBase: Reconfigure(): No change -> exiting
2016-08-03 09:45:11.756707 I Dec: Selected track #1 (type 2) in the Unknown language(0)
2016-08-03 09:45:11.756729 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2016-08-03 09:45:11.759001 I Dec: Position map filled from DB to: 0
2016-08-03 09:45:11.759025 I Dec: Duration map filled from DB to: 0
2016-08-03 09:45:11.759043 I Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-08-03 09:45:11.759066 I Player(0): Filling position map from 1 to end
2016-08-03 09:45:11.759877 I Dec: Position map filled from Encoder to: 40
2016-08-03 09:45:11.759895 I Dec: Duration map filled from Encoder to: 40
2016-08-03 09:45:11.759912 I Dec: SyncPositionMap watchingrecording total: 2 entries
2016-08-03 09:45:11.759932 I Dec: SyncPositionMap, new totframes: 40, new length: 1, posMap size: 2
2016-08-03 09:45:11.760959 I AFD: Partial position map found
2016-08-03 09:45:11.760986 I AFD: Successfully opened decoder for file: "myth://mythtv/7370_20160803074508.ts". novideo(0)
2016-08-03 09:45:11.764412 I Using file start
2016-08-03 09:45:11.765141 I Saving DefaultChanid disabled by NoRememberLastTVChan=1
2016-08-03 09:45:11.908506 I Player(0): Play( 1.0, normal 1, unpause audio 1)
2016-08-03 09:45:11.909312 I AOBase: Pause 0
2016-08-03 09:45:11.909329 I Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2016-08-03 09:45:11.909361 I Player(0): Play speed: rate: 25 speed: 1 skip: 1 => new interval 40000
2016-08-03 09:45:11.911250 I Dec: Position map filled from DB to: 0
2016-08-03 09:45:11.911280 I Dec: Duration map filled from DB to: 0
2016-08-03 09:45:11.911329 I Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2016-08-03 09:45:11.911359 I Player(0): Filling position map from 1 to end
2016-08-03 09:45:11.912412 I Dec: Position map filled from Encoder to: 40
2016-08-03 09:45:11.912439 I Dec: Duration map filled from Encoder to: 40
2016-08-03 09:45:11.912464 I Dec: SyncPositionMap watchingrecording total: 2 entries
2016-08-03 09:45:11.912949 I VDP: GetFilteredDeint() : vdpau -> \'vdpauadvanceddoublerate\'
2016-08-03 09:45:11.916019 I VidOutVDPAU: Enabled deinterlacing.
2016-08-03 09:45:11.916068 I Player(0): Stretch Factor 1, allow passthru 
2016-08-03 09:45:11.916102 I Player(0): JumpToProgram - end
2016-08-03 09:45:11.916129 I Player(0): Waiting for video buffers...
2016-08-03 09:45:11.958888 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:45:12.019390 N Player(0): Waited 103ms for video buffers AAAAAAAFAAAAFAA
2016-08-03 09:45:12.073973 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.074374 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.074773 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.075022 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.075409 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.075609 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.076944 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.077346 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.078026 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.078371 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.078654 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.078867 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.085288 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.085407 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.086176 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.086290 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.088431 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.088623 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.090058 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.090210 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.090988 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.091095 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.093086 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.093205 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.094598 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.094835 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.095665 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.095818 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.097770 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.097901 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.127861 I Player(0): Video is 3.1125 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:45:12.127885 I AOBase: Pause 1
2016-08-03 09:45:12.127994 I Player(0): Video is 3.77187 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:45:12.128009 I AOBase: Pause 1
2016-08-03 09:45:12.128087 I Player(0): Video is 4.0164 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:45:12.128100 I AOBase: Pause 1
2016-08-03 09:45:12.128171 I Player(0): Video is 3.9498 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:45:12.128186 I AOBase: Pause 1
2016-08-03 09:45:12.128256 I Player(0): Video is 3.64985 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:45:12.128270 I AOBase: Pause 1
2016-08-03 09:45:12.128337 I Player(0): Video is 3.17488 frames behind audio (too slow), dropping frame to catch up.
2016-08-03 09:45:12.128350 I AOBase: Pause 1
2016-08-03 09:45:12.128408 I AOBase: Pause 0
2016-08-03 09:45:12.129046 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.129170 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.130129 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.130324 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.132394 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.132555 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.133828 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.133948 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.134749 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.134878 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.136269 I AOBase: OutputAudioLoop: Play Event
2016-08-03 09:45:12.137200 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.137435 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.138805 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.138975 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.166349 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.166489 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.204378 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.204505 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.245032 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.245350 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.284700 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.284854 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.323958 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.324075 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.363950 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.364078 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.404291 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.404525 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.444734 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.444886 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.484373 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.484494 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.524570 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.524710 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.564078 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.564298 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.603932 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.604078 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.644128 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.644255 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.684646 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.684792 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.724478 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.724762 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.763743 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.763905 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.804013 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.804135 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.844721 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.844851 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.885229 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.885638 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.923732 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.923896 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.964567 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:12.964793 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.003905 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.004045 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.044447 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.044723 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.084259 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.084419 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.124531 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.124688 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.163778 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.163917 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.204666 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.204901 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.243956 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.244113 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.284380 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.284519 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.324085 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.324212 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.363389 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.363622 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.412726 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.412870 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.443764 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.443884 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.483896 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.484016 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.523155 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.523385 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.563897 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.564047 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.603278 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.603399 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.643621 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.643741 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.683399 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.683760 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.724365 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.724604 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.763559 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.763684 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.803200 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.803324 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.843421 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.843605 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.883525 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.883652 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.923126 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.923237 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.963175 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:13.963301 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.040292 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.040475 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.044009 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.044149 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.083092 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.083205 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.123409 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.123519 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.143220 I Player(0): FPS: 4.77 Mean: 209578 Std.Dev: 1726077 CPUs: 8% 2% 9% 4% 
2016-08-03 09:45:14.164124 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.164380 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.203423 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.203556 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.243290 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.243420 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.283931 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.284058 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.324261 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.324466 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.363795 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.363926 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.403401 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.403531 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.443544 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.443667 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.485002 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.485254 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.523641 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.523784 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.563772 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.563885 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.604026 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.604159 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.643809 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.644010 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.684029 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.684168 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.723089 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.723201 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.764369 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.764530 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.804680 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.804920 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.843847 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.843996 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.883456 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.883601 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.923667 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.923805 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.964180 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:14.964396 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.003954 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.004099 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.043146 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.043265 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.083338 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.083455 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.123720 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.123951 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.164021 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.164171 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.203274 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.203386 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.243988 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.244109 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.283988 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.284234 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.323576 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.323735 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.363220 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.363362 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.403345 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.403467 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.444280 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.444527 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.484080 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.484222 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.523212 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.523318 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.563517 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.563659 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.603557 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.603796 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.644107 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.644258 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.683869 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.683998 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.723538 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.723666 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.763953 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.764217 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.783254 D LastPlayPos frame=100
2016-08-03 09:45:15.803944 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.804089 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.843690 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.843805 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.883940 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.884053 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.924465 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.924748 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.963263 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:15.963418 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.003683 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.003889 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.043266 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.043388 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.084704 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.084954 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.103263 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- begin
2016-08-03 09:45:16.103340 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- end
2016-08-03 09:45:16.124396 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.124536 E VDPAU: Error at mythrender_vdpau.cpp:1596 (#25, A catch-all error, used when no other error code applies.)
2016-08-03 09:45:16.144383 D TV::HandleStateChange(): (0) -- begin
2016-08-03 09:45:16.144416 I TV::HandleStateChange(): Attempting to change from WatchingLiveTV to None
2016-08-03 09:45:16.144458 D TV::StopStuff(): For player ctx 0 -- begin
2016-08-03 09:45:16.144483 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- begin
2016-08-03 09:45:16.144527 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- end
2016-08-03 09:45:16.144550 I Player(0): StopPlaying - begin
2016-08-03 09:45:16.148442 I Player(0): Decoder thread exiting.
2016-08-03 09:45:16.148736 I Player(0): Exited decoder loop.
2016-08-03 09:45:16.161662 I VidOutVDPAU: DiscardFrames(1)
2016-08-03 09:45:16.161772 I VideoBuffers::DiscardFrames(1): DAAUUUDAUUUDUUF
2016-08-03 09:45:16.161829 I VideoBuffers::DiscardFrames(1): DAAAAADAAAADAAA -- done
2016-08-03 09:45:16.161861 I VidOutVDPAU: DiscardFrames() 3: DAAAAADAAAADAAA -- done()
2016-08-03 09:45:16.166195 W MythPainter: 8 images not yet de-allocated.
2016-08-03 09:45:16.166229 I VDPAU Painter: Clearing VDPAU painter cache.
2016-08-03 09:45:16.268210 I Using 1920x1080 50.000 Hz
2016-08-03 09:45:16.268264 I SwitchToGUI: Switched to 1920x1080 50.000 Hz
2016-08-03 09:45:16.269736 I AOBase: Killing AudioOutputDSP
2016-08-03 09:45:16.310372 I AOBase: OutputAudioLoop: Stop Event
2016-08-03 09:45:16.310389 I AOBase: kickoffOutputAudioLoop exiting
2016-08-03 09:45:16.651970 I Player(0): StopPlaying - end
2016-08-03 09:45:16.651991 I TV::StopStuff(): Stopping ring buffer
2016-08-03 09:45:16.707717 I TV::StopStuff(): Stopping player
2016-08-03 09:45:16.707736 I TV::StopStuff(): stopping recorder
2016-08-03 09:45:16.739865 D TV::StopStuff(): -- end
2016-08-03 09:45:16.739906 I TV::HandleStateChange(): Changing from WatchingLiveTV to None
2016-08-03 09:45:16.740310 D TV::HandleStateChange(): (0) -- end
2016-08-03 09:45:16.740337 I TV::StartTV(): Exiting main playback loop.
2016-08-03 09:45:16.740358 I TV::StartTV(): -- process events 2 begin
2016-08-03 09:45:16.740684 D ScreenSaverX11Private: StopTimer
2016-08-03 09:45:16.742078 I TV::StartTV(): -- process events 2 end
2016-08-03 09:45:16.742105 D TV::~TV(): -- begin
2016-08-03 09:45:16.755368 I TV::~TV(): -- lock
2016-08-03 09:45:16.756588 I Player(0): StopPlaying - begin
2016-08-03 09:45:16.756633 I Player(0): Exited decoder loop.
2016-08-03 09:45:16.756651 I Player(0): StopPlaying - end
2016-08-03 09:45:16.764879 D LiveTVChain(live-FE-Livingroom-2016-08-03T07:44:14Z): dtor
2016-08-03 09:45:16.765807 D TV::~TV(): -- end
2016-08-03 09:45:16.766609 N Resuming idle timer
2016-08-03 09:45:16.766677 D TV::StartTV(): -- end
2016-08-03 09:45:21.763639 D Running HouseKeeper.
2016-08-03 09:45:21.763660 D Checking to run HardwareProfiler
2016-08-03 09:46:21.763492 D Running HouseKeeper.
2016-08-03 09:46:21.763512 D Checking to run HardwareProfiler

comment:2 Changed 3 years ago by warpme@…

looks like not only me had this problem:https://forum.mythtv.org/viewtopic.php?f=11&t=845

comment:3 Changed 3 years ago by Peter Bennett

I cannot see your video. That URL asks me for a user id and password.

comment:4 Changed 3 years ago by warpme@…

Peter, I lost faith will anybody look at this ticket so I deleted file. Sorry for that! File is here http://warped.homenet.org/looped-playback.zip

The whole issue is interesting as it has kind of historical memory. I need to enter TV channel from particular set of liveTV channels if I want to trigger issue in future.

Namely: walking on any 1080i H264 channels - no problems.

To trigger issue I need:

1.Enter any SD H264 channel from exact multiplex1

2.Enter HD H264 channel from exact multiplex2

3.Enter HD H264 channel from exact multiplex3

Only such combination triggers issue.

Key seems to entering any SD H264 channel from exact multiplex1. Without this steep I can walk on all 60+ HD channels no problem.

Issue is only on Nvidia with Nvidia closed drivers. All works OK on Intel VAAPI and ATI Mesa VDPAU. On Nvidia it seems to not dependent on Nvidia BLOB version (tested legacy & current versions) nor HW (tested ION, ION2 and GT610M).

I believe it is result how MythTV uses VDPAU on Nvidia drivers as I remember situation when gigem was updating scheduler on master. In that time there was short time window with bug about how LiveTV channel change works within the same mplex (mythtv was changing channels within the same mplex like between different mplexes). With this bug looped issue disappeared. This suggest me that issue is somehow timing related...

Maybe we should made tests with mod where LiveTV channel change resets VDPAU on every channel change?

comment:5 Changed 3 years ago by Peter Bennett

It looks like it is getting an error decoding every frame after the channel change. Then because frames are not decoded it is playing what is left in the buffer and that is the last few frames over and over.

In your output you can see it is calling InitVideoCodec after each channel change. This proves it is already resetting VDPAU each time, as you suggested. Also after the InitVideoCodec log message you can see "Opened codec 0x2cd9ee0, id(H264) type(Video)" each time.

I wonder if the codec is closed before being opened again. There is no log message for closing codec.

I cannot test your situation because all of my channels are encrypted and I cannot switch channels to the same multiplex without complete retuning being done. Also we have only 720p H264 channels.

Note: See TracTickets for help on using tickets.