Ticket #9349: log.rec

File log.rec, 23.5 KB (added by dargllun@…, 9 years ago)

recorded file, frame rate correctly switched to 50Hz

Line 
12010-12-09 19:38:47.811 The NV-CONTROL X extension is not available on screen 0 of ':0.0'.
22010-12-09 19:38:47.812 max_width: 1920 max_height: 1080
32010-12-09 19:38:47.815 user: 1000 effective user: 1000 before privileged thread
42010-12-09 19:38:47.816 user: 1000 effective user: 1000 after privileged thread
52010-12-09 19:38:47.816 user: 1000 effective user: 1000 run_priv_thread
62010-12-09 19:38:47.906 max_width: 1920 max_height: 1080
72010-12-09 19:38:49.118 VDP: decoder<->render support: ffmpeg      null directfb xlib xshm xv-blit opengl vdpau
82010-12-09 19:38:49.119 VDP: decoder<->render support: vdpau       vdpau
92010-12-09 19:38:49.119 VDP: decoder<->render support: libmpeg2    null directfb xlib xshm xv-blit opengl vdpau
102010-12-09 19:38:49.123 VDP: Ignoring profile item 4 (decoder xvmc is not supported (supported: ffmpeg,vdpau,libmpeg2))
112010-12-09 19:38:49.123 VDP: Ignoring profile item 6 (decoder xvmc is not supported (supported: ffmpeg,vdpau,libmpeg2))
122010-12-09 19:38:49.124 VDP: Ignoring profile item 2 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
132010-12-09 19:38:49.126 VDP: Ignoring profile item 15 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
142010-12-09 19:38:49.126 VDP: Ignoring profile item 16 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
152010-12-09 19:38:49.127 VDP: Ignoring profile item 19 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
162010-12-09 19:38:49.127 VDP: Ignoring profile item 20 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
172010-12-09 19:38:49.128 VDP: Ignoring profile item 23 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
182010-12-09 19:38:49.128 VDP: Ignoring profile item 24 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
192010-12-09 19:38:52.829 PreviewQueue: Requesting preview for '17405_20101209193900.mpg_0x0_-1s'
202010-12-09 19:38:52.848 PreviewQueue: Requested preview for '17405_20101209193900.mpg_0x0_-1s'
212010-12-09 19:38:53.005 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
222010-12-09 19:38:53.010 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
232010-12-09 19:38:53.014 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
242010-12-09 19:38:53.020 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
252010-12-09 19:38:53.025 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
262010-12-09 19:38:53.029 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
272010-12-09 19:38:53.038 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
282010-12-09 19:38:53.078 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
292010-12-09 19:38:53.081 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
302010-12-09 19:38:53.083 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
312010-12-09 19:38:53.087 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
322010-12-09 19:38:53.088 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
332010-12-09 19:38:53.094 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
342010-12-09 19:38:53.098 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
352010-12-09 19:38:53.137 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
362010-12-09 19:38:53.145 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
372010-12-09 19:38:53.213 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
382010-12-09 19:38:53.216 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
392010-12-09 19:38:53.219 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
402010-12-09 19:38:53.222 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
412010-12-09 19:38:53.227 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
422010-12-09 19:38:53.228 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
432010-12-09 19:38:53.232 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
442010-12-09 19:38:53.495 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
452010-12-09 19:38:53.773 PreviewQueue: Requesting preview for '17405_20101209193900.mpg_0x0_-1s'
462010-12-09 19:38:53.773 PreviewQueue: Requested preview for '17405_20101209193900.mpg_0x0_-1s'
472010-12-09 19:38:54.029 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
482010-12-09 19:38:54.232 PreviewQueue: Not requesting preview for "Gute Zeiten, schlechte Zeiten":"Tayfun zerstört Aylas Traum von der großen Liebe",as it is already being generated
492010-12-09 19:38:55.347 TV: StartTV() -- begin
502010-12-09 19:38:55.347 TV: ctor -- begin
512010-12-09 19:38:55.348 TV: ctor -- end
522010-12-09 19:38:55.348 TV: Init -- begin
532010-12-09 19:38:55.351 TV: DrawUnusedRects() -- begin
542010-12-09 19:38:55.351 TV: DrawUnusedRects() -- end
552010-12-09 19:38:55.351 TV: DrawUnusedRects() -- begin
562010-12-09 19:38:55.351 TV: DrawUnusedRects() -- end
572010-12-09 19:38:55.396 TV: DrawUnusedRects() -- begin
582010-12-09 19:38:55.396 TV: DrawUnusedRects() -- end
592010-12-09 19:38:55.398 TV: Init -- end
602010-12-09 19:38:55.399 TV: tv->Playback() -- begin
612010-12-09 19:38:55.407 TV: tv->Playback() -- end
622010-12-09 19:38:55.407 TV: StartTV -- process events begin
632010-12-09 19:38:55.408 TV: HandleStateChange(0) -- begin
642010-12-09 19:38:55.410 RingBuf(/var/lib/mythtv/livetv/1514_20101205135529.mpg): OpenFile(/var/lib/mythtv/livetv/1514_20101205135529.mpg, 2000 ms)
652010-12-09 19:38:55.488 TV: DrawUnusedRects() -- begin
662010-12-09 19:38:55.488 TV: DrawUnusedRects() -- end
672010-12-09 19:38:55.488 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
682010-12-09 19:38:55.489 TV: Elapsed time since TV constructor was called: 142 ms
692010-12-09 19:38:55.711 AFD: Stream #0, has id 0x221 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x7007290
702010-12-09 19:38:55.712 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
712010-12-09 19:38:55.712 VDP: LoadBestPreferences(2048x2048, 0)
722010-12-09 19:38:55.712 VDP: LoadBestPreferences(2048x2048, 60)
732010-12-09 19:38:55.712 VDP: LoadBestPreferences(704x576, 60)
742010-12-09 19:38:55.713 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
752010-12-09 19:38:55.713 VDP: LoadBestPreferences(2048x2048, 0)
762010-12-09 19:38:55.713 VDP: LoadBestPreferences(2048x2048, 60)
772010-12-09 19:38:55.713 VDP: LoadBestPreferences(704x576, 60)
782010-12-09 19:38:55.713 AFD: Using 2 CPUs for decoding
792010-12-09 19:38:55.714 AFD: InitVideoCodec() 0x70074b0 id(MPEG2VIDEO) type (Video).
802010-12-09 19:38:55.714 AFD: Selected FPS is 25 (avg 25 stream 25 container 90000 estimated 25)
812010-12-09 19:38:55.714 Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
822010-12-09 19:38:55.714 AFD: Using ffmpeg for video decoding
832010-12-09 19:38:55.714 AFD: Looking for decoder for MPEG2VIDEO
842010-12-09 19:38:55.714 AFD: Stream #1, has id 0x222 codec id MP2, type Audio, bitrate 192000 at 0x7007cc0
852010-12-09 19:38:55.715 AFD: Looking for decoder for MP2
862010-12-09 19:38:55.715 AFD: Stream #2, has id 0x223 codec id MP2, type Audio, bitrate 96000 at 0x70084c0
872010-12-09 19:38:55.715 AFD: Looking for decoder for MP2
882010-12-09 19:38:55.715 AFD: Stream #3, has id 0x227 codec id DVB_TELETEXT, type Subtitle, bitrate 0 at 0x7963a80
892010-12-09 19:38:55.715 AFD: Teletext stream #0 (Caption) is in the German language on page 1 0.
902010-12-09 19:38:55.715 AFD: subtitle codec (Subtitle)
912010-12-09 19:38:55.825 Dec: Selected track #1 in the Unknown language(0)
922010-12-09 19:38:55.825 Dec: Selected track #1 in the German language(6776178)
932010-12-09 19:38:55.825 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
942010-12-09 19:38:56.010 Dec: Position map filled from DB to: 156741
952010-12-09 19:38:56.011 Dec: SyncPositionMap prerecorded, from DB: 10461 entries
962010-12-09 19:38:56.011 Dec: SyncPositionMap, new totframes: 156741, new length: 6269, posMap size: 10461
972010-12-09 19:38:56.011 AFD: Position map found
982010-12-09 19:38:56.011 AFD: Successfully opened decoder for file: "/var/lib/mythtv/livetv/1514_20101205135529.mpg". novideo(0)
992010-12-09 19:38:56.015 VideoOutput: Allowed renderers: directfb,xv-blit,xshm,xlib,opengl,vdpau
1002010-12-09 19:38:56.015 VideoOutput: Allowed renderers (filt: ffmpeg): directfb,xlib,xshm,xv-blit,opengl,vdpau
1012010-12-09 19:38:56.017 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
1022010-12-09 19:38:56.017 VDP: LoadBestPreferences(2048x2048, 0)
1032010-12-09 19:38:56.017 VDP: LoadBestPreferences(2048x2048, 60)
1042010-12-09 19:38:56.017 VDP: LoadBestPreferences(704x576, 60)
1052010-12-09 19:38:56.017 VideoOutput: Preferred renderer: xv-blit
1062010-12-09 19:38:56.017 VideoOutput: Trying video renderer: 'xv-blit'
1072010-12-09 19:38:56.026 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
1082010-12-09 19:38:56.026 VDP: LoadBestPreferences(2048x2048, 0)
1092010-12-09 19:38:56.026 VDP: LoadBestPreferences(2048x2048, 60)
1102010-12-09 19:38:56.026 VideoOutputXv: ctor
1112010-12-09 19:38:56.026 VideoOutWindow::SetPIPState. pip_state: 0]
1122010-12-09 19:38:56.028 VideoOutputXv: Creating gc
1132010-12-09 19:38:56.028 VideoOutputXv: XJ_screen_num: '0'
1142010-12-09 19:38:56.028 VideoOutputXv: XJ_curwin:     '12582915'
1152010-12-09 19:38:56.029 VideoOutputXv: XJ_win:        '12582915'
1162010-12-09 19:38:56.029 VideoOutputXv: XJ_root:       '261'
1172010-12-09 19:38:56.029 VideoOutputXv: XJ_gc:         '0x7f02cc239c30'
1182010-12-09 19:38:56.029 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
1192010-12-09 19:38:56.029 Video Rect    left: 0, top: 0, width: 704, height: 576, aspect: 1.33333
1202010-12-09 19:38:56.029 VDP: LoadBestPreferences(704x576, 60)
1212010-12-09 19:38:56.029 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
1222010-12-09 19:38:56.029 Video Rect    left: 0, top: 0, width: 704, height: 576, aspect: 1.33333
1232010-12-09 19:38:56.030 Trying to match best refresh rate 25.000Hz
1242010-12-09 19:38:56.030 Trying 1920x1080 50.000 Hz
1252010-12-09 19:38:56.192 SwitchToVideo: Video size 704 x 576:
126    Switched to displaying resolution 1920 x 1080, 480mm x 270mm
1272010-12-09 19:38:56.193 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
1282010-12-09 19:38:56.193 Video Rect    left: 0, top: 0, width: 704, height: 576, aspect: 1.33333
1292010-12-09 19:38:56.193 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
1302010-12-09 19:38:56.193 VideoOutput: Actual display dimensions: 480x270 mm  Aspect: 1.77778
1312010-12-09 19:38:56.193 VideoOutput: Estimated window dimensions: 480x270 mm  Aspect: 1.77778
1322010-12-09 19:38:56.198 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
1332010-12-09 19:38:56.201 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
1342010-12-09 19:38:56.201 VDP: LoadBestPreferences(2048x2048, 0)
1352010-12-09 19:38:56.201 VDP: LoadBestPreferences(2048x2048, 60)
1362010-12-09 19:38:56.202 VDP: LoadBestPreferences(704x576, 60)
1372010-12-09 19:38:56.207 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
1382010-12-09 19:38:56.207 VideoOutputXv: Adaptor#0: Radeon Textured Video has flag[s]: XvInputMask XvImageMask
1392010-12-09 19:38:56.207 VideoOutputXv: Has XVideo flags...
1402010-12-09 19:38:56.211 VideoOutputXv: Has XV_BRIGHTNESS...
1412010-12-09 19:38:56.211 VideoOutputXv: Here...
1422010-12-09 19:38:56.212 VideoOutputXv: Grabbed xv port 63
1432010-12-09 19:38:56.212 VideoOutputXv: XVideo surface found on port 63
1442010-12-09 19:38:56.212 VideoOutputXv: XV_SET_DEFAULTS is not supported on this port
1452010-12-09 19:38:56.212 VideoOutputXv: XV_SYNC_TO_VBLANK not supported
1462010-12-09 19:38:56.214 VideoOutputXv: XVideo Format #0 is 'YUY2'
1472010-12-09 19:38:56.214 VideoOutputXv: XVideo Format #1 is 'YV12'
1482010-12-09 19:38:56.214 VideoOutputXv: XVideo Format #2 is 'I420'
1492010-12-09 19:38:56.214 VideoOutputXv: XVideo Format #3 is 'UYVY'
1502010-12-09 19:38:56.214 VideoOutputXv: Using XVideo Format 'YV12'
1512010-12-09 19:38:56.214 VideoOutputXv: CreateShmImages(32): video_dim: 704x576
1522010-12-09 19:38:56.270 VDP: SetVideoRenderer(xv-blit)
1532010-12-09 19:38:56.270 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
1542010-12-09 19:38:56.271 VideoOutputXv: Chromakeying not possible with this XVideo port.
1552010-12-09 19:38:56.271 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
1562010-12-09 19:38:56.271 Video Rect    left: 0, top: 0, width: 704, height: 576, aspect: 1.33333
1572010-12-09 19:38:56.272 Over/underscan. V: 0, H: 0
1582010-12-09 19:38:56.272 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
1592010-12-09 19:38:56.272 Video Rect    left: 0, top: 0, width: 704, height: 576, aspect: 1.33333
1602010-12-09 19:38:56.272 VDP: LoadBestPreferences(704x576, 25)
1612010-12-09 19:38:56.272 Player(0): LoadFilters(''..) -> 0x0
1622010-12-09 19:38:56.273 Player(0): Decoder thread starting.
1632010-12-09 19:38:56.294 OSD: Loaded window osd_message
1642010-12-09 19:38:56.306 OSD: Loaded window osd_input
1652010-12-09 19:38:56.370 OSD: Loaded window program_info
1662010-12-09 19:38:56.374 OSD: Loaded window browse_info
1672010-12-09 19:38:56.378 OSD: Loaded window osd_status
1682010-12-09 19:38:56.381 OSD: Loaded window osd_program_editor
1692010-12-09 19:38:56.382 OSD: Loaded OSD: size 704x576 offset 0+0
1702010-12-09 19:38:56.387 Player(0): ClearAfterSeek(0)
1712010-12-09 19:38:56.388 VDP: GetFilteredDeint() : xv-blit -> 'kerneldoubleprocessdeint'
1722010-12-09 19:38:56.390 FilterManager: GetFilterInfo(convert) returning: 0x0
1732010-12-09 19:38:56.390 FilterManager: GetFilterInfo(kerneldoubleprocessdeint) returning: 0x7f02cc2fa1f0
1742010-12-09 19:38:56.039 KernelDeint: Created threads.
1752010-12-09 19:38:56.392 VideoOutput: Using deinterlace method kerneldoubleprocessdeint
1762010-12-09 19:38:56.431 Player(0): Display Refresh Rate: 60.002 Video Frame Rate: 25.000
1772010-12-09 19:38:56.450 playCtx: StartPlaying(): took 0 ms to start player.
1782010-12-09 19:38:56.450 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
1792010-12-09 19:38:56.454 TV: HandleStateChange(0) -- end
1802010-12-09 19:38:56.482 ScreenSaverX11Private: ResetTimer -- begin
1812010-12-09 19:38:56.482 ScreenSaverX11Private: StopTimer
1822010-12-09 19:38:56.483 ScreenSaverX11Private: StartTimer
1832010-12-09 19:38:56.483 ScreenSaverX11Private: ResetTimer -- end
1842010-12-09 19:38:56.483 Player(0): Enabled deinterlacing
1852010-12-09 19:38:56.533 OSD: Created window OSD_INTERACTIVE
1862010-12-09 19:38:56.558 Player(0): Video is 7.5875 frames behind audio (too slow), dropping frame to catch up.
1872010-12-09 19:38:56.559 Player(0): Video is 13.0469 frames behind audio (too slow), dropping frame to catch up.
1882010-12-09 19:38:56.581 Player(0): Video is 16.8976 frames behind audio (too slow), dropping frame to catch up.
1892010-12-09 19:38:56.582 Player(0): Video is 19.6732 frames behind audio (too slow), dropping frame to catch up.
1902010-12-09 19:38:56.583 Player(0): Video is 21.5112 frames behind audio (too slow), dropping frame to catch up.
1912010-12-09 19:38:56.604 Player(0): Video is 22.6458 frames behind audio (too slow), dropping frame to catch up.
1922010-12-09 19:38:56.605 Player(0): Video is 23.3781 frames behind audio (too slow), dropping frame to catch up.
1932010-12-09 19:38:56.606 Player(0): Video is 23.6836 frames behind audio (too slow), dropping frame to catch up.
1942010-12-09 19:38:56.627 Player(0): Video is 23.6689 frames behind audio (too slow), dropping frame to catch up.
1952010-12-09 19:38:56.628 Player(0): Video is 23.5392 frames behind audio (too slow), dropping frame to catch up.
1962010-12-09 19:38:56.629 Player(0): Video is 23.1981 frames behind audio (too slow), dropping frame to catch up.
1972010-12-09 19:38:56.650 Player(0): Video is 22.6986 frames behind audio (too slow), dropping frame to catch up.
1982010-12-09 19:38:56.650 Player(0): Video is 22.2052 frames behind audio (too slow), dropping frame to catch up.
1992010-12-09 19:38:56.651 Player(0): Video is 21.5914 frames behind audio (too slow), dropping frame to catch up.
2002010-12-09 19:38:56.672 Player(0): Video is 20.8873 frames behind audio (too slow), dropping frame to catch up.
2012010-12-09 19:38:56.673 Player(0): Video is 20.2404 frames behind audio (too slow), dropping frame to catch up.
2022010-12-09 19:38:56.674 Player(0): Video is 19.5116 frames behind audio (too slow), dropping frame to catch up.
2032010-12-09 19:38:56.695 Player(0): Video is 18.7149 frames behind audio (too slow), dropping frame to catch up.
2042010-12-09 19:38:56.696 Player(0): Video is 17.9987 frames behind audio (too slow), dropping frame to catch up.
2052010-12-09 19:38:56.697 Player(0): Video is 17.2178 frames behind audio (too slow), dropping frame to catch up.
2062010-12-09 19:38:56.718 Player(0): Video is 16.3883 frames behind audio (too slow), dropping frame to catch up.
2072010-12-09 19:38:56.719 Player(0): Video is 15.6537 frames behind audio (too slow), dropping frame to catch up.
2082010-12-09 19:38:56.720 Player(0): Video is 14.8528 frames behind audio (too slow), dropping frame to catch up.
2092010-12-09 19:38:56.741 Player(0): Video is 14.0083 frames behind audio (too slow), dropping frame to catch up.
2102010-12-09 19:38:56.742 Player(0): Video is 13.2625 frames behind audio (too slow), dropping frame to catch up.
2112010-12-09 19:38:56.743 Player(0): Video is 12.4531 frames behind audio (too slow), dropping frame to catch up.
2122010-12-09 19:38:56.764 Player(0): Video is 11.6023 frames behind audio (too slow), dropping frame to catch up.
2132010-12-09 19:38:56.765 Player(0): Video is 10.8455 frames behind audio (too slow), dropping frame to catch up.
2142010-12-09 19:38:56.766 Player(0): Video is 10.0341 frames behind audio (too slow), dropping frame to catch up.
2152010-12-09 19:38:56.787 Player(0): Video is 9.18182 frames behind audio (too slow), dropping frame to catch up.
2162010-12-09 19:38:56.788 Player(0): Video is 8.42385 frames behind audio (too slow), dropping frame to catch up.
2172010-12-09 19:38:56.789 Player(0): Video is 7.61163 frames behind audio (too slow), dropping frame to catch up.
2182010-12-09 19:38:56.810 Player(0): Video is 6.7587 frames behind audio (too slow), dropping frame to catch up.
2192010-12-09 19:38:56.811 Player(0): Video is 6.00028 frames behind audio (too slow), dropping frame to catch up.
2202010-12-09 19:38:56.812 Player(0): Video is 5.1877 frames behind audio (too slow), dropping frame to catch up.
2212010-12-09 19:38:56.833 Player(0): Video is 4.33453 frames behind audio (too slow), dropping frame to catch up.
2222010-12-09 19:38:56.834 Player(0): Video is 3.57588 frames behind audio (too slow), dropping frame to catch up.
223'video_output' mean = '27665,41', std. dev. = '16951,61', fps = '36,15'
2242010-12-09 19:39:01.519 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2252010-12-09 19:39:01.519 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2262010-12-09 19:39:01.758 TV: HandleStateChange(0) -- begin
2272010-12-09 19:39:01.758 TV: StopStuff() for player ctx 0 -- begin
2282010-12-09 19:39:01.759 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2292010-12-09 19:39:01.759 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2302010-12-09 19:39:01.759 Player(0): StopPlaying - begin
2312010-12-09 19:39:01.759 Player(0): Decoder thread exiting.
2322010-12-09 19:39:01.759 Player(0): Exited decoder loop.
2332010-12-09 19:39:01.760 VideoOutputXv: dtor
2342010-12-09 19:39:01.761 VideoOutputXv: DiscardFrames(1)
2352010-12-09 19:39:01.761 VideoBuffers::DiscardFrames(1): AUUUUUUUUUUUUUUUUUUUUUUUUUUuUuL
2362010-12-09 19:39:01.761 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2372010-12-09 19:39:01.761 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2382010-12-09 19:39:01.762 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2392010-12-09 19:39:01.765 VideoOutputXv: Closing XVideo port 63
2402010-12-09 19:39:01.797 Player(0): StopPlaying - end
2412010-12-09 19:39:01.797 TV: StopStuff(): stopping ring buffer
2422010-12-09 19:39:01.798 TV: StopStuff(): stopping player
2432010-12-09 19:39:01.798 TV: StopStuff() -- end
2442010-12-09 19:39:01.798 TV: HandleStateChange(0) -- end
2452010-12-09 19:39:01.798 TV: StartTV -- process events end
2462010-12-09 19:39:01.798 TV: StartTV -- process events 2 begin
2472010-12-09 19:39:01.799 ScreenSaverX11Private: StopTimer
2482010-12-09 19:39:01.799 TV: StartTV -- process events 2 end
2492010-12-09 19:39:01.799 TV::~TV() -- begin
2502010-12-09 19:39:01.799 TV: DrawUnusedRects() -- begin
2512010-12-09 19:39:01.800 TV: DrawUnusedRects() -- end
2522010-12-09 19:39:01.800 TV: DrawUnusedRects() -- begin
2532010-12-09 19:39:01.800 TV: DrawUnusedRects() -- end
2542010-12-09 19:39:01.811 TV::~TV() -- lock
2552010-12-09 19:39:01.812 Player(0): StopPlaying - begin
2562010-12-09 19:39:01.812 Player(0): Exited decoder loop.
2572010-12-09 19:39:01.812 Player(0): StopPlaying - end
2582010-12-09 19:39:01.839 TV::~TV() -- end
2592010-12-09 19:39:01.841 TV: StartTV -- end