2012-01-08 17:30:13.424 TV: StartTV() -- begin
2012-01-08 17:30:13.424 TV: ctor -- begin
2012-01-08 17:30:13.433 TV: ctor -- end
2012-01-08 17:30:13.433 TV: Init -- begin
2012-01-08 17:30:13.448 TV: DrawUnusedRects() -- begin
2012-01-08 17:30:13.448 TV: DrawUnusedRects() -- end
2012-01-08 17:30:13.475 TV: Init -- end
2012-01-08 17:30:13.476 SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STARTED HOSTNAME mc1 CHANID 0 STARTTIME 2012-01-08T17:29:13 SENDER mc1) called from UI thread
2012-01-08 17:30:13.477 TV: tv->Playback() -- begin
2012-01-08 17:30:13.485 TV: HandleStateChange(0) -- begin
2012-01-08 17:30:13.485 TV: Attempting to change from None to WatchingVideo
2012-01-08 17:30:13.485 RingBuf(myth://Videos@10.10.10.50:6543/Samples/dyreboern.mpg): OpenFile(myth://Videos@10.10.10.50:6543/Samples/dyreboern.mpg, 2000 ms)
2012-01-08 17:30:13.485 SendReceiveStringList(QUERY_FILE_EXISTS,Samples/dyreboern.mpg...) called from UI thread
2012-01-08 17:30:13.496 TV: StartPlayer(0, WatchingVideo, main) -- begin
2012-01-08 17:30:13.496 TV: Elapsed time since TV constructor was called: 72 ms
2012-01-08 17:30:13.502 FilterManager: filters[adjust] = 0x7f11e40869d0
2012-01-08 17:30:13.503 FilterManager: filters[bobdeint] = 0x7f11e40858e0
2012-01-08 17:30:13.506 FilterManager: filters[crop] = 0x7f11e49b0110
2012-01-08 17:30:13.508 FilterManager: filters[denoise3d] = 0x7f11e49b0ac0
2012-01-08 17:30:13.510 FilterManager: filters[fieldorderdoubleprocessdeint] = 0x7f11e49b1510
2012-01-08 17:30:13.511 FilterManager: filters[forceyv12] = 0x7f11e40994e0
2012-01-08 17:30:13.511 FilterManager: filters[forceyuv422p] = 0x7f11e410fc00
2012-01-08 17:30:13.511 FilterManager: filters[forcergb24] = 0x7f11e40aa8d0
2012-01-08 17:30:13.512 FilterManager: filters[forceargb32] = 0x7f11e40091a0
2012-01-08 17:30:13.514 FilterManager: filters[greedyhdeint] = 0x7f11e49b25b0
2012-01-08 17:30:13.514 FilterManager: filters[greedyhdoubleprocessdeint] = 0x7f11e49b26a0
2012-01-08 17:30:13.515 FilterManager: filters[invert] = 0x7f11e4005e00
2012-01-08 17:30:13.517 FilterManager: filters[ivtc] = 0x7f11e49b34e0
2012-01-08 17:30:13.522 FilterManager: filters[kerneldeint] = 0x7f11e49b3610
2012-01-08 17:30:13.522 FilterManager: filters[kerneldoubleprocessdeint] = 0x7f11e49b1c90
2012-01-08 17:30:13.525 FilterManager: filters[linearblend] = 0x7f11e4085f20
2012-01-08 17:30:13.526 FilterManager: filters[onefield] = 0x7f11e49b44c0
2012-01-08 17:30:13.528 FilterManager: filters[postprocess] = 0x7f11e49b6690
2012-01-08 17:30:13.531 FilterManager: filters[quickdnr] = 0x7f11e49b55f0
2012-01-08 17:30:13.533 FilterManager: filters[yadifdeint] = 0x7f11e49b5580
2012-01-08 17:30:13.533 FilterManager: filters[yadifdoubleprocessdeint] = 0x7f11e49b9030
2012-01-08 17:30:13.590 Launching: ps -ae | grep pulseaudio > /dev/null
2012-01-08 17:30:13.599 PID 5123: launched
2012-01-08 17:30:13.683 PID 5123: exited: status=256, result=1
2012-01-08 17:30:13.797 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
2012-01-08 17:30:13.799 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
2012-01-08 17:30:13.800 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
2012-01-08 17:30:13.801 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
2012-01-08 17:30:13.802 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
2012-01-08 17:30:13.804 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
2012-01-08 17:30:14.079 AFD: Stream #0, has id 0xb54 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x7f11e49c7c40
2012-01-08 17:30:14.087 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) filt()
2012-01-08 17:30:14.087 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt()
2012-01-08 17:30:14.087 VDP: LoadBestPreferences(2048x2048, 0)
2012-01-08 17:30:14.087 VDP: LoadBestPreferences(2048x2048, 60)
2012-01-08 17:30:14.088 VDP: LoadBestPreferences(720x576, 60)
2012-01-08 17:30:14.093 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) filt()
2012-01-08 17:30:14.093 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt()
2012-01-08 17:30:14.093 VDP: LoadBestPreferences(2048x2048, 0)
2012-01-08 17:30:14.093 VDP: LoadBestPreferences(2048x2048, 60)
2012-01-08 17:30:14.093 VDP: LoadBestPreferences(720x576, 60)
2012-01-08 17:30:14.094 AFD: Using 1 CPUs for decoding
2012-01-08 17:30:14.094 AFD: InitVideoCodec() 0x7f11e4979220 id(MPEG2VIDEO) type (Video).
2012-01-08 17:30:14.094 AFD: Selected FPS is 25 (avg 31.6667 stream 25 container 90000 estimated 25)
2012-01-08 17:30:14.094 Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2012-01-08 17:30:14.096 AFD: Using vdpau for video decoding
2012-01-08 17:30:14.097 AFD: Looking for decoder for MPEG2VIDEO
2012-01-08 17:30:14.097 AFD: Opened codec 0x7f11e4979220, id(MPEG2VIDEO) type(Video)
2012-01-08 17:30:14.097 AFD: Stream #1, has id 0xb55 codec id MP2, type Audio, bitrate 192000 at 0x7f11e4979840
2012-01-08 17:30:14.098 AFD: codec MP2 has 2 channels
2012-01-08 17:30:14.098 AFD: Looking for decoder for MP2
2012-01-08 17:30:14.098 AFD: Opened codec 0x7f11e4979a60, id(MP2) type(Audio)
2012-01-08 17:30:14.098 AFD: Stream #2, has id 0xb5d codec id DVB_VBI, type Data, bitrate 0 at 0x7f11e49c8f70
2012-01-08 17:30:14.098 AFD: Teletext stream #0 (Menu) is in the Danish language on page 1 0.
2012-01-08 17:30:14.098 AFD: data codec (Data)
2012-01-08 17:30:14.133 Launching: ps -ae | grep pulseaudio > /dev/null
2012-01-08 17:30:14.142 PID 5127: launched
2012-01-08 17:30:14.284 PID 5127: exited: status=256, result=1
2012-01-08 17:30:14.326 AO: Opening audio device 'front:CARD=NVidia,DEV=0' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2012-01-08 17:30:14.335 AudioPlayer: Enabling Audio
2012-01-08 17:30:14.336 Dec: Selected track #1 in the Unknown language(0)
2012-01-08 17:30:14.336 Dec: Selected track #1 in the Danish language(6578542)
2012-01-08 17:30:14.336 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2012-01-08 17:30:14.341 Dec: SyncPositionMap prerecorded, from DB: 0 entries
2012-01-08 17:30:14.341 AFD: Recording has no position -- using libavformat seeking.
2012-01-08 17:30:14.341 AFD: Successfully opened decoder for file: "myth://Videos@10.10.10.50:6543/Samples/dyreboern.mpg". novideo(0)
2012-01-08 17:30:14.347 VideoOutput: Allowed renderers: vdpau
2012-01-08 17:30:14.347 VideoOutput: Allowed renderers (filt: vdpau): vdpau
2012-01-08 17:30:14.352 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) filt()
2012-01-08 17:30:14.352 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt()
2012-01-08 17:30:14.353 VDP: LoadBestPreferences(2048x2048, 0)
2012-01-08 17:30:14.353 VDP: LoadBestPreferences(2048x2048, 60)
2012-01-08 17:30:14.353 VDP: LoadBestPreferences(720x576, 60)
2012-01-08 17:30:14.353 VideoOutput: Preferred renderer: vdpau
2012-01-08 17:30:14.353 VideoOutput: Trying video renderer: 'vdpau'
2012-01-08 17:30:14.383 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) filt()
2012-01-08 17:30:14.383 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt()
2012-01-08 17:30:14.383 VDP: LoadBestPreferences(2048x2048, 0)
2012-01-08 17:30:14.383 VDP: LoadBestPreferences(2048x2048, 60)
2012-01-08 17:30:14.384 VideoOutWindow::SetPIPState. pip_state: 0]
2012-01-08 17:30:14.384 VDP: LoadBestPreferences(2048x2048, 25)
2012-01-08 17:30:14.384 Clearing OpenGL painter cache.
2012-01-08 17:30:14.392 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
2012-01-08 17:30:14.392 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2012-01-08 17:30:14.392 VDP: LoadBestPreferences(720x576, 25)
2012-01-08 17:30:14.392 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
2012-01-08 17:30:14.393 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2012-01-08 17:30:14.393 VDP: SetVideoRenderer(vdpau)
2012-01-08 17:30:14.393 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2012-01-08 17:30:14.395 Trying to match best refresh rate 25.000Hz
2012-01-08 17:30:14.396 Trying 1920x1080 50.000 Hz
2012-01-08 17:30:14.398 Dynamic TwinView rate found, set 50Hz as XRandR 51
2012-01-08 17:30:14.612 SwitchToVideo: Video size 720 x 576: 
    Switched to displaying resolution 1920 x 1080, 487mm x 274mm
