At startup of frontend ************************************************************** 2010-04-08 17:42:12.304 mythfrontend version: branches/release-0-22-fixes [22594] www.mythtv.org 2010-04-08 17:42:12.307 Using runtime prefix = /usr 2010-04-08 17:42:12.307 Using configuration directory = /home/frontend/.mythtv 2010-04-08 17:42:13.166 Empty LocalHostName. 2010-04-08 17:42:13.167 Using localhost value of frontend 2010-04-08 17:42:13.168 Testing network connectivity to '192.168.1.117' 2010-04-08 17:42:13.194 New DB connection, total: 1 2010-04-08 17:42:13.400 Unable to connect to database! 2010-04-08 17:42:13.400 Driver error was [1/1045]: QMYSQL: Unable to connect Database error was: Access denied for user 'mythtv'@'192.168.1.120' (using password: YES) 2010-04-08 17:42:13.629 UPnPautoconf() - Found one UPnP backend 2010-04-08 17:42:13.840 Testing network connectivity to '192.168.1.117' 2010-04-08 17:42:13.849 Closing DB connection named 'DBManager0' 2010-04-08 17:42:14.003 Connected to database 'mythconverg' at host: 192.168.1.117 2010-04-08 17:42:14.005 Closing DB connection named 'DBManager0' 2010-04-08 17:42:14.025 ScreenSaverX11Private: Gnome screen saver support enabled 2010-04-08 17:42:14.028 DPMS is active. 2010-04-08 17:42:14.031 Primary screen: 0. 2010-04-08 17:42:14.160 Connected to database 'mythconverg' at host: 192.168.1.117 2010-04-08 17:42:14.163 Using screen 0, 1920x1080 at 0,0 2010-04-08 17:42:14.185 MythUI Image Cache size set to 20971520 bytes 2010-04-08 17:42:14.185 user: 1000 effective user: 1000 before privileged thread 2010-04-08 17:42:14.185 user: 1000 effective user: 1000 after privileged thread 2010-04-08 17:42:14.185 user: 1000 effective user: 1000 run_priv_thread 2010-04-08 17:42:14.186 Enabled verbose msgs: important general playback 2010-04-08 17:42:14.196 Primary screen: 0. 2010-04-08 17:42:14.198 Using screen 0, 1920x1080 at 0,0 2010-04-08 17:42:14.199 Using theme base resolution of 1280x720 2010-04-08 17:42:14.212 LIRC: Successfully initialized '/var/run/lirc/lircd' using '/home/frontend/.mythtv/lircrc' config 2010-04-08 17:42:14.212 JoystickMenuThread Error: Joystick disabled - Failed to read /home/frontend/.mythtv/joystickmenurc 2010-04-08 17:42:14.713 Using the OpenGL painter 2010-04-08 17:42:15.158 Loaded base theme from /usr/share/mythtv/themes/blue-abstract-wide/base.xml 2010-04-08 17:42:15.244 Loaded base theme from /usr/share/mythtv/themes/default-wide/base.xml 2010-04-08 17:42:15.282 Loaded base theme from /usr/share/mythtv/themes/default/base.xml 2010-04-08 17:42:15.589 Current MythTV Schema Version (DBSchemaVer): 1244 2010-04-08 17:42:16.282 Desktop video mode: 1920x1080 50 Hz 2010-04-08 17:42:16.308 max_width: 1920 max_height: 1080 2010-04-08 17:42:16.550 Registering Internal as a media playback plugin. 2010-04-08 17:42:16.635 MonitorRegisterExtensions(0x100, gif,jpg,png) 2010-04-08 17:42:16.694 Current MythMusic Schema Version (MusicDBSchemaVer): 1017 2010-04-08 17:42:16.756 MonitorRegisterExtensions(0x40, mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv) 2010-04-08 17:42:16.780 Current MythVideo Schema Version (mythvideo.DBSchemaVer): 1028 2010-04-08 17:42:16.859 Loading window theme from /usr/share/mythtv/themes/blue-abstract-wide/menu-ui.xml 2010-04-08 17:42:16.874 Loading menu theme from /usr/share/mythtv/themes/classic//mainmenu.xml 2010-04-08 17:42:16.878 Found mainmenu.xml for theme 'blue-abstract-wide' 2010-04-08 17:42:16.898 Using NV NPOT texture extension 2010-04-08 17:42:17.036 MythContext: Connecting to backend server: 192.168.1.117:6543 (try 1 of 1) 2010-04-08 17:42:17.076 Using protocol version 50 2010-04-08 17:42:22.423 Loading menu theme from /usr/share/mythtv/themes/classic//tvmenu.xml 2010-04-08 17:42:22.425 ScreenSaverX11Private: Calling gnome-screensaver-command --poke 2010-04-08 17:42:23.124 TV: StartTV() -- begin 2010-04-08 17:42:23.124 TV: ctor 2010-04-08 17:42:23.171 New DB connection, total: 2 2010-04-08 17:42:23.300 Connected to database 'mythconverg' at host: 192.168.1.117 2010-04-08 17:42:23.338 TV: DrawUnusedRects() -- begin 2010-04-08 17:42:23.338 TV: DrawUnusedRects() -- end 2010-04-08 17:42:23.383 TV: tv->LiveTV() -- begin 2010-04-08 17:42:23.391 TV: tv->LiveTV() -- end 2010-04-08 17:42:23.391 TV: StartTV -- process events begin 2010-04-08 17:42:23.493 TV: HandleStateChange(0) -- begin 2010-04-08 17:42:23.493 TV: Attempting to change from None to Watching WatchingLiveTV 2010-04-08 17:42:23.494 MythContext: Connecting to backend server: 192.168.1.117:6543 (try 1 of 1) 2010-04-08 17:42:23.495 Using protocol version 50 2010-04-08 17:42:23.496 Spawning LiveTV Recorder -- begin 2010-04-08 17:42:23.699 Spawning LiveTV Recorder -- end 2010-04-08 17:42:23.700 LiveTVChain(live-frontend-2010-04-08T17:42:23): ReloadAll(): Added new recording 2010-04-08 17:42:23.724 We have a playbackURL(myth://192.168.1.117:6543/1005_20100408174223.mpg) & cardtype(DUMMY) 2010-04-08 17:42:23.724 We have a RingBuffer 2010-04-08 17:42:23.775 TV: StartRecorder(): took 1 ms to start recorder. 2010-04-08 17:42:23.775 TV: StartPlayer(0, Watching WatchingLiveTV, main) -- begin 2010-04-08 17:42:23.815 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan 2010-04-08 17:42:23.818 NVP(0): Disabling Audio, params(-1,2,44100) 2010-04-08 17:42:23.821 VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,vdpau 2010-04-08 17:42:23.822 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,vdpau 2010-04-08 17:42:23.825 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) filt(colorspace=0) 2010-04-08 17:42:23.825 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(colorspace=0) 2010-04-08 17:42:23.826 VDP: LoadBestPreferences(2048x2048, 0) 2010-04-08 17:42:23.826 VDP: LoadBestPreferences(2048x2048, 60) 2010-04-08 17:42:23.826 VDP: LoadBestPreferences(720x576, 60) 2010-04-08 17:42:23.826 VideoOutput: Preferred renderer: vdpau 2010-04-08 17:42:23.826 VideoOutput: Trying video renderer: 'vdpau' 2010-04-08 17:42:23.836 VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) filt(colorspace=0) 2010-04-08 17:42:23.836 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(colorspace=0) 2010-04-08 17:42:23.836 VDP: LoadBestPreferences(2048x2048, 0) 2010-04-08 17:42:23.837 VDP: LoadBestPreferences(2048x2048, 60) 2010-04-08 17:42:23.843 VideoOutWindow::SetPIPState. pip_state: 0] 2010-04-08 17:42:23.844 Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333 2010-04-08 17:42:23.844 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.3333 2010-04-08 17:42:23.844 VDP: LoadBestPreferences(720x576, 60) 2010-04-08 17:42:23.844 Display Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333 2010-04-08 17:42:23.844 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.3333 2010-04-08 17:42:23.844 VDP: SetVideoRenderer(vdpau) 2010-04-08 17:42:23.844 VDP: SetVideoRender(vdpau) == GetVideoRenderer() 2010-04-08 17:42:23.846 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24) 2010-04-08 17:42:23.849 VideoOutput: Actual display dimensions: 488x274 mm Aspect: 1.78102 2010-04-08 17:42:23.849 VideoOutput: Estimated window dimensions: 488x274 mm Aspect: 1.78102 2010-04-08 17:42:23.872 VDPAU: Version 0 2010-04-08 17:42:23.872 VDPAU: Information NVIDIA VDPAU Driver Shared Library 185.18.36 Fri Aug 14 18:28:21 PDT 2009 2010-04-08 17:42:23.942 VidOutVDPAU: PictureAttributes: Brightness, Contrast, Colour, Hue 2010-04-08 17:42:23.943 New DB connection, total: 3 2010-04-08 17:42:23.965 Display Rect left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778 2010-04-08 17:42:23.965 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.3333 2010-04-08 17:42:23.965 VidOutVDPAU: Created VDPAU context (software decode) 2010-04-08 17:42:24.015 VDPAU: Created OSD (1920x1080) 2010-04-08 17:42:24.017 Over/underscan. V: 0.01, H: 0.01 2010-04-08 17:42:24.017 Display Rect left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778 2010-04-08 17:42:24.017 Video Rect left: 7, top: 6, width: 706, height: 564, aspect: 1.3333 2010-04-08 17:42:24.018 VDP: LoadBestPreferences(720x576, 25) 2010-04-08 17:42:24.019 FilterManager: GetFilterInfo(convert) returning: 0x0 2010-04-08 17:42:24.019 FilterManager: GetFilterInfo(colorspace) returning: 0x0 2010-04-08 17:42:24.019 FilterManager: Failed to load filter 'colorspace', no such filter exists 2010-04-08 17:42:24.019 NVP(0): LoadFilters('colorspace=0'..) -> 0x0 2010-04-08 17:42:24.021 OSD Theme Dimensions W: 1280 H: 720 2010-04-08 17:42:24.086 Connected to database 'mythconverg' at host: 192.168.1.117 2010-04-08 17:42:24.282 LiveTVChain(live-frontend-2010-04-08T17:42:23): ReloadAll(): Added new recording 2010-04-08 17:42:24.830 NVP(0): ClearAfterSeek(1) 2010-04-08 17:42:24.830 playCtx: StartDecoderThread(): took 1012 ms to start player. 2010-04-08 17:42:24.830 VidOutVDPAU: ClearAfterSeek() 2010-04-08 17:42:24.830 VidOutVDPAU: DiscardFrames(0) 2010-04-08 17:42:24.830 TV: StartPlayer(0, Watching WatchingLiveTV, main) -- end ok 2010-04-08 17:42:24.830 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA 2010-04-08 17:42:24.831 TV: Changing from None to Watching WatchingLiveTV 2010-04-08 17:42:24.831 TV: State is LiveTV & mctx == ctx 2010-04-08 17:42:24.831 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA -- done 2010-04-08 17:42:24.831 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAA -- done() 2010-04-08 17:42:24.832 New DB connection, total: 4 2010-04-08 17:42:24.833 TV: UpdateOSDInput done 2010-04-08 17:42:24.833 TV: UpdateLCD done 2010-04-08 17:42:24.836 VDP: GetFilteredDeint() : vdpau -> 'vdpauadvanceddoublerate' 2010-04-08 17:42:24.836 NVP(0): DoPause() -- begin 2010-04-08 17:42:24.837 rate: 25 speed: 1 skip: 1 = interval 40000 2010-04-08 17:42:24.837 NVP(0): DoPause() -- setting paused 2010-04-08 17:42:24.837 LiveTVChain(live-frontend-2010-04-08T17:42:23): SwitchTo(1) 2010-04-08 17:42:24.837 LiveTVChain(live-frontend-2010-04-08T17:42:23): Entry@1: '1005_20100408174224' 2010-04-08 17:42:24.837 JumpToProgram(void) 2010-04-08 17:42:24.838 TV: ITVRestart done 2010-04-08 17:42:24.857 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory 2010-04-08 17:42:24.857 FilterManager: GetFilterInfo(convert) returning: 0x0 2010-04-08 17:42:24.857 OpenGLVideoSync() 2010-04-08 17:42:24.857 FilterManager: GetFilterInfo(colorspace) returning: 0x0 2010-04-08 17:42:24.857 FilterManager: Failed to load filter 'colorspace', no such filter exists 2010-04-08 17:42:24.857 NVP(0): LoadFilters('colorspace=0'..) -> 0x0 2010-04-08 17:42:24.861 TV: HandleStateChange(0) -- end 2010-04-08 17:42:24.863 RingBuf(myth://192.168.1.117:6543/1005_20100408174223.mpg): OpenFile(myth://192.168.1.117:6543/1005_20100408174224.mpg, 12) 2010-04-08 17:42:24.874 Forcing GLX version to 1.2 (orig 1.3) 2010-04-08 17:42:24.877 GLCtx: Created window and GLX context. 2010-04-08 17:42:24.877 GLCtx: GLX Version: 1.2 2010-04-08 17:42:24.877 GLCtx: Direct rendering: Yes 2010-04-08 17:42:24.897 RingBuf(myth://192.168.1.117:6543/1005_20100408174224.mpg): CalcReadAheadThresh(0 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2010-04-08 17:42:24.905 ScreenSaverX11Private: DPMS Deactivated 1 2010-04-08 17:42:24.905 ScreenSaverX11Private: ResetTimer -- begin 2010-04-08 17:42:24.905 ScreenSaverX11Private: StopTimer 2010-04-08 17:42:24.907 ScreenSaverX11Private: StartTimer 2010-04-08 17:42:24.907 ScreenSaverX11Private: ResetTimer -- end 2010-04-08 17:42:24.929 GLCtx: OpenGL vendor : NVIDIA Corporation 2010-04-08 17:42:24.929 GLCtx: OpenGL renderer: ION/PCI/SSE2 2010-04-08 17:42:24.929 GLCtx: OpenGL version : 3.0.0 NVIDIA 185.18.36 2010-04-08 17:42:24.929 GLCtx: Max texture size: 8192 x 8192 2010-04-08 17:42:24.929 GLCtx: Max texture units: 4 2010-04-08 17:42:24.931 Set video sync frame interval to 40000 2010-04-08 17:42:24.939 Using audio as timebase 2010-04-08 17:42:24.939 Video timing method: SGI OpenGL 2010-04-08 17:42:24.940 Refresh rate: 20000, frame interval: 40000 2010-04-08 17:42:25.488 VDPAU: Using 4 output surfaces (max 4) **************************************************************** On program change when problem happens: **************************************************************** 2010-04-08 18:00:00.998 LiveTVChain(live-frontend-2010-04-08T17:42:23): ReloadAll(): Added new recording 2010-04-08 18:00:00.998 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0) 2010-04-08 18:00:01.633 Position map filled from DB to: 26400 2010-04-08 18:00:01.633 Dec: SyncPositionMap watchingrecording, from DB: 2201 entries 2010-04-08 18:00:01.633 Dec: SyncPositionMap watchingrecording no entries from encoder, try DB 2010-04-08 18:00:01.658 Position map filled from DB to: 26412 2010-04-08 18:00:01.658 Dec: SyncPositionMap watchingrecording total: 2202 entries 2010-04-08 18:00:01.658 Dec: SyncPositionMap, new totframes: 26412, new length: 1056, posMap size: 2202 2010-04-08 18:00:01.666 LiveTVChain(live-frontend-2010-04-08T17:42:23): SwitchTo(1) 2010-04-08 18:00:01.667 LiveTVChain(live-frontend-2010-04-08T17:42:23): Entry@1: '1005_20100408180000' 2010-04-08 18:00:01.672 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(1943KB) vfl(13) frh(25) ne:0 2010-04-08 18:00:01.682 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(1943KB) vfl(12) frh(25) ne:0 2010-04-08 18:00:01.693 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(1943KB) vfl(12) frh(25) ne:0 2010-04-08 18:00:01.700 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(1779KB) vfl(13) frh(23) ne:0 2010-04-08 18:00:01.710 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(1779KB) vfl(13) frh(23) ne:0 ................. Repeated a couple of hundred times 2010-04-08 18:00:03.438 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(534KB) vfl(12) frh(7) ne:0 2010-04-08 18:00:03.449 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(534KB) vfl(12) frh(7) ne:0 2010-04-08 18:00:03.460 NVP(0): IsReallyNearEnd() br(1907KB) fps(25) sz(403KB) vfl(13) frh(5) ne:1 2010-04-08 18:00:03.478 FilterManager: GetFilterInfo(convert) returning: 0x0 2010-04-08 18:00:03.479 FilterManager: GetFilterInfo(colorspace) returning: 0x0 2010-04-08 18:00:03.479 FilterManager: Failed to load filter 'colorspace', no such filter exists 2010-04-08 18:00:03.479 NVP(0): LoadFilters('colorspace=0'..) -> 0x0 2010-04-08 18:00:03.484 RingBuf(myth://192.168.1.117:6543/1005_20100408174224.mpg): OpenFile(myth://192.168.1.117:6543/1005_20100408180000.mpg, 10) 2010-04-08 18:00:03.494 RingBuf(myth://192.168.1.117:6543/1005_20100408180000.mpg): CalcReadAheadThresh(0 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2010-04-08 18:00:03.494 SwitchToProgram(void) discont: 0 newtype: 0 newid: 1 eof: 0 2010-04-08 18:00:03.497 RingBuf(myth://192.168.1.117:6543/1005_20100408180000.mpg): CalcReadAheadThresh(0 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2010-04-08 18:00:04.098 FileChangedCallback 2010-04-08 18:00:04.285 RingBuffer::Reset() nonzero readpos. toAdjust: 1 readpos: 55316 readAdjust: 858793964 2010-04-08 18:00:04.286 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1) 2010-04-08 18:00:04.287 Position map filled from DB to: 24 2010-04-08 18:00:04.287 Dec: SyncPositionMap watchingrecording, from DB: 3 entries 2010-04-08 18:00:04.288 NVP(0): Filling position map from 25 to end 2010-04-08 18:00:04.289 Dec: Position map filled from Encoder to: 60 2010-04-08 18:00:04.289 Dec: SyncPositionMap watchingrecording total: 6 entries 2010-04-08 18:00:04.289 Dec: SyncPositionMap, new totframes: 60, new length: 2, posMap size: 6 2010-04-08 18:00:04.299 [mp2 @ 0x7f49da8c8820]Header missing 2010-04-08 18:00:04.299 AFD Error: Unknown audio decoding error 2010-04-08 18:00:04.397 NVP(0): 26400 interlaced frames seen. 2010-04-08 18:00:04.437 NVP(0): Video is 4.37698 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.437 NVP(0): Video is 6.48898 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.438 NVP(0): Video is 7.82923 frames behind audio (too slow), dropping frame to catch up. 'video_output' mean = '38806.24', std. dev. = '7136.44', fps = '25.77' 2010-04-08 18:00:04.438 NVP(0): Video is 8.5844 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.438 NVP(0): Video is 8.9008 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.438 NVP(0): Video is 8.8881 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.438 NVP(0): Video is 8.62858 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.438 NVP(0): Video is 8.18392 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.438 NVP(0): Video is 7.60042 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.439 NVP(0): Video is 6.91905 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.439 NVP(0): Video is 6.15802 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.439 NVP(0): Video is 5.33725 frames behind audio (too slow), dropping frame to catch up. 2010-04-08 18:00:04.439 NVP(0): prebuffering pause 2010-04-08 18:00:04.439 NVP(0): Waiting for prebuffer.. 0 AAAAAAAAaADLDADAA 2010-04-08 18:00:04.631 NVP(0): Video is 3.25185 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:04.711 NVP(0): Video is 4.52012 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:04.791 NVP(0): Video is 5.22757 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:04.871 NVP(0): Video is 5.50817 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:04.951 NVP(0): Video is 5.46863 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:05.031 NVP(0): Video is 5.1827 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:05.111 NVP(0): Video is 4.71828 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:05.191 NVP(0): Video is 4.11995 frames ahead of audio, doubling video frame interval to slow down. 2010-04-08 18:00:05.271 NVP(0): Video is 3.4087 frames ahead of audio, doubling video frame interval to slow down. **********************************************************************