Ticket #9349: log.vid

File log.vid, 17.8 KB (added by anonymous, 9 years ago)

video playback, frame rate correctly switched to 50Hz

Line 
12010-12-09 19:31:19.363 The NV-CONTROL X extension is not available on screen 0 of ':0.0'.
22010-12-09 19:31:19.365 max_width: 1920 max_height: 1080
32010-12-09 19:31:19.368 user: 1000 effective user: 1000 before privileged thread
42010-12-09 19:31:19.368 user: 1000 effective user: 1000 after privileged thread
52010-12-09 19:31:19.368 user: 1000 effective user: 1000 run_priv_thread
62010-12-09 19:31:19.449 max_width: 1920 max_height: 1080
72010-12-09 19:31:20.690 VDP: decoder<->render support: ffmpeg      null directfb xlib xshm xv-blit opengl vdpau
82010-12-09 19:31:20.691 VDP: decoder<->render support: vdpau       vdpau
92010-12-09 19:31:20.693 VDP: decoder<->render support: libmpeg2    null directfb xlib xshm xv-blit opengl vdpau
102010-12-09 19:31:20.701 VDP: Ignoring profile item 4 (decoder xvmc is not supported (supported: ffmpeg,vdpau,libmpeg2))
112010-12-09 19:31:20.701 VDP: Ignoring profile item 6 (decoder xvmc is not supported (supported: ffmpeg,vdpau,libmpeg2))
122010-12-09 19:31:20.704 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:31:20.709 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:31:20.709 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:31:20.712 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:31:20.712 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:31:20.715 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:31:20.715 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:31:36.587 TV: StartTV() -- begin
202010-12-09 19:31:36.588 TV: ctor -- begin
212010-12-09 19:31:36.588 TV: ctor -- end
222010-12-09 19:31:36.589 TV: Init -- begin
232010-12-09 19:31:36.596 TV: DrawUnusedRects() -- begin
242010-12-09 19:31:36.596 TV: DrawUnusedRects() -- end
252010-12-09 19:31:36.597 TV: DrawUnusedRects() -- begin
262010-12-09 19:31:36.597 TV: DrawUnusedRects() -- end
272010-12-09 19:31:36.688 TV: DrawUnusedRects() -- begin
282010-12-09 19:31:36.688 TV: DrawUnusedRects() -- end
292010-12-09 19:31:36.693 TV: Init -- end
302010-12-09 19:31:36.695 TV: tv->Playback() -- begin
312010-12-09 19:31:36.709 TV: tv->Playback() -- end
322010-12-09 19:31:36.710 TV: StartTV -- process events begin
332010-12-09 19:31:36.711 TV: HandleStateChange(0) -- begin
342010-12-09 19:31:36.711 RingBuf(myth://Videos@127.0.0.1:6543/0-Incoming/Night.avi): OpenFile(myth://Videos@127.0.0.1:6543/0-Incoming/Night.avi, 2000 ms)
352010-12-09 19:31:36.788 TV: DrawUnusedRects() -- begin
362010-12-09 19:31:36.789 TV: DrawUnusedRects() -- end
372010-12-09 19:31:36.789 TV: StartPlayer(0, WatchingVideo, main) -- begin
382010-12-09 19:31:36.789 TV: Elapsed time since TV constructor was called: 201 ms
392010-12-09 19:31:37.496 AFD: Stream #0, has id 0x0 codec id MPEG4, type Video, bitrate 0 at 0x338bdc0
402010-12-09 19:31:37.502 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
412010-12-09 19:31:37.502 VDP: LoadBestPreferences(2048x2048, 0)
422010-12-09 19:31:37.503 VDP: LoadBestPreferences(2048x2048, 60)
432010-12-09 19:31:37.503 VDP: LoadBestPreferences(704x304, 60)
442010-12-09 19:31:37.505 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
452010-12-09 19:31:37.505 VDP: LoadBestPreferences(2048x2048, 0)
462010-12-09 19:31:37.506 VDP: LoadBestPreferences(2048x2048, 60)
472010-12-09 19:31:37.506 VDP: LoadBestPreferences(704x304, 60)
482010-12-09 19:31:37.506 VDPAU: Checking VDPAU capabilities.
49Failed to open VDPAU backend libvdpau_nvidia.so: cannot open shared object file: No such file or directory
502010-12-09 19:31:37.512 AFD: Using 2 CPUs for decoding
512010-12-09 19:31:37.513 AFD: InitVideoCodec() 0x338a810 id(MPEG4) type (Video).
522010-12-09 19:31:37.513 AFD: Selected FPS is 25 (avg 0 stream 25 container 25 estimated 25)
532010-12-09 19:31:37.513 Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 304) ->Interlaced Scan
542010-12-09 19:31:37.513 AFD: Using ffmpeg for video decoding
552010-12-09 19:31:37.513 AFD: Looking for decoder for MPEG4
562010-12-09 19:31:37.514 AFD: Stream #1, has id 0x1 codec id MP3, type Audio, bitrate 111736 at 0x3394b60
572010-12-09 19:31:37.514 AFD: Looking for decoder for MP3
582010-12-09 19:31:37.720 Dec: Selected track #1 in the Unknown language(0)
592010-12-09 19:31:37.721 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
602010-12-09 19:31:37.724 Dec: SyncPositionMap prerecorded, from DB: 0 entries
612010-12-09 19:31:37.724 AFD: Recording has no position -- using libavformat seeking.
622010-12-09 19:31:37.724 AFD: Successfully opened decoder for file: "myth://Videos@127.0.0.1:6543/0-Incoming/Night.avi". novideo(0)
632010-12-09 19:31:37.728 VideoOutput: Allowed renderers: directfb,xv-blit,xshm,xlib,opengl,vdpau
642010-12-09 19:31:37.729 VideoOutput: Allowed renderers (filt: ffmpeg): directfb,xlib,xshm,xv-blit,opengl,vdpau
652010-12-09 19:31:37.731 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
662010-12-09 19:31:37.731 VDP: LoadBestPreferences(2048x2048, 0)
672010-12-09 19:31:37.732 VDP: LoadBestPreferences(2048x2048, 60)
682010-12-09 19:31:37.732 VDP: LoadBestPreferences(704x304, 60)
692010-12-09 19:31:37.732 VideoOutput: Preferred renderer: xv-blit
702010-12-09 19:31:37.732 VideoOutput: Trying video renderer: 'xv-blit'
712010-12-09 19:31:37.756 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
722010-12-09 19:31:37.756 VDP: LoadBestPreferences(2048x2048, 0)
732010-12-09 19:31:37.756 VDP: LoadBestPreferences(2048x2048, 60)
742010-12-09 19:31:37.756 VideoOutputXv: ctor
752010-12-09 19:31:37.757 VideoOutWindow::SetPIPState. pip_state: 0]
762010-12-09 19:31:37.758 VideoOutputXv: Creating gc
772010-12-09 19:31:37.758 VideoOutputXv: XJ_screen_num: '0'
782010-12-09 19:31:37.758 VideoOutputXv: XJ_curwin:     '12582915'
792010-12-09 19:31:37.759 VideoOutputXv: XJ_win:        '12582915'
802010-12-09 19:31:37.759 VideoOutputXv: XJ_root:       '261'
812010-12-09 19:31:37.759 VideoOutputXv: XJ_gc:         '0x7f420031a6b0'
822010-12-09 19:31:37.759 Display Rect  left: 0, top: 229, width: 1920, height: 622, aspect: 1.33333
832010-12-09 19:31:37.759 Video Rect    left: 0, top: 0, width: 704, height: 304, aspect: 2.31579
842010-12-09 19:31:37.759 VDP: LoadBestPreferences(704x304, 60)
852010-12-09 19:31:37.760 Display Rect  left: 0, top: 229, width: 1920, height: 622, aspect: 1.33333
862010-12-09 19:31:37.760 Video Rect    left: 0, top: 0, width: 704, height: 304, aspect: 2.31579
872010-12-09 19:31:37.762 Trying to match best refresh rate 25.000Hz
882010-12-09 19:31:37.762 Trying 1920x1080 50.000 Hz
892010-12-09 19:31:37.972 SwitchToVideo: Video size 704 x 304:
90    Switched to displaying resolution 1920 x 1080, 480mm x 270mm
912010-12-09 19:31:37.973 Display Rect  left: 0, top: 125, width: 1920, height: 829, aspect: 1.77778
922010-12-09 19:31:37.973 Video Rect    left: 0, top: 0, width: 704, height: 304, aspect: 2.31579
932010-12-09 19:31:37.973 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
942010-12-09 19:31:37.973 VideoOutput: Actual display dimensions: 480x270 mm  Aspect: 1.77778
952010-12-09 19:31:37.973 VideoOutput: Estimated window dimensions: 480x270 mm  Aspect: 1.77778
962010-12-09 19:31:37.979 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
972010-12-09 19:31:37.985 VDP: Accepting: cmp(>= 100 100,>= 100 100) dec(ffmpeg) cpus(2) skiploop(disabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(kerneldoubleprocessdeint,onefield) filt()
982010-12-09 19:31:37.986 VDP: LoadBestPreferences(2048x2048, 0)
992010-12-09 19:31:37.986 VDP: LoadBestPreferences(2048x2048, 60)
1002010-12-09 19:31:37.986 VDP: LoadBestPreferences(704x304, 60)
1012010-12-09 19:31:38.016 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
1022010-12-09 19:31:38.017 VideoOutputXv: Adaptor#0: Radeon Textured Video has flag[s]: XvInputMask XvImageMask
1032010-12-09 19:31:38.017 VideoOutputXv: Has XVideo flags...
1042010-12-09 19:31:38.018 VideoOutputXv: Has XV_BRIGHTNESS...
1052010-12-09 19:31:38.018 VideoOutputXv: Here...
1062010-12-09 19:31:38.018 VideoOutputXv: Grabbed xv port 63
1072010-12-09 19:31:38.019 VideoOutputXv: XVideo surface found on port 63
1082010-12-09 19:31:38.019 VideoOutputXv: XV_SET_DEFAULTS is not supported on this port
1092010-12-09 19:31:38.019 VideoOutputXv: XV_SYNC_TO_VBLANK not supported
1102010-12-09 19:31:38.020 VideoOutputXv: XVideo Format #0 is 'YUY2'
1112010-12-09 19:31:38.020 VideoOutputXv: XVideo Format #1 is 'YV12'
1122010-12-09 19:31:38.020 VideoOutputXv: XVideo Format #2 is 'I420'
1132010-12-09 19:31:38.020 VideoOutputXv: XVideo Format #3 is 'UYVY'
1142010-12-09 19:31:38.020 VideoOutputXv: Using XVideo Format 'YV12'
1152010-12-09 19:31:38.020 VideoOutputXv: CreateShmImages(32): video_dim: 704x304
1162010-12-09 19:31:38.070 VDP: SetVideoRenderer(xv-blit)
1172010-12-09 19:31:38.070 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
1182010-12-09 19:31:38.072 VideoOutputXv: Chromakeying not possible with this XVideo port.
1192010-12-09 19:31:38.072 Display Rect  left: 0, top: 125, width: 1920, height: 829, aspect: 1.77778
1202010-12-09 19:31:38.073 Video Rect    left: 0, top: 0, width: 704, height: 304, aspect: 2.31579
1212010-12-09 19:31:38.076 Over/underscan. V: 0, H: 0
1222010-12-09 19:31:38.076 Display Rect  left: 0, top: 125, width: 1920, height: 829, aspect: 1.77778
1232010-12-09 19:31:38.076 Video Rect    left: 0, top: 0, width: 704, height: 304, aspect: 2.31579
1242010-12-09 19:31:38.076 VDP: LoadBestPreferences(704x304, 25)
1252010-12-09 19:31:38.076 Player(0): LoadFilters(''..) -> 0x0
1262010-12-09 19:31:38.078 Player(0): Decoder thread starting.
1272010-12-09 19:31:38.096 OSD: Loaded window osd_message
1282010-12-09 19:31:38.110 OSD: Loaded window osd_input
1292010-12-09 19:31:38.155 OSD: Loaded window program_info
1302010-12-09 19:31:38.164 OSD: Loaded window browse_info
1312010-12-09 19:31:38.191 OSD: Loaded window osd_status
1322010-12-09 19:31:38.199 OSD: Loaded window osd_program_editor
1332010-12-09 19:31:38.199 OSD: Loaded OSD: size 704x304 offset 0+0
1342010-12-09 19:31:38.206 Player(0): ClearAfterSeek(0)
1352010-12-09 19:31:38.212 VDP: GetFilteredDeint() : xv-blit -> 'kerneldoubleprocessdeint'
1362010-12-09 19:31:38.215 FilterManager: GetFilterInfo(convert) returning: 0x0
1372010-12-09 19:31:38.215 FilterManager: GetFilterInfo(kerneldoubleprocessdeint) returning: 0x7f42002a9bb0
1382010-12-09 19:31:38.022 KernelDeint: Created threads.
1392010-12-09 19:31:38.226 VideoOutput: Using deinterlace method kerneldoubleprocessdeint
1402010-12-09 19:31:38.262 Player(0): Display Refresh Rate: 60.002 Video Frame Rate: 25.000
1412010-12-09 19:31:38.277 playCtx: StartPlaying(): took 0 ms to start player.
1422010-12-09 19:31:38.277 TV: StartPlayer(0, WatchingVideo, main) -- end ok
1432010-12-09 19:31:38.283 TV: HandleStateChange(0) -- end
1442010-12-09 19:31:38.307 ScreenSaverX11Private: ResetTimer -- begin
1452010-12-09 19:31:38.307 ScreenSaverX11Private: StopTimer
1462010-12-09 19:31:38.309 ScreenSaverX11Private: StartTimer
1472010-12-09 19:31:38.309 ScreenSaverX11Private: ResetTimer -- end
1482010-12-09 19:31:38.309 Player(0): progressive frame seen after 2 interlaced  frames
1492010-12-09 19:31:38.366 OSD: Created window OSD_INTERACTIVE
1502010-12-09 19:31:38.369 Player(0): Video is 6.90625 frames behind audio (too slow), dropping frame to catch up.
1512010-12-09 19:31:38.370 Player(0): Disabled deinterlacing
1522010-12-09 19:31:38.370 Player(0): Video is 12.0172 frames behind audio (too slow), dropping frame to catch up.
1532010-12-09 19:31:38.370 Player(0): Video is 15.6004 frames behind audio (too slow), dropping frame to catch up.
1542010-12-09 19:31:38.393 Player(0): Video is 18.0378 frames behind audio (too slow), dropping frame to catch up.
1552010-12-09 19:31:38.393 Player(0): Video is 19.7596 frames behind audio (too slow), dropping frame to catch up.
1562010-12-09 19:31:38.393 Player(0): Video is 20.8009 frames behind audio (too slow), dropping frame to catch up.
1572010-12-09 19:31:38.414 Player(0): Video is 21.3319 frames behind audio (too slow), dropping frame to catch up.
1582010-12-09 19:31:38.414 Player(0): Video is 21.6177 frames behind audio (too slow), dropping frame to catch up.
1592010-12-09 19:31:38.415 Player(0): Video is 21.582 frames behind audio (too slow), dropping frame to catch up.
1602010-12-09 19:31:38.436 Player(0): Video is 21.3053 frames behind audio (too slow), dropping frame to catch up.
1612010-12-09 19:31:38.436 Player(0): Video is 20.9789 frames behind audio (too slow), dropping frame to catch up.
1622010-12-09 19:31:38.437 Player(0): Video is 20.4842 frames behind audio (too slow), dropping frame to catch up.
1632010-12-09 19:31:38.457 Player(0): Video is 19.8694 frames behind audio (too slow), dropping frame to catch up.
1642010-12-09 19:31:38.458 Player(0): Video is 19.2895 frames behind audio (too slow), dropping frame to catch up.
1652010-12-09 19:31:38.458 Player(0): Video is 18.6046 frames behind audio (too slow), dropping frame to catch up.
1662010-12-09 19:31:38.481 Player(0): Video is 17.841 frames behind audio (too slow), dropping frame to catch up.
1672010-12-09 19:31:38.482 Player(0): Video is 17.1682 frames behind audio (too slow), dropping frame to catch up.
1682010-12-09 19:31:38.482 Player(0): Video is 16.4137 frames behind audio (too slow), dropping frame to catch up.
1692010-12-09 19:31:38.503 Player(0): Video is 15.5977 frames behind audio (too slow), dropping frame to catch up.
1702010-12-09 19:31:38.503 Player(0): Video is 14.8608 frames behind audio (too slow), dropping frame to catch up.
1712010-12-09 19:31:38.503 Player(0): Video is 14.0643 frames behind audio (too slow), dropping frame to catch up.
1722010-12-09 19:31:38.524 Player(0): Video is 13.217 frames behind audio (too slow), dropping frame to catch up.
1732010-12-09 19:31:38.524 Player(0): Video is 12.4627 frames behind audio (too slow), dropping frame to catch up.
1742010-12-09 19:31:38.524 Player(0): Video is 11.647 frames behind audio (too slow), dropping frame to catch up.
1752010-12-09 19:31:38.545 Player(0): Video is 10.7915 frames behind audio (too slow), dropping frame to catch up.
1762010-12-09 19:31:38.545 Player(0): Video is 10.0249 frames behind audio (too slow), dropping frame to catch up.
1772010-12-09 19:31:38.545 Player(0): Video is 9.1999 frames behind audio (too slow), dropping frame to catch up.
1782010-12-09 19:31:38.566 Player(0): Video is 8.33743 frames behind audio (too slow), dropping frame to catch up.
1792010-12-09 19:31:38.566 Player(0): Video is 7.56555 frames behind audio (too slow), dropping frame to catch up.
1802010-12-09 19:31:38.566 Player(0): Video is 6.73665 frames behind audio (too slow), dropping frame to catch up.
1812010-12-09 19:31:38.587 Player(0): Video is 5.86497 frames behind audio (too slow), dropping frame to catch up.
1822010-12-09 19:31:38.587 Player(0): Video is 5.09247 frames behind audio (too slow), dropping frame to catch up.
1832010-12-09 19:31:38.587 Player(0): Video is 4.26935 frames behind audio (too slow), dropping frame to catch up.
1842010-12-09 19:31:38.608 Player(0): Video is 3.402 frames behind audio (too slow), dropping frame to catch up.
185'video_output' mean = '28562,48', std. dev. = '17308,21', fps = '35,01'
1862010-12-09 19:31:43.199 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
1872010-12-09 19:31:43.199 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
1882010-12-09 19:31:43.438 TV: HandleStateChange(0) -- begin
1892010-12-09 19:31:43.439 TV: StopStuff() for player ctx 0 -- begin
1902010-12-09 19:31:43.439 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
1912010-12-09 19:31:43.439 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
1922010-12-09 19:31:43.439 Player(0): StopPlaying - begin
1932010-12-09 19:31:43.440 Player(0): Decoder thread exiting.
1942010-12-09 19:31:43.440 Player(0): Exited decoder loop.
1952010-12-09 19:31:43.442 VideoOutputXv: dtor
1962010-12-09 19:31:43.442 VideoOutputXv: DiscardFrames(1)
1972010-12-09 19:31:43.442 VideoBuffers::DiscardFrames(1): AUUUUUUUUUUUUUUUUUUUUUUUUUUUuuL
1982010-12-09 19:31:43.443 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1992010-12-09 19:31:43.443 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2002010-12-09 19:31:43.443 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2012010-12-09 19:31:43.446 VideoOutputXv: Closing XVideo port 63
2022010-12-09 19:31:43.474 Player(0): StopPlaying - end
2032010-12-09 19:31:43.474 TV: StopStuff(): stopping ring buffer
2042010-12-09 19:31:43.475 TV: StopStuff(): stopping player
2052010-12-09 19:31:43.475 TV: StopStuff() -- end
2062010-12-09 19:31:43.475 TV: HandleStateChange(0) -- end
2072010-12-09 19:31:43.475 TV: StartTV -- process events end
2082010-12-09 19:31:43.475 TV: StartTV -- process events 2 begin
2092010-12-09 19:31:43.476 ScreenSaverX11Private: StopTimer
2102010-12-09 19:31:43.476 TV: StartTV -- process events 2 end
2112010-12-09 19:31:43.476 TV::~TV() -- begin
2122010-12-09 19:31:43.476 TV: DrawUnusedRects() -- begin
2132010-12-09 19:31:43.476 TV: DrawUnusedRects() -- end
2142010-12-09 19:31:43.477 TV: DrawUnusedRects() -- begin
2152010-12-09 19:31:43.477 TV: DrawUnusedRects() -- end
2162010-12-09 19:31:43.487 TV::~TV() -- lock
2172010-12-09 19:31:43.488 Player(0): StopPlaying - begin
2182010-12-09 19:31:43.488 Player(0): Exited decoder loop.
2192010-12-09 19:31:43.488 Player(0): StopPlaying - end
2202010-12-09 19:31:43.502 TV::~TV() -- end
2212010-12-09 19:31:43.505 TV: StartTV -- end