2008-12-12 16:46:37.581 mythfrontend version: trunk [19241] www.mythtv.org
2008-12-12 16:46:37.609 Using runtime prefix = /usr/local
2008-12-12 16:46:38.580 Empty LocalHostName.
2008-12-12 16:46:38.580 Using localhost value of mythbox
2008-12-12 16:46:38.650 New DB connection, total: 1
2008-12-12 16:46:38.654 Connected to database 'mythconverg' at host: localhost
2008-12-12 16:46:38.666 Closing DB connection named 'DBManager0'
2008-12-12 16:46:38.734 DPMS is disabled.
2008-12-12 16:46:38.736 Primary screen: 0.
2008-12-12 16:46:38.736 Connected to database 'mythconverg' at host: localhost
2008-12-12 16:46:38.738 Using screen 0, 1920x1080 at 0,0
2008-12-12 16:46:38.782 MythUI Image Cache size set to 20971520 bytes
2008-12-12 16:46:38.782 user: 1000 effective user: 1000 before privileged thread
2008-12-12 16:46:38.782 user: 1000 effective user: 1000 after privileged thread
2008-12-12 16:46:38.783 user: 1000 effective user: 1000 run_priv_thread
2008-12-12 16:46:38.803 Current Schema Version: 1226
2008-12-12 16:46:38.803 Enabled verbose msgs:  important general playback
2008-12-12 16:46:38.925 Connecting to lcd server: localhost:6545 (try 1 of 10)
2008-12-12 16:46:38.965 New DB connection, total: 2
2008-12-12 16:46:38.966 Connected to database 'mythconverg' at host: localhost
2008-12-12 16:46:40.367 max_width: 1920 max_height: 1080
2008-12-12 16:46:40.588 Primary screen: 0.
2008-12-12 16:46:40.589 Using screen 0, 1920x1080 at 0,0
2008-12-12 16:46:40.590 Switching to wide mode (blootube-wide)
2008-12-12 16:46:40.604 Using the OpenGL painter
2008-12-12 16:46:40.644 lirc init success using configuration file: /home/spikeygg/.mythtv/lircrc
2008-12-12 16:46:41.775 Loading from: /usr/local/share/mythtv/themes/blootube-wide/base.xml
2008-12-12 16:46:42.157 Loading from: /usr/local/share/mythtv/themes/default-wide/base.xml
2008-12-12 16:46:42.172 Error, already have a global font called: basesmall
2008-12-12 16:46:42.172 Error, already have a global font called: basemedium
2008-12-12 16:46:42.172 Error, already have a global font called: baselarge
2008-12-12 16:46:42.172 Error, already have a global font called: baseextralarge
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallgrey
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallpurple
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallblack
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallyellow
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallgreen
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallblue
2008-12-12 16:46:42.172 Error, already have a global font called: basesmallred
2008-12-12 16:46:42.172 Error, already have a global font called: basemediumgrey
2008-12-12 16:46:42.172 Error, already have a global font called: basemediumgreen
2008-12-12 16:46:42.172 Error, already have a global font called: basemediumred
2008-12-12 16:46:42.173 Loading from: /usr/local/share/mythtv/themes/default/base.xml
2008-12-12 16:46:42.222 Using NV NPOT texture extension
2008-12-12 16:46:42.400 Connecting to backend server: 192.168.0.10:6543 (try 1 of 5)
2008-12-12 16:46:42.402 Using protocol version 42
2008-12-12 16:46:42.741 Registering Internal as a media playback plugin.
2008-12-12 16:46:43.486 MythMusic adding CD-Writer: ATA:0,0,0 -- DVD-E616A       
2008-12-12 16:46:43.506 MythMusic adding CD-Writer: ATAPI:0,0,0 -- DVD-E616A       
2008-12-12 16:46:43.645 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
2008-12-12 16:46:49.289 Loading from: /usr/local/share/mythtv/themes/blootube-wide/menu-ui.xml
2008-12-12 16:46:49.324 Found mainmenu.xml for theme 'blootube-wide'
2008-12-12 16:46:53.747 RingBuf(dvd://dev/dvd): OpenFile(dvd://dev/dvd, 12)
2008-12-12 16:46:53.747 OpenFile() trying DVD at /dev/dvd
libdvdnav: Using dvdnav version 0.1.10-xine from http://xine.sf.net
libdvdnav: DVD Title: WIT
libdvdnav: DVD Serial Number: 2AC67E08
libdvdnav: DVD Title (Alternative): WIT
libdvdnav: Unable to find map file '/home/spikeygg/.dvdnav/WIT.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1
2008-12-12 16:46:54.928 Opened DVD device at /dev/dvd
libdvdnav: Suspected RCE Region Protection!!!
2008-12-12 16:46:55.004 There are 4 titles on the disk
2008-12-12 16:46:55.005 Title 0 has 0 parts.
2008-12-12 16:46:55.005 Title 1 has 21 parts.
2008-12-12 16:46:55.005 Title 2 has 2 parts.
2008-12-12 16:46:55.005 Title 3 has 1 parts.
2008-12-12 16:46:55.031 RingBuf(/dev/dvd): CalcReadAheadThresh(4000 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:46:55.174 TV: Attempting to change from None to WatchingPreRecorded
2008-12-12 16:46:55.174 RingBuf(dvd://dev/dvd): OpenFile(dvd://dev/dvd, 12)
2008-12-12 16:46:55.174 OpenFile() trying DVD at /dev/dvd
libdvdnav: Using dvdnav version 0.1.10-xine from http://xine.sf.net
libdvdnav: DVD Title: WIT
libdvdnav: DVD Serial Number: 2AC67E08
libdvdnav: DVD Title (Alternative): WIT
libdvdnav: Unable to find map file '/home/spikeygg/.dvdnav/WIT.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1
2008-12-12 16:46:55.385 Opened DVD device at /dev/dvd
libdvdnav: Suspected RCE Region Protection!!!
2008-12-12 16:46:55.487 There are 4 titles on the disk
2008-12-12 16:46:55.488 Title 0 has 0 parts.
2008-12-12 16:46:55.488 Title 1 has 21 parts.
2008-12-12 16:46:55.488 Title 2 has 2 parts.
2008-12-12 16:46:55.488 Title 3 has 1 parts.
2008-12-12 16:46:55.488 RingBuf(/dev/dvd): CalcReadAheadThresh(4000 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:46:55.731 DVDNAV_HIGHLIGHT: display==1, palette==0, sx==0, sy==0, ex==0, ey==0, pts==0, buttonN==1
2008-12-12 16:46:55.731 DVDNAV_VTS_CHANGE: old_vtsN==0, new_vtsN==4, aspect: 3, perm: 1
2008-12-12 16:46:55.731 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 0 titleParts 0
2008-12-12 16:46:55.731 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:46:55.731 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0, total count 1
2008-12-12 16:46:55.731 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:46:55.750 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:46:55.750 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 0 titleParts 0
2008-12-12 16:46:55.750 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:46:55.750 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0, total count 1
2008-12-12 16:46:55.750 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:46:55.855 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:46:55.858 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:46:55.859 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:46:55.859 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:46:55.859 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:46:55.859 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:46:55.862 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:46:55.862 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:46:55.862 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:46:55.862 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:46:55.862 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:46:55.862 Using 1 CPUs for decoding
2008-12-12 16:46:55.862 AFD: InitVideoCodec() 0x14aa8ed0 id(MPEG2VIDEO) type (Video).
2008-12-12 16:46:55.862 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:46:55.862 AFD: Using ffmpeg for video decoding
2008-12-12 16:46:55.862 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:46:55.862 AFD: Opened codec 0x14aa8ed0, id(MPEG2VIDEO) type(Video)
2008-12-12 16:46:55.863 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:46:55.863 NVP: Disabling Audio, params(-1,-1,-1)
2008-12-12 16:46:55.863 No codec context. Returning false
2008-12-12 16:46:55.863 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2008-12-12 16:46:55.863 Position map filled from DB to: 0
2008-12-12 16:46:55.863 SyncPositionMap prerecorded, from DB: 1 entries
2008-12-12 16:46:55.863 SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1
2008-12-12 16:46:55.863 AFD: Position map found
2008-12-12 16:46:55.863 AFD: Successfully opened decoder for file: "/dev/dvd". novideo(0)
2008-12-12 16:46:55.924 VideoOutput: Allowed renderers: directfb,opengl,xv-blit,xshm,xlib
2008-12-12 16:46:55.924 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit,opengl
2008-12-12 16:46:55.926 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:46:55.926 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:46:55.926 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:46:55.926 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:46:55.926 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:46:55.926 VideoOutput: Preferred renderer: xv-blit
2008-12-12 16:46:55.926 VideoOutput: Trying video renderer: 'xv-blit'
2008-12-12 16:46:55.937 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:46:55.938 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:46:55.938 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:46:55.938 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:46:55.938 VideoOutputXv: ctor
2008-12-12 16:46:55.988 VideoOutputXv: Creating XJ_gc
2008-12-12 16:46:55.989 VideoOutputXv: XJ_screen:     '0x1505b880'
2008-12-12 16:46:55.989 VideoOutputXv: XJ_screen_num: '0'
2008-12-12 16:46:55.989 VideoOutputXv: XJ_curwin:     '31457315'
2008-12-12 16:46:55.989 VideoOutputXv: XJ_win:        '31457315'
2008-12-12 16:46:55.989 VideoOutputXv: XJ_root:       '422'
2008-12-12 16:46:55.989 VideoOutputXv: XJ_gc:         '0x14aa4d80'
2008-12-12 16:46:56.008 XOff: 0, YOff: 0
2008-12-12 16:46:56.008 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:46:56.008 Display Rect  left: 0, top: 60, width: 1920, height: 960, aspect: 1.33333
2008-12-12 16:46:56.008 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.5
2008-12-12 16:46:56.010 VideoOutputXv: Pixel dimensions: Screen 1920x1080, window 1920x1080
2008-12-12 16:46:56.011 VideoOutputXv: Estimated display dimensions: 610x343 mm  Aspect: 1.77843
2008-12-12 16:46:56.011 VideoOutputXv: Estimated window dimensions: 610x343 mm  Aspect: 1.77843
2008-12-12 16:46:56.012 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: opengl,xv-blit,xshm,xlib
2008-12-12 16:46:56.014 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:46:56.014 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:46:56.014 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:46:56.014 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:46:56.014 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:46:56.014 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2008-12-12 16:46:56.014 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2008-12-12 16:46:56.014 VideoOutputXv: Has XVideo flags...
2008-12-12 16:46:56.014 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2008-12-12 16:46:56.014 VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask 
2008-12-12 16:46:56.014 VideoOutputXv: Has XVideo flags...
2008-12-12 16:46:56.015 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2008-12-12 16:46:56.015 VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2008-12-12 16:46:56.015 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2008-12-12 16:46:56.015 VideoOutputXv: Has XVideo flags...
2008-12-12 16:46:56.015 VideoOutputXv: Here...
2008-12-12 16:46:56.015 VideoOutputXv: Grabbed xv port 355
2008-12-12 16:46:56.015 VideoOutputXv: XVideo surface found on port 355
2008-12-12 16:46:56.015 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2008-12-12 16:46:56.015 VideoOutputXv: XVideo Format #0 is 'YUY2'
2008-12-12 16:46:56.015 VideoOutputXv: XVideo Format #1 is 'YV12'
2008-12-12 16:46:56.015 VideoOutputXv: XVideo Format #2 is 'UYVY'
2008-12-12 16:46:56.015 VideoOutputXv: XVideo Format #3 is 'I420'
2008-12-12 16:46:56.015 VideoOutputXv: Using XVideo Format 'YV12'
2008-12-12 16:46:56.015 VideoOutputXv: CreateShmImages(32): video_dim: 720x480
2008-12-12 16:46:56.038 VDP: SetVideoRenderer(xv-blit)
2008-12-12 16:46:56.039 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2008-12-12 16:46:56.039 VideoOutputXv: Chromakeying not possible with this XVideo port.
2008-12-12 16:46:56.039 Display Rect  left: 150, top: 0, width: 1620, height: 1080, aspect: 1.77778
2008-12-12 16:46:56.039 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.5
2008-12-12 16:46:56.039 Over/underscan. V: 0, H: 0
2008-12-12 16:46:56.039 Display Rect  left: 150, top: 0, width: 1620, height: 1080, aspect: 1.77778
2008-12-12 16:46:56.039 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.5
2008-12-12 16:46:56.039 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:46:56.040 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:46:56.041 OSD Theme Dimensions W: 640 H: 480
2008-12-12 16:46:56.572 NVP: ClearAfterSeek(1)
2008-12-12 16:46:56.572 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:46:56.572 TV: StartPlayer(): took 841 ms to start player.
2008-12-12 16:46:56.572 TV: Changing from None to WatchingPreRecorded
2008-12-12 16:46:56.572 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:46:56.587 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:46:56.587 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:46:56.587 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.591 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:46:56.591 No codec context. Returning false
2008-12-12 16:46:56.592 AFD: DVD Title Changed
2008-12-12 16:46:56.592 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2008-12-12 16:46:56.592 Position map filled from DB to: 0
2008-12-12 16:46:56.592 SyncPositionMap prerecorded, from DB: 1 entries
2008-12-12 16:46:56.592 SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1
2008-12-12 16:46:56.592 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:46:56.592 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:46:56.592 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 0 titleParts 0
2008-12-12 16:46:56.592 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:46:56.592 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0, total count 1
2008-12-12 16:46:56.592 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:46:56.593 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:46:56.593 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x1575cd30
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:46:56.593 VideoOutputXv: InputChanged(720,480,1.33333) 'MPEG2'->'MPEG2'
2008-12-12 16:46:56.593 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:46:56.595 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:46:56.595 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x7f3990a17af0
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:46:56.595 Using deinterlace method greedyhdeint
2008-12-12 16:46:56.596 Using deinterlace method greedyhdeint
2008-12-12 16:46:56.597 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:46:56.597 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:46:56.597 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.597 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:46:56.597 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.603 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2008-12-12 16:46:56.603 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2008-12-12 16:46:56.697 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2008-12-12 16:46:56.697 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2008-12-12 16:46:56.697 OpenGLVideoSync()
2008-12-12 16:46:56.704 OpenGLVideoSync: x,y -> 960, 540
2008-12-12 16:46:56.729 Using OpenGLVideoSync
2008-12-12 16:46:56.746 Using audio as timebase
2008-12-12 16:46:56.746 Video timing method: SGI OpenGL
2008-12-12 16:46:56.746 Refresh rate: 16666, frame interval: 33366
2008-12-12 16:46:56.754 NVP: ClearAfterSeek(1)
2008-12-12 16:46:56.754 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:46:56.754 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:46:56.754 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:46:56.754 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:46:56.754 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.754 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:46:56.754 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:46:56.755 Entering DVD Still Frame
2008-12-12 16:46:56.755 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:46:56.755 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:46:56.755 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:46:56.756 VideoOutputXv: InputChanged(720,480,1.33333) 'MPEG2'->'MPEG2'
2008-12-12 16:46:56.756 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:46:56.757 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:46:56.757 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x166c0640
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:46:56.759 Using deinterlace method greedyhdeint
2008-12-12 16:46:56.759 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:46:56.759 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:46:56.759 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.759 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:46:56.759 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.763 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.764 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2008-12-12 16:46:56.764 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2008-12-12 16:46:56.764 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:46:56.773 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.784 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.794 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.805 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.815 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.826 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.836 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.847 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.857 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.867 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.877 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.887 NVP: ClearAfterSeek(1)
2008-12-12 16:46:56.887 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:46:56.887 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:46:56.887 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:46:56.887 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:46:56.887 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:46:56.887 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:46:56.887 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:46:56.887 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:46:56.887 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:46:56.887 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:46:56.887 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:46:56.887 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:46:56.887 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 0 titleParts 0
2008-12-12 16:46:56.888 Leaving DVD Still Frame
2008-12-12 16:46:56.888 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:46:56.888 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==0, current_track==0, total count 1
2008-12-12 16:46:56.888 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:46:56.889 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:46:56.889 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:46:56.889 Entering DVD Still Frame
2008-12-12 16:46:56.896 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:46:56.897 No codec context. Returning false
2008-12-12 16:46:56.897 DVDRB: Entering DVDNAV_WAIT
2008-12-12 16:46:56.898 DVDRB: Exiting DVDNAV_WAIT status
2008-12-12 16:46:56.931 VideoOutputXv: UpdatePauseFrame() uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:06.888 DVDNAV_VTS_CHANGE: old_vtsN==4, new_vtsN==1, aspect: 3, perm: 1
2008-12-12 16:47:06.888 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2008-12-12 16:47:06.888 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
2008-12-12 16:47:06.888 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 3, part == 1 titleParts 1
2008-12-12 16:47:06.888 Leaving DVD Still Frame
2008-12-12 16:47:06.888 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:06.888 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1, physical_letterbox==-1, physical_pan_scan==-1, current_track==-1, total count 0
2008-12-12 16:47:06.888 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:47:06.890 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:06.890 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:06.891 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:06.891 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x1598d7f0
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:06.891 Using deinterlace method greedyhdeint
2008-12-12 16:47:06.891 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:06.891 VideoBuffers::DiscardFrames(1): uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:06.892 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:06.892 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:06.892 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:06.896 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:07.019 NVP: ClearAfterSeek(1)
2008-12-12 16:47:07.019 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:07.019 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:07.020 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.020 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.020 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.021 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:07.021 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:07.021 Entering DVD Still Frame
2008-12-12 16:47:07.024 AFD: DVD Title Changed
2008-12-12 16:47:07.025 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:07.025 No codec context. Returning false
2008-12-12 16:47:07.025 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:47:07.025 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:07.025 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:47:07.025 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:07.025 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:07.026 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:07.027 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x1594c9a0
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:07.027 Using deinterlace method greedyhdeint
2008-12-12 16:47:07.027 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:07.027 VideoBuffers::DiscardFrames(1): AAuLAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.027 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.027 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.027 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.030 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.033 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:07.040 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.050 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.061 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.071 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.082 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.092 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.102 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.112 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.122 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.133 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.143 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.153 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:07.158 NVP: ClearAfterSeek(1)
2008-12-12 16:47:07.158 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:07.158 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:07.158 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.158 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.158 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.158 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:07.158 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
libdvdnav: RANDOM or SHUFFLE titles are NOT handled yet.
2008-12-12 16:47:07.158 DVDNAV_VTS_CHANGE: old_vtsN==1, new_vtsN==2, aspect: 3, perm: 2
2008-12-12 16:47:07.158 DVDNAV_CELL_CHANGE: pg_length == 810000, pgc_length == 810000, cell_start == 0, pg_start == 0, title == 2, part == 1 titleParts 2
2008-12-12 16:47:07.158 Leaving DVD Still Frame
2008-12-12 16:47:07.158 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:07.159 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1, physical_letterbox==-1, physical_pan_scan==-1, current_track==-1, total count 0
2008-12-12 16:47:07.159 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:47:07.163 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:07.163 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.164 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:07.165 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:07.165 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x1598ca60
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:07.165 Using deinterlace method greedyhdeint
2008-12-12 16:47:07.165 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:07.165 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.165 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.165 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.165 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.170 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:07.296 NVP: ClearAfterSeek(1)
2008-12-12 16:47:07.296 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:07.296 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:07.296 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.296 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.296 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.296 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:07.296 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:07.296 Entering DVD Still Frame
libdvdnav: RANDOM or SHUFFLE titles are NOT handled yet.
2008-12-12 16:47:07.296 AFD: DVD Title Changed
2008-12-12 16:47:07.296 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2008-12-12 16:47:07.296 Position map filled from DB to: 269
2008-12-12 16:47:07.296 SyncPositionMap prerecorded, from DB: 1 entries
2008-12-12 16:47:07.296 SyncPositionMap, new totframes: 269, new length: 9, posMap size: 1
2008-12-12 16:47:07.296 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:07.297 Leaving DVD Still Frame
2008-12-12 16:47:07.297 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:47:07.297 DVDNAV_CELL_CHANGE: pg_length == 810000, pgc_length == 810000, cell_start == 0, pg_start == 0, title == 2, part == 1 titleParts 2
2008-12-12 16:47:07.297 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:07.297 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1, physical_letterbox==-1, physical_pan_scan==-1, current_track==-1, total count 0
2008-12-12 16:47:07.297 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:47:07.297 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:07.297 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:47:07.297 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:47:07.297 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 9800000 at 0x0x14a97450
2008-12-12 16:47:07.297 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:07.297 RingBuf(/dev/dvd): CalcReadAheadThresh(9800 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(256 KB)
2008-12-12 16:47:07.297 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:07.297 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:07.299 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:07.299 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x166c0870
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:07.299 Using deinterlace method greedyhdeint
2008-12-12 16:47:07.299 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:07.299 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.299 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.299 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.299 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.305 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:07.313 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.429 NVP: ClearAfterSeek(1)
2008-12-12 16:47:07.429 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:07.429 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:07.429 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.429 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.429 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.429 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:07.429 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:07.429 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:47:07.429 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:47:07.432 No codec context. Returning false
2008-12-12 16:47:07.432 Dec: Trying to select track (w/lang)
2008-12-12 16:47:07.432 Dec: Selecting first track
2008-12-12 16:47:07.432 Dec: Selected track #1 in the Unknown language(0)
2008-12-12 16:47:07.433 No codec context. Returning false
2008-12-12 16:47:07.434 No codec context. Returning false
2008-12-12 16:47:07.435 No codec context. Returning false
2008-12-12 16:47:07.436 No codec context. Returning false
2008-12-12 16:47:07.437 No codec context. Returning false
2008-12-12 16:47:07.443 No codec context. Returning false
2008-12-12 16:47:07.446 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:47:07.446 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:47:07.446 AFD: HandleGopStart: Initial key frame distance: 15.
2008-12-12 16:47:07.448 No codec context. Returning false
2008-12-12 16:47:07.448 AFD: HandleGopStart: Key frame distance changed from 8 to 1.
2008-12-12 16:47:07.450 No codec context. Returning false
2008-12-12 16:47:07.452 No codec context. Returning false
2008-12-12 16:47:07.455 No codec context. Returning false
2008-12-12 16:47:07.457 No codec context. Returning false
2008-12-12 16:47:07.460 No codec context. Returning false
2008-12-12 16:47:07.462 No codec context. Returning false
2008-12-12 16:47:07.465 No codec context. Returning false
2008-12-12 16:47:07.466 AFD: HandleStreamChange(): streams_changed 0x14a9d220 -- stream count 2
2008-12-12 16:47:07.466 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 9800000 at 0x0x14a97450
2008-12-12 16:47:07.466 NVP: progressive frame seen after 2 interlaced  frames
2008-12-12 16:47:07.466 Disabled deinterlacing
2008-12-12 16:47:07.468 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:47:07.468 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:47:07.468 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:47:07.468 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:47:07.468 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:47:07.470 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:47:07.470 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:47:07.470 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:47:07.470 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:47:07.470 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:47:07.470 Using 1 CPUs for decoding
2008-12-12 16:47:07.470 AFD: Warning, video codec 0x14aa8ed0 id(MPEG2VIDEO) type (Video) already open.
2008-12-12 16:47:07.470 AFD: InitVideoCodec() 0x14aa8ed0 id(MPEG2VIDEO) type (Video).
2008-12-12 16:47:07.470 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:07.470 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:07.471 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:07.471 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x166c0670
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:07.472 Using deinterlace method greedyhdeint
2008-12-12 16:47:07.472 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:07.472 VideoBuffers::DiscardFrames(1): AAAAUUUUUUUUUUUUuUULAAAAAAAAAAA
2008-12-12 16:47:07.472 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.472 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.472 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.476 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:07.484 NVP: prebuffering pause
2008-12-12 16:47:07.484 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.601 NVP: ClearAfterSeek(1)
2008-12-12 16:47:07.601 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:07.601 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:07.601 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.601 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:07.601 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:07.601 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:07.601 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:07.602 Enabled deinterlacing
2008-12-12 16:47:07.602 AFD: Using ffmpeg for video decoding
2008-12-12 16:47:07.602 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:07.602 AFD: Stream #1, has id 0x128 codec id AC3, type Audio, bitrate 0 at 0x0x15610d90
2008-12-12 16:47:07.602 AFD: codec AC3 has 0 channels
2008-12-12 16:47:07.602 AFD: Looking for decoder for AC3
2008-12-12 16:47:07.602 AFD: Opened codec 0x1563d210, id(AC3) type(Audio)
2008-12-12 16:47:07.602 RingBuf(/dev/dvd): CalcReadAheadThresh(9800 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(256 KB)
2008-12-12 16:47:07.605 Opening audio device 'default'. ch 2(2) sr 48000
2008-12-12 16:47:07.605 Opening ALSA audio device 'default'.
2008-12-12 16:47:07.629 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:07.641 NVP: Enabling Audio
2008-12-12 16:47:07.643 Opening audio device 'default'. ch 2(2) sr 48000
2008-12-12 16:47:07.643 Opening ALSA audio device 'default'.
2008-12-12 16:47:07.648 Dec: Trying to reselect track
2008-12-12 16:47:07.648 Dec: Selected track #1 in the Unknown language(0)
2008-12-12 16:47:07.679 NVP: progressive frame seen after 2 interlaced  frames
2008-12-12 16:47:07.679 Disabled deinterlacing
2008-12-12 16:47:07.730 NVP: Video is 3.54403 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:07.791 GetNextFreeFrame() served a busy frame 3. Dropping. UUUUUUUUUUUUUUUUAuLUAAUUUUUUUUU
2008-12-12 16:47:07.798 NVP: Video is 6.19454 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:07.864 NVP: Video is 7.93517 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:07.930 NVP: Video is 8.99338 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:07.997 NVP: Video is 9.54726 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.064 NVP: Video is 9.70794 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.130 NVP: Video is 9.5737 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.197 NVP: Video is 9.23326 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.264 NVP: Video is 8.72316 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.330 NVP: Video is 8.08584 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.397 NVP: Video is 7.36061 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.464 NVP: Video is 6.56941 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.530 NVP: Video is 5.72127 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.597 NVP: Video is 4.83792 frames ahead of audio,
			doubling video frame interval to slow down.
2008-12-12 16:47:08.664 NVP: Video is 3.92813 frames ahead of audio,
			doubling video frame interval to slow down.
'video_output' mean = '40295.15', std. dev. = '21205.01', fps = '24.82'
'video_output' mean = '33334.17', std. dev. = '300.42', fps = '30.00'
2008-12-12 16:47:15.380 DVDNAV_VTS_CHANGE: old_vtsN==2, new_vtsN==1, aspect: 3, perm: 1
2008-12-12 16:47:15.381 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 3, part == 1 titleParts 1
2008-12-12 16:47:15.381 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:15.381 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1, physical_letterbox==-1, physical_pan_scan==-1, current_track==-1, total count 0
2008-12-12 16:47:15.381 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:47:15.381 AFD: DVD Title Changed
2008-12-12 16:47:15.382 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2008-12-12 16:47:15.382 Position map filled from DB to: 0
2008-12-12 16:47:15.382 SyncPositionMap prerecorded, from DB: 1 entries
2008-12-12 16:47:15.382 SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1
2008-12-12 16:47:15.382 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:15.382 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:15.382 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:15.383 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:15.383 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x15513b50
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:15.384 Using deinterlace method greedyhdeint
2008-12-12 16:47:15.384 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:15.384 VideoBuffers::DiscardFrames(1): UUAUUUUUUUUUUUUUUUULuUUuUUUUUUU
2008-12-12 16:47:15.384 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.384 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.384 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.417 NVP: prebuffering pause
2008-12-12 16:47:15.417 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:15.526 NVP: ClearAfterSeek(1)
2008-12-12 16:47:15.526 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:15.526 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:15.526 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:15.526 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.526 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.527 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:15.527 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:15.527 Enabled deinterlacing
2008-12-12 16:47:15.527 Entering DVD Still Frame
2008-12-12 16:47:15.527 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:47:15.527 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:15.527 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:47:15.527 NVP: Disabling Audio, params(-1,-1,-1)
2008-12-12 16:47:15.527 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:15.527 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:15.529 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:15.529 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x16831e30
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:15.529 Using deinterlace method greedyhdeint
2008-12-12 16:47:15.530 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:15.530 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:15.530 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.530 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.530 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.534 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:15.563 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.573 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.583 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.593 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.603 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.614 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.624 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.634 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.644 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.654 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.664 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.667 NVP: ClearAfterSeek(1)
2008-12-12 16:47:15.667 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:15.667 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:15.667 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:15.667 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.667 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.667 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:15.667 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:15.667 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:47:15.667 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:47:15.670 No codec context. Returning false
2008-12-12 16:47:15.670 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:47:15.670 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 3, part == 1 titleParts 1
2008-12-12 16:47:15.670 Leaving DVD Still Frame
2008-12-12 16:47:15.671 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:15.671 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1, physical_letterbox==-1, physical_pan_scan==-1, current_track==-1, total count 0
2008-12-12 16:47:15.671 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:47:15.671 AFD: HandleGopStart: gopset not set, syncing positionMap
2008-12-12 16:47:15.671 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2008-12-12 16:47:15.671 AFD: HandleGopStart: Initial key frame distance: 15.
2008-12-12 16:47:15.671 Entering DVD Still Frame
2008-12-12 16:47:15.674 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:15.674 No codec context. Returning false
2008-12-12 16:47:15.675 DVDNAV_VTS_CHANGE: old_vtsN==1, new_vtsN==4, aspect: 3, perm: 1
2008-12-12 16:47:15.675 NVP: progressive frame seen after 2 interlaced  frames
2008-12-12 16:47:15.675 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 3 titleParts 0
2008-12-12 16:47:15.675 Disabled deinterlacing
2008-12-12 16:47:15.675 Leaving DVD Still Frame
2008-12-12 16:47:15.675 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:15.675 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==1, current_track==0, total count 1
2008-12-12 16:47:15.675 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 0
2008-12-12 16:47:15.756 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:15.756 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:15.757 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:15.757 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x16832430
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:15.757 Using deinterlace method greedyhdeint
2008-12-12 16:47:15.758 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:15.758 VideoBuffers::DiscardFrames(1): AAAAAAALAAAAAAAAAAAAAAAAuAAAAAA
2008-12-12 16:47:15.758 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.758 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.758 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.762 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:15.788 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:15.891 NVP: ClearAfterSeek(1)
2008-12-12 16:47:15.891 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:15.891 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:15.891 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:15.891 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.891 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.891 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:15.891 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:15.891 Enabled deinterlacing
2008-12-12 16:47:15.891 Entering DVD Still Frame
2008-12-12 16:47:15.896 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.902 AFD: DVD Title Changed
2008-12-12 16:47:15.903 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:15.903 No codec context. Returning false
2008-12-12 16:47:15.903 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:47:15.903 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:15.903 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:47:15.903 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:15.903 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:15.904 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:15.904 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x1594d1d0
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:15.904 Using deinterlace method greedyhdeint
2008-12-12 16:47:15.904 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:15.904 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAALAuA
2008-12-12 16:47:15.904 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.904 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:15.905 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:15.906 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.909 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:15.916 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.927 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.937 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.947 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.957 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.967 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.977 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.987 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:15.998 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.008 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.018 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.028 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.036 NVP: ClearAfterSeek(1)
2008-12-12 16:47:16.036 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:16.036 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:16.036 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:16.036 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:16.036 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:16.036 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:16.036 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:16.036 AFD: HandleStreamChange(): streams_changed 0x14a9d220 -- stream count 2
2008-12-12 16:47:16.037 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:47:16.038 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.039 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:47:16.039 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:47:16.039 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:47:16.039 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:47:16.039 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:47:16.041 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:47:16.041 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:47:16.041 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:47:16.041 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:47:16.041 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:47:16.041 Using 1 CPUs for decoding
2008-12-12 16:47:16.041 AFD: Warning, video codec 0x14aa8ed0 id(MPEG2VIDEO) type (Video) already open.
2008-12-12 16:47:16.041 AFD: InitVideoCodec() 0x14aa8ed0 id(MPEG2VIDEO) type (Video).
2008-12-12 16:47:16.041 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:16.041 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:16.042 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:16.042 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x16a2df30
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:16.043 Using deinterlace method greedyhdeint
2008-12-12 16:47:16.043 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:16.043 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:16.043 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:16.043 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:16.043 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:16.048 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:16.048 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.058 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.069 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.079 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.089 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.099 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.109 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.119 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.130 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.140 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.150 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.160 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.170 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.174 NVP: ClearAfterSeek(1)
2008-12-12 16:47:16.174 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:16.174 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:16.174 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:16.174 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:16.174 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:16.174 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:16.174 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:16.174 AFD: Using ffmpeg for video decoding
2008-12-12 16:47:16.174 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:16.174 AFD: Stream #1, has id 0x32 codec id DVD_SUBTITLE, type Subtitle, bitrate 0 at 0x0x16e9d900
2008-12-12 16:47:16.174 AFD: subtitle codec (Subtitle)
2008-12-12 16:47:16.174 AFD: Looking for decoder for DVD_SUBTITLE
2008-12-12 16:47:16.174 AFD: Opened codec 0x15fc17d0, id(DVD_SUBTITLE) type(Subtitle)
2008-12-12 16:47:16.174 AFD: Subtitle track #1 is A/V stream #1 and is in the Undetermined language(7695972).
2008-12-12 16:47:16.174 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:47:16.174 AFD: DVD Subtitle Track Map Stream id #32 track #-1 
2008-12-12 16:47:16.174 Leaving DVD Still Frame
2008-12-12 16:47:16.175 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:47:16.175 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==1
2008-12-12 16:47:16.175 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 3 titleParts 0
2008-12-12 16:47:16.175 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:16.175 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==1, current_track==0, total count 1
2008-12-12 16:47:16.175 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 0
2008-12-12 16:47:16.177 Entering DVD Still Frame
2008-12-12 16:47:16.177 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:16.180 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:16.188 No codec context. Returning false
2008-12-12 16:47:16.188 Leaving DVD Still Frame
2008-12-12 16:47:16.188 DVDRB: Clearing Menu SPU Packet
2008-12-12 16:47:16.188 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==1
2008-12-12 16:47:16.188 AFD: HandleStreamChange(): streams_changed 0x14a9d220 -- stream count 3
2008-12-12 16:47:16.188 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:47:16.190 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:47:16.190 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:47:16.190 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:47:16.190 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:47:16.190 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:47:16.192 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,yadifdeint) filt()
2008-12-12 16:47:16.192 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdeint,linearblend) filt()
2008-12-12 16:47:16.192 VDP: LoadBestPreferences(2048x2048, 0)
2008-12-12 16:47:16.192 VDP: LoadBestPreferences(2048x2048, 60)
2008-12-12 16:47:16.192 VDP: LoadBestPreferences(720x480, 60)
2008-12-12 16:47:16.192 Using 1 CPUs for decoding
2008-12-12 16:47:16.192 AFD: Warning, video codec 0x14aa8ed0 id(MPEG2VIDEO) type (Video) already open.
2008-12-12 16:47:16.192 AFD: InitVideoCodec() 0x14aa8ed0 id(MPEG2VIDEO) type (Video).
2008-12-12 16:47:16.192 AFD: Using ffmpeg for video decoding
2008-12-12 16:47:16.192 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:16.192 AFD: Stream #1, has id 0x32 codec id DVD_SUBTITLE, type Subtitle, bitrate 0 at 0x0x16e9d900
2008-12-12 16:47:16.193 AFD: subtitle codec (Subtitle)
2008-12-12 16:47:16.193 AFD: Looking for decoder for DVD_SUBTITLE
2008-12-12 16:47:16.193 AFD: Subtitle track #1 is A/V stream #1 and is in the Undetermined language(7695972).
2008-12-12 16:47:16.193 AFD: Stream #2, has id 0x33 codec id DVD_SUBTITLE, type Subtitle, bitrate 0 at 0x0x163727c0
2008-12-12 16:47:16.193 AFD: subtitle codec (Subtitle)
2008-12-12 16:47:16.193 AFD: Looking for decoder for DVD_SUBTITLE
2008-12-12 16:47:16.193 AFD: Opened codec 0x1526a4f0, id(DVD_SUBTITLE) type(Subtitle)
2008-12-12 16:47:16.193 AFD: Subtitle track #2 is A/V stream #2 and is in the Undetermined language(7695972).
2008-12-12 16:47:16.193 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:47:16.193 AFD: DVD Subtitle Track Map Stream id #32 track #-1 
2008-12-12 16:47:16.193 AFD: DVD Subtitle Track Map Stream id #33 track #-1 
2008-12-12 16:47:16.193 DVDRB: Entering DVDNAV_WAIT
2008-12-12 16:47:16.224 VideoOutputXv: UpdatePauseFrame() LAAAAAAAAAuAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:16.263 DVDRB: Exiting DVDNAV_WAIT status
2008-12-12 16:47:16.270 Entering DVD Still Frame
2008-12-12 16:47:24.485 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==2
2008-12-12 16:47:25.298 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==1
2008-12-12 16:47:26.107 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==2
2008-12-12 16:47:26.712 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==3
2008-12-12 16:47:31.771 DVDNAV_HOP_CHANNEL happened.
2008-12-12 16:47:31.771 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==1
2008-12-12 16:47:31.771 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 0, part == 3 titleParts 0
2008-12-12 16:47:31.771 Leaving DVD Still Frame
2008-12-12 16:47:31.771 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:31.771 DVDNAV_SPU_STREAM_CHANGE: physical_wide==0, physical_letterbox==0, physical_pan_scan==1, current_track==0, total count 1
2008-12-12 16:47:31.771 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 0
2008-12-12 16:47:31.773 Entering DVD Still Frame
2008-12-12 16:47:31.773 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:31.784 No codec context. Returning false
2008-12-12 16:47:31.784 Leaving DVD Still Frame
2008-12-12 16:47:31.784 DVDRB: Clearing Menu SPU Packet
2008-12-12 16:47:31.785 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==1
2008-12-12 16:47:31.785 Entering DVD Still Frame
2008-12-12 16:47:31.829 NVP: progressive frame seen after 2 interlaced  frames
2008-12-12 16:47:31.830 Disabled deinterlacing
2008-12-12 16:47:31.880 VideoOutputXv: UpdatePauseFrame() AAAuAAAAAAAALAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:33.688 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==2
2008-12-12 16:47:34.403 DVDNAV_HIGHLIGHT: display==1, palette==262212, sx==260, sy==35073, ex==63683, ey==0, pts==302037761, buttonN==3
2008-12-12 16:47:37.332 TV: Attempting to change from WatchingPreRecorded to None
2008-12-12 16:47:37.333 TV: StopStuff() -- begin
2008-12-12 16:47:37.333 TV:  StopStuff() -- get dvd player out of still frame or wait status
2008-12-12 16:47:37.333 TV: StopStuff(): stopping ring buffer[s]
2008-12-12 16:47:37.333 TV: StopStuff(): stopping player[s] (1/2)
2008-12-12 16:47:37.333 TV: StopStuff(): stopping player[s] (2/2)
2008-12-12 16:47:37.341 DVDNAV_VTS_CHANGE: old_vtsN==4, new_vtsN==1, aspect: 3, perm: 1
2008-12-12 16:47:37.342 DVDNAV_CELL_CHANGE: pg_length == 45000, pgc_length == 45000, cell_start == 0, pg_start == 0, title == 3, part == 1 titleParts 1
2008-12-12 16:47:37.342 Leaving DVD Still Frame
2008-12-12 16:47:37.342 DVDNAV_SPU_CLUT_CHANGE happened.
2008-12-12 16:47:37.342 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1, physical_letterbox==-1, physical_pan_scan==-1, current_track==-1, total count 0
2008-12-12 16:47:37.342 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0 Track Count 1
2008-12-12 16:47:37.342 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:37.342 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:37.343 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:37.343 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x1569b7e0
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:37.343 Using deinterlace method greedyhdeint
2008-12-12 16:47:37.343 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:37.344 VideoBuffers::DiscardFrames(1): AAAuAAAAAAAALAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:37.344 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.344 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:37.344 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.482 NVP: ClearAfterSeek(1)
2008-12-12 16:47:37.482 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:37.482 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:37.482 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:37.482 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:37.482 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.484 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:37.484 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:37.484 Enabled deinterlacing
2008-12-12 16:47:37.484 Entering DVD Still Frame
2008-12-12 16:47:37.484 AFD: DVD Title Changed
2008-12-12 16:47:37.484 AFD: DVD Cell Changed. Update framesPlayed: 0 
2008-12-12 16:47:37.487 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 4587200 at 0x0x14a97450
2008-12-12 16:47:37.487 AFD: Looking for decoder for MPEG2VIDEO
2008-12-12 16:47:37.487 AFD: Stream #1, has id 0x32 codec id DVD_SUBTITLE, type Subtitle, bitrate 0 at 0x0x16e9d900
2008-12-12 16:47:37.487 AFD: subtitle codec (Subtitle)
2008-12-12 16:47:37.487 AFD: Looking for decoder for DVD_SUBTITLE
2008-12-12 16:47:37.487 AFD: Subtitle track #1 is A/V stream #1 and is in the Undetermined language(7695972).
2008-12-12 16:47:37.488 AFD: Stream #2, has id 0x33 codec id DVD_SUBTITLE, type Subtitle, bitrate 0 at 0x0x163727c0
2008-12-12 16:47:37.488 AFD: subtitle codec (Subtitle)
2008-12-12 16:47:37.488 AFD: Looking for decoder for DVD_SUBTITLE
2008-12-12 16:47:37.488 AFD: Subtitle track #2 is A/V stream #2 and is in the Undetermined language(7695972).
2008-12-12 16:47:37.488 RingBuf(/dev/dvd): CalcReadAheadThresh(4588 KB)
			 -> threshhold(64 KB) min read(32 KB) blk size(64 KB)
2008-12-12 16:47:37.488 AFD: DVD Subtitle Track Map Stream id #32 track #-1 
2008-12-12 16:47:37.488 AFD: DVD Subtitle Track Map Stream id #33 track #-1 
2008-12-12 16:47:37.488 VideoOutputXv: InputChanged(720,480,1.77778) 'MPEG2'->'MPEG2'
2008-12-12 16:47:37.489 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdeint'
2008-12-12 16:47:37.490 FilterManager: GetFilterInfo(convert) returning: 0x0
2008-12-12 16:47:37.490 FilterManager: GetFilterInfo(greedyhdeint) returning: 0x16a85d10
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-12-12 16:47:37.490 Using deinterlace method greedyhdeint
2008-12-12 16:47:37.491 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:37.491 VideoBuffers::DiscardFrames(1): AAAAuALAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:37.491 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.491 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:37.491 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.496 VDP: LoadBestPreferences(720x480, 29.97)
2008-12-12 16:47:37.496 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.507 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.517 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.527 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.537 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.548 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.558 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.569 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.579 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.589 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.599 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.610 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.620 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.630 NVP, Error: In DVD Menu: No video frames in queue
2008-12-12 16:47:37.638 NVP: ClearAfterSeek(1)
2008-12-12 16:47:37.638 VideoOutputXv: ClearAfterSeek()
2008-12-12 16:47:37.638 VideoOutputXv: DiscardFrames(0)
2008-12-12 16:47:37.638 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:37.639 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:37.639 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.639 NVP: LoadFilters(''..) -> 0x0
2008-12-12 16:47:37.639 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-12-12 16:47:37.639 NVP: Exited decoder loop.
2008-12-12 16:47:37.640 ~OpenGLVideoSync() -- begin
2008-12-12 16:47:37.640 ~OpenGLVideoSync() -- middle
2008-12-12 16:47:37.641 ~OpenGLVideoSync() -- end
2008-12-12 16:47:37.641 VideoOutputXv: dtor
2008-12-12 16:47:37.641 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:37.641 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:37.641 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.641 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:37.641 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.641 VideoOutputXv: DiscardFrames(1)
2008-12-12 16:47:37.641 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-12-12 16:47:37.641 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.641 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-12-12 16:47:37.641 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-12-12 16:47:37.642 VideoOutputXv: Closing XVideo port 355
2008-12-12 16:47:37.697 DVDRB: Clearing Menu SPU Packet
2008-12-12 16:47:37.697 TV: StopStuff() -- end
2008-12-12 16:47:37.697 TV: Changing from WatchingPreRecorded to None
2008-12-12 16:47:41.454 Deleting UPnP client...