Ticket #5956: console-log-patch-5956-v1.txt

File console-log-patch-5956-v1.txt, 15.3 KB (added by Ricahrd Eyton <eyton@…>, 11 years ago)
Line 
1$ mythtv /media/data/720p/od-indy4.mkv
22008-12-30 13:42:39.411 Using runtime prefix = /usr
32008-12-30 13:42:39.412 Unable to read configuration file mysql.txt
42008-12-30 13:42:39.412 Empty LocalHostName.
52008-12-30 13:42:39.412 Using localhost value of phooey
62008-12-30 13:42:39.423 New DB connection, total: 1
72008-12-30 13:42:39.430 Connected to database 'mythconverg' at host: localhost
82008-12-30 13:42:39.430 Closing DB connection named 'DBManager0'
92008-12-30 13:42:39.453 XScreenSaver support enabled
102008-12-30 13:42:39.454 DPMS is active.
112008-12-30 13:42:39.456 Primary screen: 1.
122008-12-30 13:42:39.457 Connected to database 'mythconverg' at host: localhost
132008-12-30 13:42:39.460 Using screen 1, 1360x768 at 0,0
142008-12-30 13:42:39.502 MythUI Image Cache size set to 20971520 bytes
152008-12-30 13:42:39.506 Current Schema Version: 1227
162008-12-30 13:42:39.532 max_width: 1360 max_height: 768
172008-12-30 13:42:39.536 Primary screen: 1.
182008-12-30 13:42:39.537 Using screen 1, 1360x768 at 0,0
192008-12-30 13:42:39.540 Switching to square mode (G.A.N.T)
202008-12-30 13:42:39.555 Using the Qt painter
212008-12-30 13:42:39.894 TV: ctor
222008-12-30 13:42:39.911 New DB connection, total: 2
232008-12-30 13:42:39.912 Connected to database 'mythconverg' at host: localhost
242008-12-30 13:42:39.960 TV: StartTV() -- begin
252008-12-30 13:42:39.960 TV: ctor
262008-12-30 13:42:40.019 TV: tv->Playback() -- begin
272008-12-30 13:42:40.025 TV: tv->Playback() -- end
282008-12-30 13:42:40.026 TV: StartTV -- process events begin
292008-12-30 13:42:40.046 TV: HandleStateChange(0) -- begin
302008-12-30 13:42:40.046 TV: Attempting to change from None to WatchingPreRecorded
312008-12-30 13:42:40.047 RingBuf(/media/data/720p/od-indy4.mkv): OpenFile(/media/data/720p/od-indy4.mkv, 12)
322008-12-30 13:42:40.047 RingBuf(/media/data/720p/od-indy4.mkv): CalcReadAheadThresh(0 KB)
33                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
342008-12-30 13:42:40.047 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
352008-12-30 13:42:40.167 st:0 removing common factor 1000000 from timebase
362008-12-30 13:42:40.168 st:1 removing common factor 1000000 from timebase
372008-12-30 13:42:40.187 [h264 @ 0x7f9687e803e0]no picture
382008-12-30 13:42:40.187 AFD: Stream #0, has id 0x0 codec id H264, type Video, bitrate 0 at 0x0x16167a0
392008-12-30 13:42:40.193 VDP: Accepting: cmp(>= 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
402008-12-30 13:42:40.194 VDP: LoadBestPreferences(2048x2048, 0)
412008-12-30 13:42:40.194 VDP: LoadBestPreferences(2048x2048, 60)
422008-12-30 13:42:40.194 VDP: LoadBestPreferences(1280x528, 60)
432008-12-30 13:42:40.463 VDPAU WARNING: Codec not fully supported - playback may fail.
442008-12-30 13:42:40.678 VDPAU: Version 0
452008-12-30 13:42:40.678 VDPAU: Information Unknown
462008-12-30 13:42:40.679 VDPAU Error: Error at util-vdpau.cpp:467 (#23, The system does not have enough resources to complete the requested operation at this time.)
472008-12-30 13:42:40.679 VDPAU Error: Error at util-vdpau.cpp:482 (#3, An invalid handle value was provided.)
482008-12-30 13:42:40.680 VDPAU Error: Error at util-vdpau.cpp:490 (#0, UNKNOWN)
492008-12-30 13:42:40.691 VDP: Accepting: cmp(>= 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
502008-12-30 13:42:40.691 VDP: LoadBestPreferences(2048x2048, 0)
512008-12-30 13:42:40.691 VDP: LoadBestPreferences(2048x2048, 60)
522008-12-30 13:42:40.691 VDP: LoadBestPreferences(1280x528, 60)
532008-12-30 13:42:40.691 Using 0 CPUs for decoding
542008-12-30 13:42:40.692 AFD: InitVideoCodec() 0x1616950 id(H264) type (Video).
552008-12-30 13:42:40.692 detectInterlace(Detect Scan, Interlaced Scan, 23.976, 528) ->Interlaced Scan
562008-12-30 13:42:40.692 AFD: Using ffmpeg for video decoding
572008-12-30 13:42:40.692 AFD: Looking for decoder for H264
582008-12-30 13:42:40.692 AFD: Opened codec 0x1616950, id(H264) type(Video)
592008-12-30 13:42:40.692 AFD: Stream #1, has id 0x0 codec id AC3, type Audio, bitrate 0 at 0x0x16207d0
602008-12-30 13:42:40.692 AFD: codec AC3 has 6 channels
612008-12-30 13:42:40.693 AFD: Looking for decoder for AC3
622008-12-30 13:42:40.694 AFD: Opened codec 0x1620980, id(AC3) type(Audio)
632008-12-30 13:42:40.694 RingBuf(/media/data/720p/od-indy4.mkv): CalcReadAheadThresh(0 KB)
64                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
652008-12-30 13:42:40.698 Opening audio device 'default'. ch 2(2) sr 48000
662008-12-30 13:42:40.698 Opening ALSA audio device 'default'.
672008-12-30 13:42:40.718 ALSA, Warning: mmap not available, attempting to fall back to slow writes.
682008-12-30 13:42:40.755 Mixer unable to find control PCM
692008-12-30 13:42:40.755 Mixer unable to find control PCM
702008-12-30 13:42:40.756 Mixer unable to find control PCM
712008-12-30 13:42:40.756 Mixer unable to find control PCM
722008-12-30 13:42:40.757 Mixer unable to find control PCM
732008-12-30 13:42:40.757 Mixer unable to find control PCM
742008-12-30 13:42:40.757 Mixer unable to find control PCM
752008-12-30 13:42:40.759 Dec: Trying to select track (w/lang)
762008-12-30 13:42:40.759 Dec: Selecting first track
772008-12-30 13:42:40.759 Dec: Selected track #1 in the Unknown language(0)
782008-12-30 13:42:40.759 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
792008-12-30 13:42:40.761 Dec: SyncPositionMap prerecorded, from DB: 0 entries
802008-12-30 13:42:40.761 AFD: Recording has no position -- using libavformat seeking.
812008-12-30 13:42:40.761 Input #0, matroska, from '/media/data/720p/od-indy4.mkv':
822008-12-30 13:42:40.761   Duration: 02:02:32.41, start: 0.000000, bitrate: N/A
832008-12-30 13:42:40.762     Stream #0.0(eng), 1/1000: Video: h264, 1280x528, 104271/2500000, 500 kb/s, 23.98 tb(r)
842008-12-30 13:42:40.762     Stream #0.1, 1/1000: Audio: ac3, 48000 Hz, stereo, s16
852008-12-30 13:42:40.762 AFD: Successfully opened decoder for file: "/media/data/720p/od-indy4.mkv". novideo(0)
862008-12-30 13:42:40.765 VideoOutput: Allowed renderers: directfb,opengl,xv-blit,xshm,xlib
872008-12-30 13:42:40.765 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit,opengl
882008-12-30 13:42:40.769 VDP: Accepting: cmp(>= 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
892008-12-30 13:42:40.770 VDP: LoadBestPreferences(2048x2048, 0)
902008-12-30 13:42:40.770 VDP: LoadBestPreferences(2048x2048, 60)
912008-12-30 13:42:40.770 VDP: LoadBestPreferences(1280x528, 60)
922008-12-30 13:42:40.770 VideoOutput: Trying video renderer: 'xv-blit'
932008-12-30 13:42:40.786 VDP: Accepting: cmp(>= 0 0) dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
942008-12-30 13:42:40.787 VDP: LoadBestPreferences(2048x2048, 0)
952008-12-30 13:42:40.787 VDP: LoadBestPreferences(2048x2048, 60)
962008-12-30 13:42:40.792 VideoOutputXv: ctor
972008-12-30 13:42:40.792 VideoOutWindow::SetPIPState. pip_state: 0]
982008-12-30 13:42:40.793 VideoOutputXv: Creating XJ_gc
992008-12-30 13:42:40.794 VideoOutputXv: XJ_screen:     '0x16ca7d0'
1002008-12-30 13:42:40.794 VideoOutputXv: XJ_screen_num: '1'
1012008-12-30 13:42:40.794 VideoOutputXv: XJ_curwin:     '77594655'
1022008-12-30 13:42:40.794 VideoOutputXv: XJ_win:        '77594655'
1032008-12-30 13:42:40.794 VideoOutputXv: XJ_root:       '597'
1042008-12-30 13:42:40.795 VideoOutputXv: XJ_gc:         '0x16d5e90'
1052008-12-30 13:42:40.795 Display Rect  left: 0, top: 173, width: 1360, height: 422, aspect: 1.33333
1062008-12-30 13:42:40.795 Video Rect    left: 0, top: 0, width: 1280, height: 528, aspect: 2.42424
1072008-12-30 13:42:40.796 VDP: LoadBestPreferences(1280x528, 60)
1082008-12-30 13:42:40.826 GLCtx: Created window and GLX context.
1092008-12-30 13:42:40.826 GLCtx: GLX Version: 1.2
1102008-12-30 13:42:40.827 GLCtx: Direct rendering: Yes
1112008-12-30 13:42:40.832 VideoOutputXv: Pixel dimensions: Screen 1360x768, window 1360x768
1122008-12-30 13:42:40.878 GLCtx: OpenGL vendor  : NVIDIA Corporation
1132008-12-30 13:42:40.878 GLCtx: OpenGL renderer: GeForce 8800 GT/PCI/SSE2
1142008-12-30 13:42:40.878 GLCtx: OpenGL version : 2.1.2 NVIDIA 180.18
1152008-12-30 13:42:40.879 GLCtx: Max texture size: 8192 x 8192
1162008-12-30 13:42:40.880 VideoOutputXv: Estimated display dimensions: 1645x929 mm  Aspect: 1.77072
1172008-12-30 13:42:40.880 VideoOutputXv: Estimated window dimensions: 1645x929 mm  Aspect: 1.77072
1182008-12-30 13:42:40.887 VideoOutputXv: InitSetupBuffers() render: vdpau, allowed: opengl,xv-blit,xshm,xlib
1192008-12-30 13:42:40.887 VideoOutputXv: Desired video renderer 'vdpau' not available.
120                        codec 'H.264' makes 'opengl,xv-blit,xshm,xlib,' available, using 'opengl' instead.
1212008-12-30 13:42:40.887 VDP: SetVideoRenderer(opengl)
1222008-12-30 13:42:40.887 VDP: Old preferences: rend(vdpau) osd(vdpau) deint(none,none) filt()
1232008-12-30 13:42:40.888 VDP: New preferences: rend(opengl) osd(softblend) deint(none,none) filt()
1242008-12-30 13:42:40.930 GLCtx: Swap interval set to 1.
1252008-12-30 13:42:40.930 GLCtx: Using GL_NV_fence
1262008-12-30 13:42:40.931 GLVid: Viewport: 1360x768
1272008-12-30 13:42:40.958 GLVid: Created texture (1280x528)
1282008-12-30 13:42:40.958 GLVid: Creating master filter.
1292008-12-30 13:42:40.958 GLVid: Created master fragment program
1302008-12-30 13:42:40.959 GLVid: Creating resize filter.
1312008-12-30 13:42:41.012 GLCtx: Created frame buffer object (1280x528).
1322008-12-30 13:42:41.012 GLVid: Using packed textures with mmx and with PBOs
1332008-12-30 13:42:41.093 VDP: GetFilteredDeint() : opengl -> 'none'
1342008-12-30 13:42:41.108 Couldn't load deinterlace filter none
1352008-12-30 13:42:41.108 Using deinterlace method
1362008-12-30 13:42:41.112 VDP: SetVideoRenderer(opengl)
1372008-12-30 13:42:41.112 VDP: SetVideoRender(opengl) == GetVideoRenderer()
1382008-12-30 13:42:41.112 Display Rect  left: 0, top: 102, width: 1360, height: 563, aspect: 1.77778
1392008-12-30 13:42:41.112 Video Rect    left: 0, top: 0, width: 1280, height: 528, aspect: 2.42424
1402008-12-30 13:42:41.116 Over/underscan. V: 0, H: 0
1412008-12-30 13:42:41.117 Display Rect  left: 0, top: 102, width: 1360, height: 563, aspect: 1.77778
1422008-12-30 13:42:41.117 Video Rect    left: 0, top: 0, width: 1280, height: 528, aspect: 2.42424
1432008-12-30 13:42:41.117 VDP: LoadBestPreferences(1280x528, 23.976)
1442008-12-30 13:42:41.118 NVP(0): LoadFilters(''..) -> 0x0
1452008-12-30 13:42:41.120 OSD Theme Dimensions W: 640 H: 480
1462008-12-30 13:42:41.386 WriteAudio: buffer underrun
1472008-12-30 13:42:41.680 NVP(0): ClearAfterSeek(1)
1482008-12-30 13:42:41.681 VideoOutputXv: ClearAfterSeek()
1492008-12-30 13:42:41.681 VideoOutputXv: DiscardFrames(0)
1502008-12-30 13:42:41.680 playCtx: StartDecoderThread(): took 917 ms to start player.
1512008-12-30 13:42:41.681 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1522008-12-30 13:42:41.681 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1532008-12-30 13:42:41.681 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
1542008-12-30 13:42:41.681 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1552008-12-30 13:42:41.681 TV: Changing from None to WatchingPreRecorded
1562008-12-30 13:42:41.682 Realtime priority would require SUID as root.
1572008-12-30 13:42:41.683 TV: HandleStateChange(0) -- end
1582008-12-30 13:42:41.695 [h264 @ 0x7f9687e803e0]no picture
1592008-12-30 13:42:41.708 [h264 @ 0x7f9687e803e0]no picture
1602008-12-30 13:42:41.712 DPMS Deactivated
1612008-12-30 13:42:41.733 VDP: GetFilteredDeint() : opengl -> 'none'
1622008-12-30 13:42:41.734 VDP: GetFilteredDeint() : opengl -> 'none'
1632008-12-30 13:42:41.755 Couldn't load deinterlace filter none
1642008-12-30 13:42:41.755 Using deinterlace method
1652008-12-30 13:42:41.859 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
1662008-12-30 13:42:41.859 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
1672008-12-30 13:42:41.860 OpenGLVideoSync()
1682008-12-30 13:42:41.860 ~OpenGLVideoSync() -- closing opengl vsync
1692008-12-30 13:42:41.860 RTCVideoSync: Could not open /dev/rtc, Permission denied.
1702008-12-30 13:42:41.860 Using audio as timebase
1712008-12-30 13:42:41.860 Video timing method: USleep with busy wait
1722008-12-30 13:42:41.860 Refresh rate: 16662, frame interval: 41708
1732008-12-30 13:42:41.861 NVP(0): progressive frame seen after 2 interlaced  frames
1742008-12-30 13:42:41.910 Disabled deinterlacing
175'video_output' mean = '41703.45', std. dev. = '3826.66', fps = '23.98'
1762008-12-30 13:42:48.592 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
1772008-12-30 13:42:48.592 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
1782008-12-30 13:42:48.633 TV: HandleStateChange(0) -- begin
1792008-12-30 13:42:48.633 TV: Attempting to change from WatchingPreRecorded to None
1802008-12-30 13:42:48.633 TV: StopStuff() for player ctx 0 -- begin
1812008-12-30 13:42:48.633 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
1822008-12-30 13:42:48.633 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
1832008-12-30 13:42:48.634 TV: StopStuff(): stopping ring buffer
1842008-12-30 13:42:48.637 NVP(0): Exited decoder loop.
1852008-12-30 13:42:48.652 VideoOutputXv: dtor
1862008-12-30 13:42:48.652 VideoOutputXv: DiscardFrames(1)
1872008-12-30 13:42:48.652 VideoBuffers::DiscardFrames(1): UuUUuUULLUUAUUUUUUUUUUUUUUUUUUU
1882008-12-30 13:42:48.653 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1892008-12-30 13:42:48.653 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1902008-12-30 13:42:48.653 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1912008-12-30 13:42:48.653 VideoOutputXv: DiscardFrames(1)
1922008-12-30 13:42:48.653 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1932008-12-30 13:42:48.653 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1942008-12-30 13:42:48.654 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1952008-12-30 13:42:48.661 TV: StopStuff(): stopping player
1962008-12-30 13:42:48.662 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1972008-12-30 13:42:48.662 TV: StopStuff() -- end
1982008-12-30 13:42:48.662 TV: Changing from WatchingPreRecorded to None
1992008-12-30 13:42:48.662 TV: HandleStateChange(0) -- end
2002008-12-30 13:42:48.662 TV: StartTV -- process events end
2012008-12-30 13:42:48.663 TV: StartTV -- process events 2 begin
2022008-12-30 13:42:48.663 DPMS Reactivated.
2032008-12-30 13:42:48.663 TV: StartTV -- process events 2 end
2042008-12-30 13:42:48.663 TV::~TV() -- begin
2052008-12-30 13:42:48.663 GLVid: Removing master filter
2062008-12-30 13:42:48.663 TV::~TV() -- lock
2072008-12-30 13:42:48.664 GLVid: Removing resize filter
2082008-12-30 13:42:48.664 Display Rect  left: 0, top: 102, width: 1360, height: 563, aspect: 1.77778
2092008-12-30 13:42:48.664 Video Rect    left: 0, top: 0, width: 1280, height: 528, aspect: 2.42424
2102008-12-30 13:42:48.664 Snapping height to avoid scaling: height: 528, top: 120
2112008-12-30 13:42:48.664 Snapping width to avoid scaling: width: 1280, left: 0
2122008-12-30 13:42:48.664 Display Rect  left: 0, top: 120, width: 1280, height: 528, aspect: 1.66631
2132008-12-30 13:42:48.664 Video Rect    left: 0, top: 0, width: 1280, height: 528, aspect: 2.42424
2142008-12-30 13:42:48.664 TV: StopStuff() for player ctx 0 -- begin
2152008-12-30 13:42:48.664 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2162008-12-30 13:42:48.665 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2172008-12-30 13:42:48.665 TV: StopStuff(): stopping ring buffer
2182008-12-30 13:42:48.665 TV: StopStuff(): stopping player
2192008-12-30 13:42:48.665 TV: StopStuff(): stopping recorder
2202008-12-30 13:42:48.665 TV: StopStuff() -- end
2212008-12-30 13:42:48.669 GLCtx: Deleting OpenGL Resources
2222008-12-30 13:42:48.671 GLCtx: Unmapping gl window
2232008-12-30 13:42:48.672 GLCtx: Destroying gl window
2242008-12-30 13:42:48.676 GLCtx: Destroying glx context
2252008-12-30 13:42:48.732 TV::~TV() -- end
2262008-12-30 13:42:48.733 TV: StartTV -- end
227
228