2012-01-08 17:30:14.617 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-01-08 17:30:14.617 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2012-01-08 17:30:14.617 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2012-01-08 17:30:14.617 VideoOutput: Actual display dimensions: 487x274 mm  Aspect: 1.77737
2012-01-08 17:30:14.617 VideoOutput: Estimated window dimensions: 487x274 mm  Aspect: 1.77737
2012-01-08 17:30:14.688 VDPAU: Created 2 output surfaces.
2012-01-08 17:30:14.688 VDPAU: Set colorkey to 0x20202
2012-01-08 17:30:14.688 VDPAU: Version 1
2012-01-08 17:30:14.688 VDPAU: Information NVIDIA VDPAU Driver Shared Library  270.41.06  Mon Apr 18 15:13:22 PDT 2011
2012-01-08 17:30:14.688 VDPAU: HQ Scaling not supported.
2012-01-08 17:30:14.688 VDPAU: MPEG4 hardware acceleration not supported.
2012-01-08 17:30:14.688 VDPAU: Created VDPAU render device 1920x1080
2012-01-08 17:30:14.689 VidOutVDPAU: Created VDPAU osd (1920x1080)
2012-01-08 17:30:14.802 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-01-08 17:30:14.802 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2012-01-08 17:30:14.802 VidOutVDPAU: Created VDPAU context (GPU decode)
2012-01-08 17:30:14.808 Over/underscan. V: 0, H: 0
2012-01-08 17:30:14.809 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-01-08 17:30:14.809 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2012-01-08 17:30:14.809 Player(0): Forcing decode extra audio option on (Video method requires it).
2012-01-08 17:30:14.809 Player(0): InitFilters() vo '' prog '' over ''
2012-01-08 17:30:14.809 Player(0): LoadFilters(''..) -> 0x0
2012-01-08 17:30:14.813 Player(0): Decoder thread starting.
2012-01-08 17:30:14.830 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 132
                        Name: 'basenormal'      Type: 'fontdef'
