Ticket #10245: verbose_logs_playback_extra.txt

File verbose_logs_playback_extra.txt, 21.6 KB (added by Kenni Lund [kenni a kelu dot dk], 12 years ago)

-v playback,extra

Line 
12012-01-08 17:30:13.424 TV: StartTV() -- begin
22012-01-08 17:30:13.424 TV: ctor -- begin
32012-01-08 17:30:13.433 TV: ctor -- end
42012-01-08 17:30:13.433 TV: Init -- begin
52012-01-08 17:30:13.448 TV: DrawUnusedRects() -- begin
62012-01-08 17:30:13.448 TV: DrawUnusedRects() -- end
72012-01-08 17:30:13.475 TV: Init -- end
82012-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
92012-01-08 17:30:13.477 TV: tv->Playback() -- begin
102012-01-08 17:30:13.485 TV: HandleStateChange(0) -- begin
112012-01-08 17:30:13.485 TV: Attempting to change from None to WatchingVideo
122012-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)
132012-01-08 17:30:13.485 SendReceiveStringList(QUERY_FILE_EXISTS,Samples/dyreboern.mpg...) called from UI thread
142012-01-08 17:30:13.496 TV: StartPlayer(0, WatchingVideo, main) -- begin
152012-01-08 17:30:13.496 TV: Elapsed time since TV constructor was called: 72 ms
162012-01-08 17:30:13.502 FilterManager: filters[adjust] = 0x7f11e40869d0
172012-01-08 17:30:13.503 FilterManager: filters[bobdeint] = 0x7f11e40858e0
182012-01-08 17:30:13.506 FilterManager: filters[crop] = 0x7f11e49b0110
192012-01-08 17:30:13.508 FilterManager: filters[denoise3d] = 0x7f11e49b0ac0
202012-01-08 17:30:13.510 FilterManager: filters[fieldorderdoubleprocessdeint] = 0x7f11e49b1510
212012-01-08 17:30:13.511 FilterManager: filters[forceyv12] = 0x7f11e40994e0
222012-01-08 17:30:13.511 FilterManager: filters[forceyuv422p] = 0x7f11e410fc00
232012-01-08 17:30:13.511 FilterManager: filters[forcergb24] = 0x7f11e40aa8d0
242012-01-08 17:30:13.512 FilterManager: filters[forceargb32] = 0x7f11e40091a0
252012-01-08 17:30:13.514 FilterManager: filters[greedyhdeint] = 0x7f11e49b25b0
262012-01-08 17:30:13.514 FilterManager: filters[greedyhdoubleprocessdeint] = 0x7f11e49b26a0
272012-01-08 17:30:13.515 FilterManager: filters[invert] = 0x7f11e4005e00
282012-01-08 17:30:13.517 FilterManager: filters[ivtc] = 0x7f11e49b34e0
292012-01-08 17:30:13.522 FilterManager: filters[kerneldeint] = 0x7f11e49b3610
302012-01-08 17:30:13.522 FilterManager: filters[kerneldoubleprocessdeint] = 0x7f11e49b1c90
312012-01-08 17:30:13.525 FilterManager: filters[linearblend] = 0x7f11e4085f20
322012-01-08 17:30:13.526 FilterManager: filters[onefield] = 0x7f11e49b44c0
332012-01-08 17:30:13.528 FilterManager: filters[postprocess] = 0x7f11e49b6690
342012-01-08 17:30:13.531 FilterManager: filters[quickdnr] = 0x7f11e49b55f0
352012-01-08 17:30:13.533 FilterManager: filters[yadifdeint] = 0x7f11e49b5580
362012-01-08 17:30:13.533 FilterManager: filters[yadifdoubleprocessdeint] = 0x7f11e49b9030
372012-01-08 17:30:13.590 Launching: ps -ae | grep pulseaudio > /dev/null
382012-01-08 17:30:13.599 PID 5123: launched
392012-01-08 17:30:13.683 PID 5123: exited: status=256, result=1
402012-01-08 17:30:13.797 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
412012-01-08 17:30:13.799 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
422012-01-08 17:30:13.800 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
432012-01-08 17:30:13.801 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
442012-01-08 17:30:13.802 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
452012-01-08 17:30:13.804 [mpeg2video @ 0x7f1210b9e6e0]mpeg_decode_postinit() failure
462012-01-08 17:30:14.079 AFD: Stream #0, has id 0xb54 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x7f11e49c7c40
472012-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()
482012-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()
492012-01-08 17:30:14.087 VDP: LoadBestPreferences(2048x2048, 0)
502012-01-08 17:30:14.087 VDP: LoadBestPreferences(2048x2048, 60)
512012-01-08 17:30:14.088 VDP: LoadBestPreferences(720x576, 60)
522012-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()
532012-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()
542012-01-08 17:30:14.093 VDP: LoadBestPreferences(2048x2048, 0)
552012-01-08 17:30:14.093 VDP: LoadBestPreferences(2048x2048, 60)
562012-01-08 17:30:14.093 VDP: LoadBestPreferences(720x576, 60)
572012-01-08 17:30:14.094 AFD: Using 1 CPUs for decoding
582012-01-08 17:30:14.094 AFD: InitVideoCodec() 0x7f11e4979220 id(MPEG2VIDEO) type (Video).
592012-01-08 17:30:14.094 AFD: Selected FPS is 25 (avg 31.6667 stream 25 container 90000 estimated 25)
602012-01-08 17:30:14.094 Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
612012-01-08 17:30:14.096 AFD: Using vdpau for video decoding
622012-01-08 17:30:14.097 AFD: Looking for decoder for MPEG2VIDEO
632012-01-08 17:30:14.097 AFD: Opened codec 0x7f11e4979220, id(MPEG2VIDEO) type(Video)
642012-01-08 17:30:14.097 AFD: Stream #1, has id 0xb55 codec id MP2, type Audio, bitrate 192000 at 0x7f11e4979840
652012-01-08 17:30:14.098 AFD: codec MP2 has 2 channels
662012-01-08 17:30:14.098 AFD: Looking for decoder for MP2
672012-01-08 17:30:14.098 AFD: Opened codec 0x7f11e4979a60, id(MP2) type(Audio)
682012-01-08 17:30:14.098 AFD: Stream #2, has id 0xb5d codec id DVB_VBI, type Data, bitrate 0 at 0x7f11e49c8f70
692012-01-08 17:30:14.098 AFD: Teletext stream #0 (Menu) is in the Danish language on page 1 0.
702012-01-08 17:30:14.098 AFD: data codec (Data)
712012-01-08 17:30:14.133 Launching: ps -ae | grep pulseaudio > /dev/null
722012-01-08 17:30:14.142 PID 5127: launched
732012-01-08 17:30:14.284 PID 5127: exited: status=256, result=1
742012-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
752012-01-08 17:30:14.335 AudioPlayer: Enabling Audio
762012-01-08 17:30:14.336 Dec: Selected track #1 in the Unknown language(0)
772012-01-08 17:30:14.336 Dec: Selected track #1 in the Danish language(6578542)
782012-01-08 17:30:14.336 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
792012-01-08 17:30:14.341 Dec: SyncPositionMap prerecorded, from DB: 0 entries
802012-01-08 17:30:14.341 AFD: Recording has no position -- using libavformat seeking.
812012-01-08 17:30:14.341 AFD: Successfully opened decoder for file: "myth://Videos@10.10.10.50:6543/Samples/dyreboern.mpg". novideo(0)
822012-01-08 17:30:14.347 VideoOutput: Allowed renderers: vdpau
832012-01-08 17:30:14.347 VideoOutput: Allowed renderers (filt: vdpau): vdpau
842012-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()
852012-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()
862012-01-08 17:30:14.353 VDP: LoadBestPreferences(2048x2048, 0)
872012-01-08 17:30:14.353 VDP: LoadBestPreferences(2048x2048, 60)
882012-01-08 17:30:14.353 VDP: LoadBestPreferences(720x576, 60)
892012-01-08 17:30:14.353 VideoOutput: Preferred renderer: vdpau
902012-01-08 17:30:14.353 VideoOutput: Trying video renderer: 'vdpau'
912012-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()
922012-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()
932012-01-08 17:30:14.383 VDP: LoadBestPreferences(2048x2048, 0)
942012-01-08 17:30:14.383 VDP: LoadBestPreferences(2048x2048, 60)
952012-01-08 17:30:14.384 VideoOutWindow::SetPIPState. pip_state: 0]
962012-01-08 17:30:14.384 VDP: LoadBestPreferences(2048x2048, 25)
972012-01-08 17:30:14.384 Clearing OpenGL painter cache.
982012-01-08 17:30:14.392 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
992012-01-08 17:30:14.392 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
1002012-01-08 17:30:14.392 VDP: LoadBestPreferences(720x576, 25)
1012012-01-08 17:30:14.392 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
1022012-01-08 17:30:14.393 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
1032012-01-08 17:30:14.393 VDP: SetVideoRenderer(vdpau)
1042012-01-08 17:30:14.393 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
1052012-01-08 17:30:14.395 Trying to match best refresh rate 25.000Hz
1062012-01-08 17:30:14.396 Trying 1920x1080 50.000 Hz
1072012-01-08 17:30:14.398 Dynamic TwinView rate found, set 50Hz as XRandR 51
1082012-01-08 17:30:14.612 SwitchToVideo: Video size 720 x 576:
109    Switched to displaying resolution 1920 x 1080, 487mm x 274mm
1102012-01-08 17:30:14.617 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1112012-01-08 17:30:14.617 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
1122012-01-08 17:30:14.617 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
1132012-01-08 17:30:14.617 VideoOutput: Actual display dimensions: 487x274 mm  Aspect: 1.77737
1142012-01-08 17:30:14.617 VideoOutput: Estimated window dimensions: 487x274 mm  Aspect: 1.77737
1152012-01-08 17:30:14.688 VDPAU: Created 2 output surfaces.
1162012-01-08 17:30:14.688 VDPAU: Set colorkey to 0x20202
1172012-01-08 17:30:14.688 VDPAU: Version 1
1182012-01-08 17:30:14.688 VDPAU: Information NVIDIA VDPAU Driver Shared Library  270.41.06  Mon Apr 18 15:13:22 PDT 2011
1192012-01-08 17:30:14.688 VDPAU: HQ Scaling not supported.
1202012-01-08 17:30:14.688 VDPAU: MPEG4 hardware acceleration not supported.
1212012-01-08 17:30:14.688 VDPAU: Created VDPAU render device 1920x1080
1222012-01-08 17:30:14.689 VidOutVDPAU: Created VDPAU osd (1920x1080)
1232012-01-08 17:30:14.802 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1242012-01-08 17:30:14.802 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
1252012-01-08 17:30:14.802 VidOutVDPAU: Created VDPAU context (GPU decode)
1262012-01-08 17:30:14.808 Over/underscan. V: 0, H: 0
1272012-01-08 17:30:14.809 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1282012-01-08 17:30:14.809 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
1292012-01-08 17:30:14.809 Player(0): Forcing decode extra audio option on (Video method requires it).
1302012-01-08 17:30:14.809 Player(0): InitFilters() vo '' prog '' over ''
1312012-01-08 17:30:14.809 Player(0): LoadFilters(''..) -> 0x0
1322012-01-08 17:30:14.813 Player(0): Decoder thread starting.
1332012-01-08 17:30:14.830 MythFontProperties: loaded 'CartoGothic Std'
134                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 132
135                        Name: 'basenormal'      Type: 'fontdef'
1362012-01-08 17:30:14.834 OSD: Loaded window osd_message
1372012-01-08 17:30:14.856 MythFontProperties: loaded 'League Gothic'
138                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 164
139                        Name: 'basegothicmedium'        Type: 'fontdef'
1402012-01-08 17:30:14.861 OSD: Loaded window osd_input
1412012-01-08 17:30:14.878 MythFontProperties: loaded 'CartoGothic Std'
142                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 196
143                        Name: 'basesmall'       Type: 'fontdef'
1442012-01-08 17:30:14.879 MythFontProperties: loaded 'League Gothic'
145                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 201
146                        Name: 'basegothicmedium'        Type: 'fontdef'
1472012-01-08 17:30:14.879 MythFontProperties: loaded 'League Gothic'
148                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 206
149                        Name: 'basegothicsmall' Type: 'fontdef'
1502012-01-08 17:30:14.880 MythFontProperties: loaded 'League Gothic'
151                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 211
152                        Name: 'basegothicsmalllightblue'        Type: 'fontdef'
1532012-01-08 17:30:14.884 VidOutVDPAU: Created VDPAU decoder (2 ref frames)
1542012-01-08 17:30:14.887 Detect Letterbox: The source is not a supported frame format (was 11)
1552012-01-08 17:30:14.891 MythFontProperties: loaded 'League Gothic'
156                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 215
157                        Name: 'basegothicmid'   Type: 'fontdef'
1582012-01-08 17:30:14.891 MythFontProperties: loaded 'League Gothic'
159                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 219
160                        Name: 'basegothicmidblue'       Type: 'fontdef'
1612012-01-08 17:30:14.892 MythFontProperties: loaded 'League Gothic'
162                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 224
163                        Name: 'basegothicmidwhite'      Type: 'fontdef'
1642012-01-08 17:30:14.893 MythFontProperties: loaded 'CartoGothic Std'
165                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 229
166                        Name: 'basesmalllightblue'      Type: 'fontdef'
1672012-01-08 17:30:14.943 OSD: Loaded window program_info
1682012-01-08 17:30:14.973 MythFontProperties: loaded 'CartoGothic Std'
169                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 567
170                        Name: 'basesmall'       Type: 'fontdef'
1712012-01-08 17:30:14.974 MythFontProperties: loaded 'League Gothic'
172                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 572
173                        Name: 'basegothicmedium'        Type: 'fontdef'
1742012-01-08 17:30:14.974 MythFontProperties: loaded 'League Gothic'
175                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 577
176                        Name: 'basegothicsmall' Type: 'fontdef'
1772012-01-08 17:30:14.975 MythFontProperties: loaded 'League Gothic'
178                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 582
179                        Name: 'basegothicsmalllightblue'        Type: 'fontdef'
1802012-01-08 17:30:14.975 MythFontProperties: loaded 'League Gothic'
181                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 586
182                        Name: 'basegothicmid'   Type: 'fontdef'
1832012-01-08 17:30:14.976 MythFontProperties: loaded 'League Gothic'
184                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 590
185                        Name: 'basegothicmidblue'       Type: 'fontdef'
1862012-01-08 17:30:14.976 MythFontProperties: loaded 'League Gothic'
187                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 595
188                        Name: 'basegothicmidwhite'      Type: 'fontdef'
1892012-01-08 17:30:14.977 MythFontProperties: loaded 'CartoGothic Std'
190                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 600
191                        Name: 'basesmalllightblue'      Type: 'fontdef'
1922012-01-08 17:30:14.985 OSD: Loaded window browse_info
1932012-01-08 17:30:14.996 MythFontProperties: loaded 'CartoGothic Std'
194                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 685
195                        Name: 'basenormal'      Type: 'fontdef'
1962012-01-08 17:30:15.002 OSD: Loaded window osd_status
1972012-01-08 17:30:15.018 MythFontProperties: loaded 'CartoGothic Std'
198                        Location: /home/kenni/.mythtv/themes/Arclight/osd.xml @ 901
199                        Name: 'basenormal'      Type: 'fontdef'
2002012-01-08 17:30:15.026 OSD: Loaded window osd_program_editor
2012012-01-08 17:30:15.027 OSD: Loaded OSD: size 1920x1080 offset 0+0
2022012-01-08 17:30:15.029 OSD: Created window aa_OSD_SUBTITLES
2032012-01-08 17:30:15.031 Player(0): ClearAfterSeek(0)
2042012-01-08 17:30:15.034 VDP: GetFilteredDeint() : vdpau -> 'vdpauadvanceddoublerate'
2052012-01-08 17:30:15.049 VidOutVDPAU: Enabled deinterlacing.
2062012-01-08 17:30:15.049 VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2072012-01-08 17:30:15.049 VSYNC: RTCVideoSync: Could not open /dev/rtc, Permission denied.
2082012-01-08 17:30:15.052 Player(0): Video timing method: USleep with busy wait
2092012-01-08 17:30:15.052 Player(0): Display Refresh Rate: 50.000 Video Frame Rate: 25.000
2102012-01-08 17:30:15.052 playCtx: StartPlaying(): took 0 ms to start player.
2112012-01-08 17:30:15.052 TV: StartPlayer(0, WatchingVideo, main) -- end ok
2122012-01-08 17:30:15.053 TV: Changing from None to WatchingVideo
2132012-01-08 17:30:15.160 TV: DrawUnusedRects() -- begin
2142012-01-08 17:30:15.161 TV: DrawUnusedRects() -- end
2152012-01-08 17:30:15.161 TV: HandleStateChange(0) -- end
2162012-01-08 17:30:15.162 TV: tv->Playback() -- end
2172012-01-08 17:30:15.162 TV: StartTV -- process events begin
2182012-01-08 17:30:15.168 ScreenSaverX11Private: ResetTimer -- begin
2192012-01-08 17:30:15.168 ScreenSaverX11Private: StopTimer
2202012-01-08 17:30:15.170 ScreenSaverX11Private: StartTimer
2212012-01-08 17:30:15.170 ScreenSaverX11Private: ResetTimer -- end
2222012-01-08 17:30:15.172 Player(0): Enabled deinterlacing
2232012-01-08 17:30:15.179 VDPAU: Added 2 output surfaces (total 4, max 4)
2242012-01-08 17:30:15.317 Player(0): Video is 3.2125 frames behind audio (too slow), dropping frame to catch up.
2252012-01-08 17:30:15.318 Player(0): Video is 3.99062 frames behind audio (too slow), dropping frame to catch up.
2262012-01-08 17:30:15.318 Player(0): Video is 4.3242 frames behind audio (too slow), dropping frame to catch up.
2272012-01-08 17:30:15.318 Player(0): Video is 4.3244 frames behind audio (too slow), dropping frame to catch up.
2282012-01-08 17:30:15.318 Player(0): Video is 4.07455 frames behind audio (too slow), dropping frame to catch up.
2292012-01-08 17:30:15.319 Player(0): Video is 3.6434 frames behind audio (too slow), dropping frame to catch up.
2302012-01-08 17:30:15.319 Player(0): Video is 3.07005 frames behind audio (too slow), dropping frame to catch up.
2312012-01-08 17:30:15.326 AFD: HandleGopStart: gopset not set, syncing positionMap
2322012-01-08 17:30:15.326 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2332012-01-08 17:30:15.326 AFD: HandleGopStart: Initial key frame distance: 15.
234'video_output' mean = '36876.06', std. dev. = '10458.05', fps = '27.12'
2352012-01-08 17:30:20.337 AFD: HandleGopStart: Key frame distance changed from 24 to 9.
2362012-01-08 17:30:21.297 AFD: HandleGopStart: Key frame distance changed from 9 to 24.
2372012-01-08 17:30:22.217 AFD: HandleGopStart: Key frame distance changed from 24 to 23.
238'video_output' mean = '39997.67', std. dev. = '26.19', fps = '25.00'
2392012-01-08 17:30:23.218 AFD: HandleGopStart: Key frame distance changed from 23 to 25.
2402012-01-08 17:30:24.177 AFD: HandleGopStart: Key frame distance changed from 25 to 24.
2412012-01-08 17:30:24.618 AFD: HandleGopStart: Key frame distance changed from 24 to 11.
2422012-01-08 17:30:25.618 AFD: HandleGopStart: Key frame distance changed from 11 to 25.
2432012-01-08 17:30:26.577 AFD: HandleGopStart: Key frame distance changed from 25 to 24.
244'video_output' mean = '39998.26', std. dev. = '27.43', fps = '25.00'
2452012-01-08 17:30:30.717 Player(0): 400 interlaced frames seen.
246'video_output' mean = '39998.19', std. dev. = '18.64', fps = '25.00'
2472012-01-08 17:30:33.058 [mp2 @ 0x7f1210b9e6e0]incomplete frame
2482012-01-08 17:30:33.058 AFD Error: Unknown audio decoding error
2492012-01-08 17:30:33.238 TV: HandleStateChange(0) -- begin
2502012-01-08 17:30:33.238 TV: Attempting to change from WatchingVideo to None
2512012-01-08 17:30:33.238 TV: StopStuff() for player ctx 0 -- begin
2522012-01-08 17:30:33.239 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2532012-01-08 17:30:33.239 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2542012-01-08 17:30:33.239 Player(0): StopPlaying - begin
2552012-01-08 17:30:33.240 Player(0): Decoder thread exiting.
2562012-01-08 17:30:33.240 Player(0): Exited decoder loop.
2572012-01-08 17:30:33.253 VidOutVDPAU: DiscardFrames(1)
2582012-01-08 17:30:33.253 VideoBuffers::DiscardFrames(1): AAAAAUUUUUuUuDDDL
2592012-01-08 17:30:33.253 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAADDDA -- done()
2602012-01-08 17:30:33.253 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAADDDA -- done
2612012-01-08 17:30:33.253 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAADDDA -- done()
2622012-01-08 17:30:33.303 VDPAU Painter: Clearing VDPAU painter cache.
2632012-01-08 17:30:33.303 MythPainter: 43 images not yet de-allocated.
2642012-01-08 17:30:33.394 Trying 1920x1080 0.000 Hz
2652012-01-08 17:30:33.394 SwitchToGUI: Switched to 1920x1080 0.000 Hz
2662012-01-08 17:30:33.401 Player(0): StopPlaying - end
2672012-01-08 17:30:33.402 TV: StopStuff(): stopping ring buffer
2682012-01-08 17:30:33.407 TV: StopStuff(): stopping player
2692012-01-08 17:30:33.407 TV: StopStuff() -- end
2702012-01-08 17:30:33.407 TV: Changing from WatchingVideo to None
2712012-01-08 17:30:33.407 TV: HandleStateChange(0) -- end
2722012-01-08 17:30:33.408 ScreenSaverX11Private: StopTimer
2732012-01-08 17:30:33.408 TV: HandleStateChange(0) -- begin
2742012-01-08 17:30:33.409 TV: Attempting to change from None to None
2752012-01-08 17:30:33.409 TV: HandleStateChange(0) -- end
2762012-01-08 17:30:33.409 TV: StartTV -- process events end
2772012-01-08 17:30:33.409 TV: StartTV -- process events 2 begin
2782012-01-08 17:30:33.409 TV: StartTV -- process events 2 end
2792012-01-08 17:30:33.409 TV::~TV() -- begin
2802012-01-08 17:30:33.409 TV: DrawUnusedRects() -- begin
2812012-01-08 17:30:33.410 TV: DrawUnusedRects() -- end
2822012-01-08 17:30:33.410 TV: DrawUnusedRects() -- begin
2832012-01-08 17:30:33.410 TV: DrawUnusedRects() -- end
2842012-01-08 17:30:33.421 TV::~TV() -- lock
2852012-01-08 17:30:33.421 Player(0): StopPlaying - begin
2862012-01-08 17:30:33.421 Player(0): Exited decoder loop.
2872012-01-08 17:30:33.421 Player(0): StopPlaying - end
2882012-01-08 17:30:33.427 TV::~TV() -- end
2892012-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
2902012-01-08 17:30:33.428 TV: StartTV -- end