2012-01-08 17:30:14.834 OSD: Loaded window osd_message
2012-01-08 17:30:14.856 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 164
                        Name: 'basegothicmedium'        Type: 'fontdef'
2012-01-08 17:30:14.861 OSD: Loaded window osd_input
2012-01-08 17:30:14.878 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 196
                        Name: 'basesmall'       Type: 'fontdef'
2012-01-08 17:30:14.879 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 201
                        Name: 'basegothicmedium'        Type: 'fontdef'
2012-01-08 17:30:14.879 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 206
                        Name: 'basegothicsmall' Type: 'fontdef'
2012-01-08 17:30:14.880 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 211
                        Name: 'basegothicsmalllightblue'        Type: 'fontdef'
2012-01-08 17:30:14.884 VidOutVDPAU: Created VDPAU decoder (2 ref frames)
2012-01-08 17:30:14.887 Detect Letterbox: The source is not a supported frame format (was 11)
2012-01-08 17:30:14.891 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 215
                        Name: 'basegothicmid'   Type: 'fontdef'
2012-01-08 17:30:14.891 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 219
                        Name: 'basegothicmidblue'       Type: 'fontdef'
2012-01-08 17:30:14.892 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 224
                        Name: 'basegothicmidwhite'      Type: 'fontdef'
2012-01-08 17:30:14.893 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 229
                        Name: 'basesmalllightblue'      Type: 'fontdef'
2012-01-08 17:30:14.943 OSD: Loaded window program_info
2012-01-08 17:30:14.973 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 567
                        Name: 'basesmall'       Type: 'fontdef'
2012-01-08 17:30:14.974 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 572
                        Name: 'basegothicmedium'        Type: 'fontdef'
2012-01-08 17:30:14.974 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 577
                        Name: 'basegothicsmall' Type: 'fontdef'
2012-01-08 17:30:14.975 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 582
                        Name: 'basegothicsmalllightblue'        Type: 'fontdef'
2012-01-08 17:30:14.975 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 586
                        Name: 'basegothicmid'   Type: 'fontdef'
2012-01-08 17:30:14.976 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 590
                        Name: 'basegothicmidblue'       Type: 'fontdef'
2012-01-08 17:30:14.976 MythFontProperties: loaded 'League Gothic'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 595
                        Name: 'basegothicmidwhite'      Type: 'fontdef'
2012-01-08 17:30:14.977 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 600
                        Name: 'basesmalllightblue'      Type: 'fontdef'
2012-01-08 17:30:14.985 OSD: Loaded window browse_info
2012-01-08 17:30:14.996 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 685
                        Name: 'basenormal'      Type: 'fontdef'
2012-01-08 17:30:15.002 OSD: Loaded window osd_status
2012-01-08 17:30:15.018 MythFontProperties: loaded 'CartoGothic Std'
                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 901
                        Name: 'basenormal'      Type: 'fontdef'
2012-01-08 17:30:15.026 OSD: Loaded window osd_program_editor
2012-01-08 17:30:15.027 OSD: Loaded OSD: size 1920x1080 offset 0+0
2012-01-08 17:30:15.029 OSD: Created window aa_OSD_SUBTITLES
2012-01-08 17:30:15.031 Player(0): ClearAfterSeek(0)
2012-01-08 17:30:15.034 VDP: GetFilteredDeint() : vdpau -> 'vdpauadvanceddoublerate'
2012-01-08 17:30:15.049 VidOutVDPAU: Enabled deinterlacing.
2012-01-08 17:30:15.049 VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2012-01-08 17:30:15.049 VSYNC: RTCVideoSync: Could not open /dev/rtc, Permission denied.
2012-01-08 17:30:15.052 Player(0): Video timing method: USleep with busy wait
2012-01-08 17:30:15.052 Player(0): Display Refresh Rate: 50.000 Video Frame Rate: 25.000
2012-01-08 17:30:15.052 playCtx: StartPlaying(): took 0 ms to start player.
2012-01-08 17:30:15.052 TV: StartPlayer(0, WatchingVideo, main) -- end ok
2012-01-08 17:30:15.053 TV: Changing from None to WatchingVideo
2012-01-08 17:30:15.160 TV: DrawUnusedRects() -- begin
2012-01-08 17:30:15.161 TV: DrawUnusedRects() -- end
2012-01-08 17:30:15.161 TV: HandleStateChange(0) -- end
2012-01-08 17:30:15.162 TV: tv->Playback() -- end
2012-01-08 17:30:15.162 TV: StartTV -- process events begin
2012-01-08 17:30:15.168 ScreenSaverX11Private: ResetTimer -- begin
2012-01-08 17:30:15.168 ScreenSaverX11Private: StopTimer
2012-01-08 17:30:15.170 ScreenSaverX11Private: StartTimer
2012-01-08 17:30:15.170 ScreenSaverX11Private: ResetTimer -- end
2012-01-08 17:30:15.172 Player(0): Enabled deinterlacing
2012-01-08 17:30:15.179 VDPAU: Added 2 output surfaces (total 4, max 4)
2012-01-08 17:30:15.317 Player(0): Video is 3.2125 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.318 Player(0): Video is 3.99062 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.318 Player(0): Video is 4.3242 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.318 Player(0): Video is 4.3244 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.318 Player(0): Video is 4.07455 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.319 Player(0): Video is 3.6434 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.319 Player(0): Video is 3.07005 frames behind audio (too slow), dropping frame to catch up.
2012-01-08 17:30:15.326 AFD: HandleGopStart: gopset not set, syncing positionMap
2012-01-08 17:30:15.326 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2012-01-08 17:30:15.326 AFD: HandleGopStart: Initial key frame distance: 15.
'video_output' mean = '36876.06', std. dev. = '10458.05', fps = '27.12'
2012-01-08 17:30:20.337 AFD: HandleGopStart: Key frame distance changed from 24 to 9.
2012-01-08 17:30:21.297 AFD: HandleGopStart: Key frame distance changed from 9 to 24.
2012-01-08 17:30:22.217 AFD: HandleGopStart: Key frame distance changed from 24 to 23.
'video_output' mean = '39997.67', std. dev. = '26.19', fps = '25.00'
2012-01-08 17:30:23.218 AFD: HandleGopStart: Key frame distance changed from 23 to 25.
2012-01-08 17:30:24.177 AFD: HandleGopStart: Key frame distance changed from 25 to 24.
2012-01-08 17:30:24.618 AFD: HandleGopStart: Key frame distance changed from 24 to 11.
2012-01-08 17:30:25.618 AFD: HandleGopStart: Key frame distance changed from 11 to 25.
2012-01-08 17:30:26.577 AFD: HandleGopStart: Key frame distance changed from 25 to 24.
'video_output' mean = '39998.26', std. dev. = '27.43', fps = '25.00'
2012-01-08 17:30:30.717 Player(0): 400 interlaced frames seen.
'video_output' mean = '39998.19', std. dev. = '18.64', fps = '25.00'
2012-01-08 17:30:33.058 [mp2 @ 0x7f1210b9e6e0]incomplete frame
2012-01-08 17:30:33.058 AFD Error: Unknown audio decoding error
2012-01-08 17:30:33.238 TV: HandleStateChange(0) -- begin
2012-01-08 17:30:33.238 TV: Attempting to change from WatchingVideo to None
2012-01-08 17:30:33.238 TV: StopStuff() for player ctx 0 -- begin
2012-01-08 17:30:33.239 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-01-08 17:30:33.239 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-01-08 17:30:33.239 Player(0): StopPlaying - begin
2012-01-08 17:30:33.240 Player(0): Decoder thread exiting.
2012-01-08 17:30:33.240 Player(0): Exited decoder loop.
2012-01-08 17:30:33.253 VidOutVDPAU: DiscardFrames(1)
2012-01-08 17:30:33.253 VideoBuffers::DiscardFrames(1): AAAAAUUUUUuUuDDDL
2012-01-08 17:30:33.253 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAADDDA -- done()
2012-01-08 17:30:33.253 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAADDDA -- done
2012-01-08 17:30:33.253 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAADDDA -- done()
2012-01-08 17:30:33.303 VDPAU Painter: Clearing VDPAU painter cache.
2012-01-08 17:30:33.303 MythPainter: 43 images not yet de-allocated.
2012-01-08 17:30:33.394 Trying 1920x1080 0.000 Hz
2012-01-08 17:30:33.394 SwitchToGUI: Switched to 1920x1080 0.000 Hz
2012-01-08 17:30:33.401 Player(0): StopPlaying - end
2012-01-08 17:30:33.402 TV: StopStuff(): stopping ring buffer
2012-01-08 17:30:33.407 TV: StopStuff(): stopping player
2012-01-08 17:30:33.407 TV: StopStuff() -- end
2012-01-08 17:30:33.407 TV: Changing from WatchingVideo to None
2012-01-08 17:30:33.407 TV: HandleStateChange(0) -- end
2012-01-08 17:30:33.408 ScreenSaverX11Private: StopTimer
2012-01-08 17:30:33.408 TV: HandleStateChange(0) -- begin
2012-01-08 17:30:33.409 TV: Attempting to change from None to None
2012-01-08 17:30:33.409 TV: HandleStateChange(0) -- end
2012-01-08 17:30:33.409 TV: StartTV -- process events end
2012-01-08 17:30:33.409 TV: StartTV -- process events 2 begin
2012-01-08 17:30:33.409 TV: StartTV -- process events 2 end
2012-01-08 17:30:33.409 TV::~TV() -- begin
2012-01-08 17:30:33.409 TV: DrawUnusedRects() -- begin
2012-01-08 17:30:33.410 TV: DrawUnusedRects() -- end
2012-01-08 17:30:33.410 TV: DrawUnusedRects() -- begin
2012-01-08 17:30:33.410 TV: DrawUnusedRects() -- end
2012-01-08 17:30:33.421 TV::~TV() -- lock
2012-01-08 17:30:33.421 Player(0): StopPlaying - begin
2012-01-08 17:30:33.421 Player(0): Exited decoder loop.
2012-01-08 17:30:33.421 Player(0): StopPlaying - end
2012-01-08 17:30:33.427 TV::~TV() -- end
2012-01-08 17:30:33.427 SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STOPPED HOSTNAME mc1 CHANID 0 STARTTIME 2012-01-08T17:29:13 SENDER mc1) called from UI thread
2012-01-08 17:30:33.428 TV: StartTV -- end