View unanswered posts    View active topics

All times are UTC - 6 hours





Post new topic Reply to topic  [ 95 posts ] 
Go to page Previous  1, 2, 3, 4, 5, 6, 7  Next

Print view Previous topic   Next topic  
Author Message
Search for:
PostPosted: Wed Dec 29, 2010 8:32 pm 
Offline
Joined: Fri Jul 21, 2006 11:12 pm
Posts: 1194
Location: SC
neutron68 wrote:
Is this intended to help us see what version the mythtv built in player is?
No, it is intended to see more detailed logs of the playback of the files that are having issues.


Top
 Profile  
 
PostPosted: Wed Dec 29, 2010 10:46 pm 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
bfransen,
I didn't see any clues from that playback command, did you?
Maybe you intended me to insert a video filename somewhere in the command line?

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Top
 Profile  
 
 Post subject:
PostPosted: Wed Dec 29, 2010 11:00 pm 
Offline
Joined: Fri Jul 21, 2006 11:12 pm
Posts: 1194
Location: SC
From the log you posted it doesn't look like you played the recording back. You need to play the recording back in mythfrontend just like you have done when it gave you the problem. Then look at the log and see what additional info is provided.

Britney


Top
 Profile  
 
 Post subject: list of steps?
PostPosted: Wed Dec 29, 2010 11:55 pm 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
brfransen wrote:
From the log you posted it doesn't look like you played the recording back. You need to play the recording back in mythfrontend just like you have done when it gave you the problem. Then look at the log and see what additional info is provided.

Britney
No, I didn't play a recording back. You mentioned exiting the Mythtv frontend, opening an xterm window and typing the command - no mention of playback.

Is this the list of steps you are suggesting? (please edit the list, if I'm wrong)

1. play back a problem KMSP video with the built-in mythtv player.
2. stop the video.
3. exit the Mythtv frontend to the Fluxbox desktop.
4. use ALT-X to open an xterm window.
5. as the mythtv user, type "mythfrontend -v playback" at the $ prompt.
6. note the output.

Eric

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Top
 Profile  
 
 Post subject:
PostPosted: Thu Dec 30, 2010 12:41 am 
Offline
Joined: Fri Jul 21, 2006 11:12 pm
Posts: 1194
Location: SC
We are trying to capture additional info about the playback of the problem recording with the '-v playback' that is more than the default logging that is captured in the mythfrontend.log. To do that we run mythfrontend manually from an xterm. Here are your steps rearranged:

-exit the Mythtv frontend to the Fluxbox desktop.
-use ALT-X to open an xterm window.
-as the mythtv user, type "mythfrontend -v playback" at the $ prompt
-play back a problem KMSP video with the built-in mythtv player.
-stop the video.
-note the output.

Britney


Top
 Profile  
 
 Post subject: here we go!
PostPosted: Thu Dec 30, 2010 9:10 pm 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
Thanks. Listing all the steps helps a lot. It eliminates the guessing on this end.

Here we go! This is the log from 10 seconds of playback of a stuttering Fox/KMSP recording. This is from tonight's 7pm episode of Bones.
Code:
eric@mythtv:~$ cat /home/mythtv/output5.txt
2010-12-30 21:03:18.875 Using runtime prefix = /usr
2010-12-30 21:03:19.467 DPMS is disabled.
2010-12-30 21:03:19.468 Empty LocalHostName.
2010-12-30 21:03:19.468 Using localhost value of mythtv
2010-12-30 21:03:19.490 New DB connection, total: 1
2010-12-30 21:03:19.500 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:03:19.502 Closing DB connection named 'DBManager0'
2010-12-30 21:03:19.504 Primary screen 0.
2010-12-30 21:03:19.504 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:03:19.506 Using screen 0, 1920x1080 at 0,0
2010-12-30 21:03:19.525 user: 100 effective user: 100 before privileged thread
2010-12-30 21:03:19.525 user: 100 effective user: 100 run_priv_thread
2010-12-30 21:03:19.526 user: 100 effective user: 100 after privileged thread
2010-12-30 21:03:19.527 New DB connection, total: 2
2010-12-30 21:03:19.528 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:03:19.530 mythfrontend version: 0.21.20080304-1 www.mythtv.org
2010-12-30 21:03:19.530 Enabled verbose msgs:  important general playback
2010-12-30 21:03:19.649 Unable to parse themeinfo.xml for glass-wide
2010-12-30 21:03:19.649 The theme (glass-wide) is missing a themeinfo.xml file
2010-12-30 21:03:19.881 Unable to parse themeinfo.xml for glass-wide
2010-12-30 21:03:19.881 The theme (glass-wide) is missing a themeinfo.xml file
2010-12-30 21:03:20.103 max_width: 1920 max_height: 1080
2010-12-30 21:03:20.210 No theme dir: /home/mythtv/.mythtv/themes/neon-wide
2010-12-30 21:03:20.211 Primary screen 0.
2010-12-30 21:03:20.211 Using screen 0, 1920x1080 at 0,0
2010-12-30 21:03:20.212 No theme dir: /home/mythtv/.mythtv/themes/neon-wide
2010-12-30 21:03:20.213 Switching to wide mode (neon-wide)
2010-12-30 21:03:20.233 Using the Qt painter
2010-12-30 21:03:20.235 JoystickMenuClient Error: Joystick disabled - Failed to open device /dev/input/js0
2010-12-30 21:03:20.236 lirc init success using configuration file: /home/mythtv/.mythtv/lircrc
2010-12-30 21:03:21.828 Loading from: /usr/share/mythtv/themes/neon-wide/base.xml
2010-12-30 21:03:21.901 Loading from: /usr/share/mythtv/themes/default/base.xml
2010-12-30 21:03:21.924 Registering Internal as a media playback plugin.
2010-12-30 21:03:21.994 MonitorRegisterExtensions(0x100, gif,jpg,png)
2010-12-30 21:03:22.133 MythMusic adding CD-Writer: ATA:1,0,0 -- DVD-RW  DVR-111D
2010-12-30 21:03:23.640 Failed to run 'cdrecord --scanbus -dev=ATAPI'
2010-12-30 21:03:23.675 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
SIP listening on IP Address 192.168.7.10:5060 NAT address 192.168.7.10
SIP: Cannot register; proxy, username or password not set
2010-12-30 21:03:23.816 No theme dir: /home/mythtv/.mythtv/themes/neon-wide
2010-12-30 21:03:28.757 XMLParse::LoadTheme using /usr/share/mythtv/themes/neon-wide/ui.xml
2010-12-30 21:03:29.172 Connecting to backend server: 127.0.0.1:6543 (try 1 of 5)
2010-12-30 21:03:29.173 Using protocol version 40
2010-12-30 21:03:33.121 TV: Attempting to change from None to WatchingPreRecorded
2010-12-30 21:03:33.123 RingBuf(/myth/tv/1901_20101230193000.mpg): OpenFile(/myth/tv/1901_20101230193000.mpg, 12)
2010-12-30 21:03:33.124 RingBuf(/myth/tv/1901_20101230193000.mpg): CalcReadAheadThresh(0 KB)
          -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-12-30 21:03:33.356 AFD: Stream #0, has id 0x49 codec id MPEG2VIDEO, type Video, bitrate 38800000 at 0x0x82b8860
2010-12-30 21:03:33.359 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:03:33.359 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:03:33.359 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:03:33.359 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:03:33.359 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 21:03:33.360 Using 1 CPUs for decoding
2010-12-30 21:03:33.361 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:03:33.361 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:03:33.361 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:03:33.361 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:03:33.362 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 21:03:33.362 AFD: InitVideoCodec() 0x831a390 id(MPEG2VIDEO) type (Video).
2010-12-30 21:03:33.362 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2010-12-30 21:03:33.362 AFD: EIA-608 caption 1 is in the Undetermined language.
2010-12-30 21:03:33.362 AFD: EIA-708 caption service #1 is in the English language.
2010-12-30 21:03:33.362 AFD: Using ffmpeg for video decoding
2010-12-30 21:03:33.363 AFD: Looking for decoder for MPEG2VIDEO
2010-12-30 21:03:33.363 AFD: Opened codec 0x831a390, id(MPEG2VIDEO) type(Video)
2010-12-30 21:03:33.363 AFD: Stream #1, has id 0x52 codec id AC3, type Audio, bitrate 448000 at 0x0x82b90d0
2010-12-30 21:03:33.363 AFD: codec AC3 has 6 channels
2010-12-30 21:03:33.363 AFD: Looking for decoder for AC3
2010-12-30 21:03:33.364 AFD: Opened codec 0x831a700, id(AC3) type(Audio)
2010-12-30 21:03:33.364 AFD: Stream #2, has id 0x53 codec id AC3, type Audio, bitrate 192000 at 0x0x82d7db0
2010-12-30 21:03:33.364 AFD: codec AC3 has 2 channels
2010-12-30 21:03:33.364 AFD: Looking for decoder for AC3
2010-12-30 21:03:33.365 AFD: Opened codec 0x8366980, id(AC3) type(Audio)
2010-12-30 21:03:33.366 RingBuf(/myth/tv/1901_20101230193000.mpg): CalcReadAheadThresh(3079680956 KB)
          -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-12-30 21:03:33.371 Opening audio device '/dev/dsp'. ch 2(2) sr 48000
2010-12-30 21:03:33.371 Opening OSS audio device '/dev/dsp'.
2010-12-30 21:03:33.376 Dec: Selected track #1 in the Undetermined language(7695972)
2010-12-30 21:03:33.376 Dec: Selected track #1 in the English language(6647399)
2010-12-30 21:03:33.376 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2010-12-30 21:03:33.452 Position map filled from DB to: 108003
2010-12-30 21:03:33.454 SyncPositionMap prerecorded, from DB: 6933 entries
2010-12-30 21:03:33.454 SyncPositionMap, new totframes: 108003, new length: 1801, posMap size: 6933
2010-12-30 21:03:33.454 AFD: Position map found
2010-12-30 21:03:33.454 AFD: Successfully opened decoder for file: "/myth/tv/1901_20101230193000.mpg". novideo(0)
2010-12-30 21:03:33.463 VideoOutput: Allowed renderers: directfb,opengl,xv-blit,xshm,xlib
2010-12-30 21:03:33.463 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit,opengl
2010-12-30 21:03:33.465 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:03:33.465 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:03:33.465 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:03:33.465 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:03:33.465 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 21:03:33.466 VideoOutput: Preferred renderer: xv-blit
2010-12-30 21:03:33.466 VideoOutput: Trying video renderer: xv-blit
2010-12-30 21:03:33.467 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:03:33.467 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:03:33.467 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:03:33.467 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:03:33.482 VideoOutputXv: ctor
2010-12-30 21:03:33.483 XOff: 0, YOff: 0
2010-12-30 21:03:33.483 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 21:03:33.484 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
2010-12-30 21:03:33.484 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 21:03:33.485 VideoOutputXv: Pixel dimensions: Screen 1920x1080, window 1920x1080
2010-12-30 21:03:33.486 VideoOutputXv: Estimated display dimensions: 488x274 mm  Aspect: 1.78102
2010-12-30 21:03:33.486 VideoOutputXv: Estimated window dimensions: 488x274 mm  Aspect: 1.78102
2010-12-30 21:03:33.486 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: opengl,xv-blit,xshm,xlib
2010-12-30 21:03:33.488 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:03:33.489 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:03:33.489 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:03:33.489 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:03:33.489 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 21:03:33.489 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2010-12-30 21:03:33.489 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2010-12-30 21:03:33.490 VideoOutputXv: Has XVideo flags...
2010-12-30 21:03:33.490 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2010-12-30 21:03:33.490 VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
2010-12-30 21:03:33.490 VideoOutputXv: Has XVideo flags...
2010-12-30 21:03:33.490 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2010-12-30 21:03:33.490 VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2010-12-30 21:03:33.490 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2010-12-30 21:03:33.490 VideoOutputXv: Has XVideo flags...
2010-12-30 21:03:33.490 VideoOutputXv: Here...
2010-12-30 21:03:33.490 VideoOutputXv: Grabbed xv port 355
2010-12-30 21:03:33.491 VideoOutputXv: XVideo surface found on port 355
2010-12-30 21:03:33.491 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2010-12-30 21:03:33.491 VideoOutputXv: XVideo Format #0 is 'YUY2'
2010-12-30 21:03:33.491 VideoOutputXv: XVideo Format #1 is 'YV12'
2010-12-30 21:03:33.491 VideoOutputXv: XVideo Format #2 is 'UYVY'
2010-12-30 21:03:33.491 VideoOutputXv: XVideo Format #3 is 'I420'
2010-12-30 21:03:33.491 VideoOutputXv: Using XVideo Format 'YV12'
2010-12-30 21:03:33.491 VideoOutputXv: CreateShmImages(32): video_dim: 1280x720
2010-12-30 21:03:33.603 VDP: SetVideoRenderer(xv-blit)
2010-12-30 21:03:33.603 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2010-12-30 21:03:33.604 VideoOutputXv: Chromakeying not possible with this XVideo port.
2010-12-30 21:03:33.604 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:03:33.604 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 21:03:33.606 Over/underscan. V: 0, H: 0
2010-12-30 21:03:33.606 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:03:33.606 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 21:03:33.606 VDP: LoadBestPreferences(1280x720, 59.9401)
2010-12-30 21:03:33.607 NVP: LoadFilters(''..) -> 0
2010-12-30 21:03:33.609 OSD Theme Dimensions W: 640 H: 480
2010-12-30 21:03:34.294 NVP: ClearAfterSeek(1)
2010-12-30 21:03:34.294 TV: StartPlayer(): took 1136 ms to start player.
2010-12-30 21:03:34.294 VideoOutputXv: ClearAfterSeek()
2010-12-30 21:03:34.295 VideoOutputXv: DiscardFrames(0)
2010-12-30 21:03:34.295 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:03:34.295 TV: Changing from None to WatchingPreRecorded
2010-12-30 21:03:34.295 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:03:34.296 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:34.298 The realtime priority setting is not enabled.
2010-12-30 21:03:34.298 VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2010-12-30 21:03:34.304 Using deinterlace method bobdeint
2010-12-30 21:03:34.328 AFD: DoFastForward(4328 (1), do discard frames)
2010-12-30 21:03:34.328 Dec: DoFastForward(4328 (1), do discard frames)
2010-12-30 21:03:34.329 AFD: SeekReset(4332, 0, do flush, do discard)
2010-12-30 21:03:34.329 AFD: SeekReset() flushing
2010-12-30 21:03:34.329 VideoOutputXv: DiscardFrames(1)
2010-12-30 21:03:34.329 VideoBuffers::DiscardFrames(1): UAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:03:34.329 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:34.329 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:03:34.329 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:34.329 NVP: ClearAfterSeek(0)
2010-12-30 21:03:34.406 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2010-12-30 21:03:34.406 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2010-12-30 21:03:34.406 RTCVideoSync: Could not set RTC frequency, Permission denied.
2010-12-30 21:03:34.406 Set video sync frame interval to 16683
2010-12-30 21:03:34.406 Video sync method can't support double framerate (refresh rate too low for bob deint)
2010-12-30 21:03:34.406 Set video sync frame interval to 16683
2010-12-30 21:03:34.406 VDP: GetFilteredDeint(greedyhdeint) : xv-blit -> 'greedyhdeint'
greedyhdeint: size changed from 0 x 0 -> 1280 x 720
2010-12-30 21:03:34.420 Using deinterlace method greedyhdeint
2010-12-30 21:03:34.420 Using audio as timebase
2010-12-30 21:03:34.420 Video timing method: USleep with busy wait
2010-12-30 21:03:34.420 Refresh rate: 16666, frame interval: 16683
2010-12-30 21:03:34.421 NVP: Waiting for prebuffer.. 0 AAuLULAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:03:34.487 NVP: Waiting for prebuffer.. 1 AAUUUUUUUuUULULAAAAAAAAAAAAAAAA
2010-12-30 21:03:34.492 NVP: progressive frame seen after 2 interlaced  frames
2010-12-30 21:03:34.528 NVP: Video is 7.07307 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.529 Disabled deinterlacing
2010-12-30 21:03:34.562 NVP: Video is 12.3929 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.595 NVP: Video is 16.1279 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.628 NVP: Video is 18.6745 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.662 NVP: Video is 20.3446 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.695 NVP: Video is 21.3424 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.728 NVP: Video is 21.8511 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.762 NVP: Video is 21.9628 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.795 NVP: Video is 21.8069 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.828 NVP: Video is 21.4651 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.862 NVP: Video is 20.9391 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.895 NVP: Video is 20.3048 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.928 NVP: Video is 19.5743 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.962 NVP: Video is 18.7717 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:34.995 NVP: Video is 17.93 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.028 NVP: Video is 17.0439 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.062 NVP: Video is 16.1247 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.095 NVP: Video is 15.1805 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.129 NVP: Video is 14.2326 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.162 NVP: Video is 13.2669 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.195 NVP: Video is 12.3028 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.229 NVP: Video is 11.3251 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.262 NVP: Video is 10.337 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.295 NVP: Video is 9.34119 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.329 NVP: Video is 8.35455 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.362 NVP: Video is 7.37481 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.395 NVP: Video is 6.37026 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.429 NVP: Video is 5.55691 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.462 NVP: Video is 4.57226 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:35.495 NVP: Video is 3.56405 frames ahead of audio,
         doubling video frame interval to slow down.
'video_output' mean = '22504.28', std. dev. = '7990.74', fps = '44.44'
2010-12-30 21:03:37.397 NVP: Video is 3.42211 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.430 NVP: Video is 3.73542 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.463 NVP: Video is 3.71564 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.497 NVP: Video is 3.92561 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.530 NVP: Video is 3.67848 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.563 NVP: Video is 3.23839 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.797 NVP: Video is 3.09141 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.830 NVP: Video is 3.57729 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.864 NVP: Video is 4.21147 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.897 NVP: Video is 4.91189 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.931 NVP: Video is 5.70689 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.964 NVP: Video is 5.89858 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:37.997 NVP: Video is 5.77258 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.031 NVP: Video is 5.49829 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.064 NVP: Video is 4.6482 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.097 NVP: Video is 4.19043 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.131 NVP: Video is 3.77216 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.164 NVP: Video is 3.32362 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.231 NVP: Video is 3.00468 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.698 NVP: Video is 3.04783 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.731 NVP: Video is 3.49967 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.764 NVP: Video is 3.70365 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.798 NVP: Video is 3.61692 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:38.831 NVP: Video is 3.28208 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.148 NVP: Video is 3.23647 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.181 NVP: Video is 3.38638 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.215 NVP: Video is 3.25907 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.248 NVP: Video is 3.02871 frames ahead of audio,
         doubling video frame interval to slow down.
'video_output' mean = '26016.44', std. dev. = '8305.00', fps = '38.44'
2010-12-30 21:03:39.382 NVP: Video is 3.29743 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.415 NVP: Video is 3.94162 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.448 NVP: Video is 4.31985 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.482 NVP: Video is 4.87328 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.515 NVP: Video is 5.52808 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.548 NVP: Video is 5.64455 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.582 NVP: Video is 5.97171 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.615 NVP: Video is 6.48678 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.648 NVP: Video is 6.19877 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.682 NVP: Video is 5.86285 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.715 NVP: Video is 5.23629 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.749 NVP: Video is 5.00611 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.782 NVP: Video is 5.08823 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.815 NVP: Video is 5.4046 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.849 NVP: Video is 4.95253 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.882 NVP: Video is 4.82329 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.915 NVP: Video is 4.9961 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.949 NVP: Video is 5.03578 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:39.982 NVP: Video is 5.32033 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.016 NVP: Video is 5.77348 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.049 NVP: Video is 5.99347 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.082 NVP: Video is 6.14344 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.116 NVP: Video is 6.52568 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.149 NVP: Video is 5.7784 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.182 NVP: Video is 5.45765 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.216 NVP: Video is 5.45687 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.249 NVP: Video is 5.09663 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.282 NVP: Video is 5.06624 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.316 NVP: Video is 5.29821 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.349 NVP: Video is 5.39723 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.383 NVP: Video is 5.72625 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.416 NVP: Video is 5.56842 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.449 NVP: Video is 5.71977 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.483 NVP: Video is 6.07307 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.516 NVP: Video is 6.26308 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.549 NVP: Video is 6.64539 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.583 NVP: Video is 7.20182 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.616 NVP: Video is 7.84397 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.649 NVP: Video is 6.99185 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.683 NVP: Video is 6.62249 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.716 NVP: Video is 6.57028 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.750 NVP: Video is 6.4712 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.783 NVP: Video is 6.63664 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.816 NVP: Video is 6.3711 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.850 NVP: Video is 6.12696 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.883 NVP: Video is 6.16867 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.916 NVP: Video is 6.4697 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.950 NVP: Video is 5.96116 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:40.983 NVP: Video is 5.84949 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.017 NVP: Video is 6.00551 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.050 NVP: Video is 5.74789 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.083 NVP: Video is 5.79446 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.117 NVP: Video is 5.75442 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.150 NVP: Video is 5.31979 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.183 NVP: Video is 4.75406 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.217 NVP: Video is 4.07505 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:41.250 NVP: Video is 3.32602 frames ahead of audio,
         doubling video frame interval to slow down.
'video_output' mean = '27522.63', std. dev. = '7990.42', fps = '36.33'
2010-12-30 21:03:42.601 NVP: Video is 3.17701 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.635 NVP: Video is 3.4317 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.668 NVP: Video is 3.62273 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.701 NVP: Video is 4.03573 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.735 NVP: Video is 4.25559 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.768 NVP: Video is 4.67524 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.801 NVP: Video is 4.82509 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.835 NVP: Video is 4.96745 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.868 NVP: Video is 4.96937 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.902 NVP: Video is 5.25553 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.935 NVP: Video is 5.33525 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:42.968 NVP: Video is 5.14032 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.002 NVP: Video is 5.17395 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.035 NVP: Video is 4.80951 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.068 NVP: Video is 4.79093 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.102 NVP: Video is 5.01678 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.135 NVP: Video is 5.12624 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.168 NVP: Video is 5.43308 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.202 NVP: Video is 5.93299 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.235 NVP: Video is 5.93329 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.268 NVP: Video is 5.84355 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.302 NVP: Video is 6.01606 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.335 NVP: Video is 6.01055 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.369 NVP: Video is 5.75166 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.402 NVP: Video is 5.30276 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.435 NVP: Video is 4.74129 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.469 NVP: Video is 4.54499 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.502 NVP: Video is 4.62255 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.535 NVP: Video is 4.30606 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.569 NVP: Video is 4.33843 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.602 NVP: Video is 4.24282 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.635 NVP: Video is 4.05125 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.669 NVP: Video is 3.65282 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:43.702 NVP: Video is 3.09926 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:44.396 NVP: 400 progressive frames seen.
'video_output' mean = '23110.33', std. dev. = '8263.71', fps = '43.27'
2010-12-30 21:03:45.346 NVP: Video is 3.02973 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.362 NVP: Video is 3.18636 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.379 NVP: Video is 3.33381 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.404 NVP: Video is 3.29455 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.437 NVP: Video is 3.13025 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.471 NVP: Video is 3.08194 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.537 NVP: Video is 3.13846 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.571 NVP: Video is 3.52269 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.604 NVP: Video is 3.67596 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.638 NVP: Video is 3.79092 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.671 NVP: Video is 4.13193 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.704 NVP: Video is 4.29773 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.738 NVP: Video is 4.06246 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.771 NVP: Video is 4.11077 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.804 NVP: Video is 4.08709 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.838 NVP: Video is 4.30906 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.871 NVP: Video is 4.35569 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.904 NVP: Video is 4.13589 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.938 NVP: Video is 3.73128 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:45.971 NVP: Video is 3.17305 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:46.255 NVP: Video is 3.40658 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:46.288 NVP: Video is 3.96356 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:46.321 NVP: Video is 3.99167 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:46.355 NVP: Video is 3.75796 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:46.388 NVP: Video is 3.34292 frames ahead of audio,
         doubling video frame interval to slow down.
'video_output' mean = '21758.31', std. dev. = '7664.48', fps = '45.96'
'video_output' mean = '18845.37', std. dev. = '5627.16', fps = '53.06'
2010-12-30 21:03:48.940 NVP: Video is 3.02955 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:48.974 NVP: Video is 3.62081 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:49.007 NVP: Video is 4.31901 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:49.040 NVP: Video is 3.1643 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:03:49.055 TV: Attempting to change from WatchingPreRecorded to None
2010-12-30 21:03:49.055 TV: StopStuff() -- begin
2010-12-30 21:03:49.055 TV: StopStuff(): stopping ring buffer[s]
2010-12-30 21:03:49.080 TV: StopStuff(): stopping player[s] (1/2)
2010-12-30 21:03:49.080 TV: StopStuff(): stopping player[s] (2/2)
2010-12-30 21:03:49.102 NVP: Exited decoder loop.
2010-12-30 21:03:49.107 VideoOutputXv: dtor
2010-12-30 21:03:49.107 VideoOutputXv: DiscardFrames(1)
2010-12-30 21:03:49.107 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUUUUUUUUUUUUUUUuAUL
2010-12-30 21:03:49.108 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:49.108 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:03:49.108 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:49.108 VideoOutputXv: DiscardFrames(1)
2010-12-30 21:03:49.108 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:03:49.108 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:49.108 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:03:49.109 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:03:49.113 VideoOutputXv: Closing XVideo port 355
2010-12-30 21:03:49.227 TV: StopStuff() -- end
2010-12-30 21:03:49.228 TV: Changing from WatchingPreRecorded to None
2010-12-30 21:03:49.504 Starting preview generator 1 && (0 || ((2010-12-30T20:56:59<2010-12-30T21:03:49)->1 && (2010-12-30T20:56:59>=2010-12-30T20:00:00)->1)) && 1 && 1 && 1
2010-12-30 21:03:49.727 Using runtime prefix = /usr
2010-12-30 21:03:49.729 Empty LocalHostName.
2010-12-30 21:03:49.729 Using localhost value of mythtv
2010-12-30 21:03:49.751 New DB connection, total: 1
2010-12-30 21:03:49.761 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:03:49.762 Closing DB connection named 'DBManager0'
2010-12-30 21:03:49.763 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:03:49.765 New DB connection, total: 2
2010-12-30 21:03:49.765 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:03:49.768 Current Schema Version: 1214
2010-12-30 21:03:49.965 AFD: Opened codec 0x82d0900, id(MPEG2VIDEO) type(Video)
2010-12-30 21:03:49.965 AFD: codec AC3 has 6 channels
2010-12-30 21:03:49.967 AFD: Opened codec 0x82d0ea0, id(AC3) type(Audio)
2010-12-30 21:03:49.967 AFD: codec AC3 has 2 channels
2010-12-30 21:03:49.968 AFD: Opened codec 0x82d1450, id(AC3) type(Audio)
2010-12-30 21:03:50.249 Preview: Grabbed preview '/myth/tv/1901_20101230193000.mpg' 1280x720@70s
2010-12-30 21:03:50.471 Preview: Preview process returned 0.
2010-12-30 21:03:50.471 Preview: Preview process ran ok.
2010-12-30 21:03:50.477 Preview: previewThreadDone took 5ms
eric@mythtv:~$

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Top
 Profile  
 
PostPosted: Thu Dec 30, 2010 9:33 pm 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
For comparison, here's a playback log from 10 seconds of playback from KARE 11.1 Minneapolis, NBC 1080i. This is the tail end of The Tonight Show from 12-29-2010.
Code:
2010-12-30 21:27:15.092 Using runtime prefix = /usr
2010-12-30 21:27:15.685 DPMS is disabled.
2010-12-30 21:27:15.685 Empty LocalHostName.
2010-12-30 21:27:15.686 Using localhost value of mythtv
2010-12-30 21:27:15.708 New DB connection, total: 1
2010-12-30 21:27:15.718 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:27:15.721 Closing DB connection named 'DBManager0'
2010-12-30 21:27:15.722 Primary screen 0.
2010-12-30 21:27:15.723 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:27:15.725 Using screen 0, 1920x1080 at 0,0
2010-12-30 21:27:15.743 user: 100 effective user: 100 before privileged thread
2010-12-30 21:27:15.743 user: 100 effective user: 100 run_priv_thread
2010-12-30 21:27:15.743 user: 100 effective user: 100 after privileged thread
2010-12-30 21:27:15.745 New DB connection, total: 2
2010-12-30 21:27:15.746 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:27:15.748 mythfrontend version: 0.21.20080304-1 www.mythtv.org
2010-12-30 21:27:15.748 Enabled verbose msgs:  important general playback
2010-12-30 21:27:15.865 Unable to parse themeinfo.xml for glass-wide
2010-12-30 21:27:15.866 The theme (glass-wide) is missing a themeinfo.xml file
2010-12-30 21:27:16.097 Unable to parse themeinfo.xml for glass-wide
2010-12-30 21:27:16.098 The theme (glass-wide) is missing a themeinfo.xml file
2010-12-30 21:27:16.321 max_width: 1920 max_height: 1080
2010-12-30 21:27:16.429 No theme dir: /home/mythtv/.mythtv/themes/neon-wide
2010-12-30 21:27:16.430 Primary screen 0.
2010-12-30 21:27:16.431 Using screen 0, 1920x1080 at 0,0
2010-12-30 21:27:16.431 No theme dir: /home/mythtv/.mythtv/themes/neon-wide
2010-12-30 21:27:16.432 Switching to wide mode (neon-wide)
2010-12-30 21:27:16.452 Using the Qt painter
2010-12-30 21:27:16.454 JoystickMenuClient Error: Joystick disabled - Failed to open device /dev/input/js0
2010-12-30 21:27:16.455 lirc init success using configuration file: /home/mythtv/.mythtv/lircrc
2010-12-30 21:27:17.901 Loading from: /usr/share/mythtv/themes/neon-wide/base.xml
2010-12-30 21:27:17.975 Loading from: /usr/share/mythtv/themes/default/base.xml
2010-12-30 21:27:17.999 Registering Internal as a media playback plugin.
2010-12-30 21:27:18.068 MonitorRegisterExtensions(0x100, gif,jpg,png)
2010-12-30 21:27:18.206 MythMusic adding CD-Writer: ATA:1,0,0 -- DVD-RW  DVR-111D
2010-12-30 21:27:19.713 Failed to run 'cdrecord --scanbus -dev=ATAPI'
2010-12-30 21:27:19.748 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
SIP listening on IP Address 192.168.7.10:5060 NAT address 192.168.7.10
SIP: Cannot register; proxy, username or password not set
2010-12-30 21:27:19.889 No theme dir: /home/mythtv/.mythtv/themes/neon-wide
2010-12-30 21:27:24.719 XMLParse::LoadTheme using /usr/share/mythtv/themes/neon-wide/ui.xml
2010-12-30 21:27:25.124 Connecting to backend server: 127.0.0.1:6543 (try 1 of 5)
2010-12-30 21:27:25.126 Using protocol version 40
2010-12-30 21:27:39.150 TV: Attempting to change from None to WatchingPreRecorded
2010-12-30 21:27:39.152 RingBuf(/myth/tv/2101_20101229233600.mpg): OpenFile(/myth/tv/2101_20101229233600.mpg, 12)
2010-12-30 21:27:39.160 RingBuf(/myth/tv/2101_20101229233600.mpg): CalcReadAheadThresh(0 KB)
          -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-12-30 21:27:40.113 AFD: Stream #0, has id 0x49 codec id MPEG2VIDEO, type Video, bitrate 13345600 at 0x0x8432510
2010-12-30 21:27:40.116 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:27:40.116 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:27:40.116 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:27:40.117 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:27:40.117 VDP: LoadBestPreferences(1920x1080, 60)
2010-12-30 21:27:40.117 Using 1 CPUs for decoding
2010-12-30 21:27:40.119 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:27:40.119 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:27:40.119 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:27:40.120 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:27:40.120 VDP: LoadBestPreferences(1920x1080, 60)
2010-12-30 21:27:40.120 AFD: InitVideoCodec() 0x84bcc60 id(MPEG2VIDEO) type (Video).
2010-12-30 21:27:40.120 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
2010-12-30 21:27:40.120 AFD: EIA-708 caption service #1 is in the English language.
2010-12-30 21:27:40.120 AFD: Using ffmpeg for video decoding
2010-12-30 21:27:40.121 AFD: Looking for decoder for MPEG2VIDEO
2010-12-30 21:27:40.121 AFD: Opened codec 0x84bcc60, id(MPEG2VIDEO) type(Video)
2010-12-30 21:27:40.121 AFD: Stream #1, has id 0x52 codec id AC3, type Audio, bitrate 384000 at 0x0x841de60
2010-12-30 21:27:40.121 AFD: codec AC3 has 6 channels
2010-12-30 21:27:40.121 AFD: Looking for decoder for AC3
2010-12-30 21:27:40.122 AFD: Opened codec 0x8417690, id(AC3) type(Audio)
2010-12-30 21:27:40.122 RingBuf(/myth/tv/2101_20101229233600.mpg): CalcReadAheadThresh(3080417962 KB)
          -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-12-30 21:27:40.128 Opening audio device '/dev/dsp'. ch 2(2) sr 48000
2010-12-30 21:27:40.129 Opening OSS audio device '/dev/dsp'.
2010-12-30 21:27:40.134 Dec: Trying to select track (w/lang)
2010-12-30 21:27:40.134 Dec: Selecting first track
2010-12-30 21:27:40.134 Dec: Selected track #1 in the Unknown language(0)
2010-12-30 21:27:40.134 Dec: Selected track #1 in the English language(6647399)
2010-12-30 21:27:40.134 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2010-12-30 21:27:41.007 Position map filled from DB to: 116940
2010-12-30 21:27:41.009 SyncPositionMap prerecorded, from DB: 7797 entries
2010-12-30 21:27:41.010 SyncPositionMap, new totframes: 116940, new length: 3901, posMap size: 7797
2010-12-30 21:27:41.010 AFD: Position map found
2010-12-30 21:27:41.010 AFD: Successfully opened decoder for file: "/myth/tv/2101_20101229233600.mpg". novideo(0)
2010-12-30 21:27:41.033 VideoOutput: Allowed renderers: directfb,opengl,xv-blit,xshm,xlib
2010-12-30 21:27:41.033 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit,opengl
2010-12-30 21:27:41.035 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:27:41.035 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:27:41.035 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:27:41.036 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:27:41.036 VDP: LoadBestPreferences(1920x1080, 60)
2010-12-30 21:27:41.036 VideoOutput: Preferred renderer: xv-blit
2010-12-30 21:27:41.036 VideoOutput: Trying video renderer: xv-blit
2010-12-30 21:27:41.038 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:27:41.038 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:27:41.038 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:27:41.038 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:27:41.053 VideoOutputXv: ctor
2010-12-30 21:27:41.055 XOff: 0, YOff: 0
2010-12-30 21:27:41.055 VDP: LoadBestPreferences(1920x1088, 60)
2010-12-30 21:27:41.055 Snapping width to avoid scaling: width: 1920, left: 0
2010-12-30 21:27:41.055 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
2010-12-30 21:27:41.055 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:27:41.056 VideoOutputXv: Pixel dimensions: Screen 1920x1080, window 1920x1080
2010-12-30 21:27:41.057 VideoOutputXv: Estimated display dimensions: 488x274 mm  Aspect: 1.78102
2010-12-30 21:27:41.058 VideoOutputXv: Estimated window dimensions: 488x274 mm  Aspect: 1.78102
2010-12-30 21:27:41.058 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: opengl,xv-blit,xshm,xlib
2010-12-30 21:27:41.060 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,greedyhdeint) filt()
2010-12-30 21:27:41.060 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-12-30 21:27:41.060 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 21:27:41.060 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 21:27:41.060 VDP: LoadBestPreferences(1920x1088, 60)
2010-12-30 21:27:41.061 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2010-12-30 21:27:41.061 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2010-12-30 21:27:41.061 VideoOutputXv: Has XVideo flags...
2010-12-30 21:27:41.061 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2010-12-30 21:27:41.061 VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
2010-12-30 21:27:41.061 VideoOutputXv: Has XVideo flags...
2010-12-30 21:27:41.061 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2010-12-30 21:27:41.062 VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2010-12-30 21:27:41.062 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2010-12-30 21:27:41.062 VideoOutputXv: Has XVideo flags...
2010-12-30 21:27:41.062 VideoOutputXv: Here...
2010-12-30 21:27:41.062 VideoOutputXv: Grabbed xv port 355
2010-12-30 21:27:41.062 VideoOutputXv: XVideo surface found on port 355
2010-12-30 21:27:41.062 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2010-12-30 21:27:41.062 VideoOutputXv: XVideo Format #0 is 'YUY2'
2010-12-30 21:27:41.063 VideoOutputXv: XVideo Format #1 is 'YV12'
2010-12-30 21:27:41.063 VideoOutputXv: XVideo Format #2 is 'UYVY'
2010-12-30 21:27:41.063 VideoOutputXv: XVideo Format #3 is 'I420'
2010-12-30 21:27:41.063 VideoOutputXv: Using XVideo Format 'YV12'
2010-12-30 21:27:41.063 VideoOutputXv: CreateShmImages(32): video_dim: 1920x1088
2010-12-30 21:27:41.328 VDP: SetVideoRenderer(xv-blit)
2010-12-30 21:27:41.328 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2010-12-30 21:27:41.328 VideoOutputXv: Chromakeying not possible with this XVideo port.
2010-12-30 21:27:41.328 Snapping height to avoid scaling: height: 1080, top: 0
2010-12-30 21:27:41.328 Snapping width to avoid scaling: width: 1920, left: 0
2010-12-30 21:27:41.328 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:27:41.328 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:27:41.330 Over/underscan. V: 0, H: 0
2010-12-30 21:27:41.331 Snapping height to avoid scaling: height: 1080, top: 0
2010-12-30 21:27:41.331 Snapping width to avoid scaling: width: 1920, left: 0
2010-12-30 21:27:41.331 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:27:41.331 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 21:27:41.331 VDP: LoadBestPreferences(1920x1088, 29.97)
2010-12-30 21:27:41.332 NVP: LoadFilters(''..) -> 0
2010-12-30 21:27:41.334 OSD Theme Dimensions W: 640 H: 480
2010-12-30 21:27:42.237 NVP: ClearAfterSeek(1)
2010-12-30 21:27:42.237 VideoOutputXv: ClearAfterSeek()
2010-12-30 21:27:42.237 VideoOutputXv: DiscardFrames(0)
2010-12-30 21:27:42.237 TV: StartPlayer(): took 3041 ms to start player.
2010-12-30 21:27:42.237 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:27:42.237 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:27:42.238 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:27:42.238 TV: Changing from None to WatchingPreRecorded
2010-12-30 21:27:42.240 New DB connection, total: 3
2010-12-30 21:27:42.241 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:27:42.243 New DB connection, total: 4
2010-12-30 21:27:42.244 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:27:42.245 The realtime priority setting is not enabled.
2010-12-30 21:27:42.246 VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2010-12-30 21:27:42.251 Using deinterlace method bobdeint
2010-12-30 21:27:42.353 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2010-12-30 21:27:42.353 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2010-12-30 21:27:42.353 RTCVideoSync: Could not set RTC frequency, Permission denied.
2010-12-30 21:27:42.353 Set video sync frame interval to 33366
2010-12-30 21:27:42.354 Using audio as timebase
2010-12-30 21:27:42.354 Video timing method: USleep with busy wait
2010-12-30 21:27:42.354 Refresh rate: 16666, frame interval: 33366
2010-12-30 21:27:42.354 NVP: Waiting for prebuffer.. 0 uLULAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:27:42.487 NVP: Waiting for prebuffer.. 1 UUUUuUULLAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:27:42.789 NVP: Video is 3.20191 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:27:42.856 NVP: Video is 3.43541 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:27:42.923 NVP: Video is 3.35578 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 21:27:42.989 NVP: Video is 3.05628 frames ahead of audio,
         doubling video frame interval to slow down.
'video_output' mean = '35054.04', std. dev. = '6929.35', fps = '28.53'
'video_output' mean = '33360.90', std. dev. = '68.81', fps = '29.98'
'video_output' mean = '33362.94', std. dev. = '54.81', fps = '29.97'
2010-12-30 21:27:56.022 NVP: 400 interlaced frames seen.
'video_output' mean = '33413.87', std. dev. = '636.75', fps = '29.93'
2010-12-30 21:27:59.485 TV: Attempting to change from WatchingPreRecorded to None
2010-12-30 21:27:59.485 TV: StopStuff() -- begin
2010-12-30 21:27:59.485 TV: StopStuff(): stopping ring buffer[s]
2010-12-30 21:27:59.486 TV: StopStuff(): stopping player[s] (1/2)
2010-12-30 21:27:59.486 TV: StopStuff(): stopping player[s] (2/2)
'video_output' mean = '33345.36', std. dev. = '52.99', fps = '29.99'
2010-12-30 21:27:59.509 NVP: Exited decoder loop.
2010-12-30 21:27:59.524 VideoOutputXv: dtor
2010-12-30 21:27:59.524 VideoOutputXv: DiscardFrames(1)
2010-12-30 21:27:59.524 VideoBuffers::DiscardFrames(1): uLUUAUUUUUUUUUUUUUUUUuUUUUUUUUU
2010-12-30 21:27:59.524 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:27:59.524 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:27:59.524 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:27:59.524 VideoOutputXv: DiscardFrames(1)
2010-12-30 21:27:59.524 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 21:27:59.524 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:27:59.524 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 21:27:59.524 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 21:27:59.529 VideoOutputXv: Closing XVideo port 355
2010-12-30 21:27:59.932 TV: StopStuff() -- end
2010-12-30 21:27:59.932 TV: Changing from WatchingPreRecorded to None
2010-12-30 21:28:00.243 Starting preview generator 1 && (0 || ((2010-12-30T01:48:35<2010-12-30T21:27:59)->1 && (2010-12-30T01:48:35>=2010-12-30T00:41:00)->1)) && 1 && 1 && 1
2010-12-30 21:28:00.475 Using runtime prefix = /usr
2010-12-30 21:28:00.476 Empty LocalHostName.
2010-12-30 21:28:00.476 Using localhost value of mythtv
2010-12-30 21:28:00.498 New DB connection, total: 1
2010-12-30 21:28:00.508 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:28:00.510 Closing DB connection named 'DBManager0'
2010-12-30 21:28:00.511 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:28:00.512 New DB connection, total: 2
2010-12-30 21:28:00.513 Connected to database 'mythconverg' at host: localhost
2010-12-30 21:28:00.515 Current Schema Version: 1214
2010-12-30 21:28:00.745 AFD: Opened codec 0x82d01e0, id(MPEG2VIDEO) type(Video)
2010-12-30 21:28:00.745 AFD: codec AC3 has 6 channels
2010-12-30 21:28:00.746 AFD: Opened codec 0x82d0780, id(AC3) type(Audio)
2010-12-30 21:28:01.285 Preview: Grabbed preview '/myth/tv/2101_20101229233600.mpg' 1920x1088@70s
2010-12-30 21:28:01.581 Preview: Preview process returned 0.
2010-12-30 21:28:01.581 Preview: Preview process ran ok.
2010-12-30 21:28:01.587 Preview: previewThreadDone took 5ms
Destroying SipFsm object
2010-12-30 21:28:06.164 Deleting UPnP client...
eric@mythtv:~$

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Top
 Profile  
 
 Post subject:
PostPosted: Thu Dec 30, 2010 10:58 pm 
Offline
Joined: Tue Aug 15, 2006 11:14 am
Posts: 1343
Location: Orlando FL
Very similar results when I played back a short clip of the Simpsons Christmas episode.
Sorry it's so long I wish we could upload txt files.
Code:
2010-12-30 23:35:28.394 mythfrontend version: branches/release-0-23-fixes [25661M] www.mythtv.org
2010-12-30 23:35:28.395 Using runtime prefix = /usr
2010-12-30 23:35:28.395 Using configuration directory = /home/mythtv/.mythtv
2010-12-30 23:35:29.125 Using localhost value of Pippin
2010-12-30 23:35:29.131 New DB connection, total: 1
2010-12-30 23:35:29.136 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:29.136 Closing DB connection named 'DBManager0'
2010-12-30 23:35:29.159 DPMS is disabled.
2010-12-30 23:35:29.161 Primary screen: 0.
2010-12-30 23:35:29.162 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:29.164 Using screen 0, 1920x1080 at 0,0
2010-12-30 23:35:29.206 MythXGetRefreshRate(): Doubling refresh rate for interlaced display.
2010-12-30 23:35:29.207 Desktop video mode: 1920x1080 59.9484 Hz
2010-12-30 23:35:29.226 max_width: 1920 max_height: 1080
2010-12-30 23:35:29.228 MythUI Image Cache size set to 20971520 bytes
2010-12-30 23:35:29.258 user: 1000 effective user: 1000 before privileged thread
2010-12-30 23:35:29.258 user: 1000 effective user: 1000 run_priv_thread
2010-12-30 23:35:29.258 user: 1000 effective user: 1000 after privileged thread
2010-12-30 23:35:29.259 Enabled verbose msgs:  important general playback
2010-12-30 23:35:29.264 Primary screen: 0.
2010-12-30 23:35:29.265 Using screen 0, 1920x1080 at 0,0
2010-12-30 23:35:29.266 Using theme base resolution of 1280x720
2010-12-30 23:35:29.273 LIRC: Successfully initialized '/dev/lircd' using '/home/mythtv/.mythtv/lircrc' config
2010-12-30 23:35:29.273 JoystickMenuThread Error: Joystick disabled - Failed to read /home/mythtv/.mythtv/joystickmenurc
2010-12-30 23:35:29.295 Using Frameless Window
2010-12-30 23:35:29.562 Using the Qt painter
2010-12-30 23:35:29.637 MythFontProperties, Error: Failed to load 'Bitstream Vera Sans', got 'DejaVu Sans' instead
         Location: /usr/share/mythtv/themes/ProjectGrayhem-wide/base.xml @ 8
         Name: 'small'   Type: 'font'
2010-12-30 23:35:29.666 MythFontProperties, Error: Failed to load 'Bitstream Vera Sans', got 'DejaVu Sans' instead
         Location: /usr/share/mythtv/themes/ProjectGrayhem-wide/base.xml @ 14
         Name: 'medium'   Type: 'font'
2010-12-30 23:35:29.686 MythFontProperties, Error: Failed to load 'Bitstream Vera Sans', got 'DejaVu Sans' instead
         Location: /usr/share/mythtv/themes/ProjectGrayhem-wide/base.xml @ 20
         Name: 'large'   Type: 'font'
2010-12-30 23:35:29.686 XMLParseBase: Loaded base theme from '/usr/share/mythtv/themes/ProjectGrayhem-wide/base.xml'
2010-12-30 23:35:29.881 XMLParseBase: Loaded base theme from '/usr/share/mythtv/themes/default-wide/base.xml'
2010-12-30 23:35:29.921 XMLParseBase: Loaded base theme from '/usr/share/mythtv/themes/default/base.xml'
2010-12-30 23:35:29.924 Current MythTV Schema Version (DBSchemaVer): 1254
2010-12-30 23:35:29.925 VDP: decoder<->render support: ffmpeg      null xlib xshm xv-blit opengl vdpau
2010-12-30 23:35:29.925 VDP: decoder<->render support: libmpeg2    null xlib xshm xv-blit opengl vdpau
2010-12-30 23:35:29.926 VDP: decoder<->render support: xvmc        xvmc-blit
2010-12-30 23:35:29.926 VDP: decoder<->render support: xvmc-vld    xvmc-blit
2010-12-30 23:35:29.926 VDP: decoder<->render support: vdpau       vdpau
2010-12-30 23:35:29.929 VDP: Ignoring profile item 56 (OSD Renderer opengl is not supported w/renderer xvmc-blit (supported: chromakey,ia44blend))
2010-12-30 23:35:29.930 VDP: Ignoring profile item 54 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:29.933 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:29.933 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:29.935 VDP: Ignoring profile item 70 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:29.935 VDP: Ignoring profile item 71 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:29.936 VDP: Ignoring profile item 74 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:29.936 VDP: Ignoring profile item 75 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:31.494 Registering Internal as a media playback plugin.
2010-12-30 23:35:31.513 Cannot load language en_us for module mytharchive
2010-12-30 23:35:31.515 Cannot load language en_us for module mythbrowser
2010-12-30 23:35:31.517 Registering WebBrowser as a media playback plugin.
2010-12-30 23:35:31.517 Cannot load language en_us for module mythbrowser
2010-12-30 23:35:31.518 MythPlugin::init() dlerror: libmythdb-0.22.so.0: cannot open shared object file: No such file or directory
2010-12-30 23:35:31.518 Unable to initialize plugin 'mythflix'.
2010-12-30 23:35:31.552 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
         eno: No such file or directory (2)
2010-12-30 23:35:31.561 MMUnix::AddDevice() Error: failed to stat /dev/power,
         eno: No such file or directory (2)
2010-12-30 23:35:31.580 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
         eno: No such file or directory (2)
2010-12-30 23:35:31.589 MMUnix::AddDevice() Error: failed to stat /dev/power,
         eno: No such file or directory (2)
2010-12-30 23:35:31.608 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
         eno: No such file or directory (2)
2010-12-30 23:35:31.617 MMUnix::AddDevice() Error: failed to stat /dev/power,
         eno: No such file or directory (2)
2010-12-30 23:35:31.636 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
         eno: No such file or directory (2)
2010-12-30 23:35:31.645 MMUnix::AddDevice() Error: failed to stat /dev/power,
         eno: No such file or directory (2)
2010-12-30 23:35:31.664 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
         eno: No such file or directory (2)
2010-12-30 23:35:31.673 MMUnix::AddDevice() Error: failed to stat /dev/power,
         eno: No such file or directory (2)
2010-12-30 23:35:31.682 MonitorRegisterExtensions(0x100, gif,jpg,png)
2010-12-30 23:35:31.682 Cannot load language en_us for module mythgallery
2010-12-30 23:35:31.698 Current MythMusic Schema Version (MusicDBSchemaVer): 1017
2010-12-30 23:35:31.731 MonitorRegisterExtensions(0x40, mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
2010-12-30 23:35:31.735 Cannot load language en_us for module mythmusic
2010-12-30 23:35:31.743 Cannot load language en_us for module mythnetvision
2010-12-30 23:35:31.755 Cannot load language en_us for module mythstream
2010-12-30 23:35:31.761 Current MythVideo Schema Version (mythvideo.DBSchemaVer): 1032
2010-12-30 23:35:31.780 Cannot load language en_us for module mythvideo
2010-12-30 23:35:31.789 Cannot load language en_us for module mythweather
2010-12-30 23:35:31.791 NetworkControl: Listening for remote connections on port 6546
2010-12-30 23:35:31.793 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/ProjectGrayhem-wide/menu-ui.xml
2010-12-30 23:35:31.831 Failed to mount /dev/sdc.
2010-12-30 23:35:31.850 MythFontProperties, Error: Failed to load 'Bitstream Vera Sans', got 'DejaVu Sans' instead
         Location: /usr/share/mythtv/themes/ProjectGrayhem-wide/menu-ui.xml @ 5
         Name: 'menufont'   Type: 'font'
2010-12-30 23:35:31.850 MythFontProperties, Error: Unknown tag in font 'clock'
         Location: /usr/share/mythtv/themes/ProjectGrayhem-wide/menu-ui.xml @ 14
         Name: ''   Type: 'size:small'
2010-12-30 23:35:31.852 XMLParseBase, Error: Unknown widget type.
         Location: /usr/share/mythtv/themes/ProjectGrayhem-wide/menu-ui.xml @ 20
         Name: ''   Type: 'color'
2010-12-30 23:35:31.896 Failed to mount /dev/sdd.
2010-12-30 23:35:31.941 Loading menu theme from /home/mythtv/.mythtv/mainmenu.xml
2010-12-30 23:35:31.953 Found mainmenu.xml for theme 'ProjectGrayhem-wide'
2010-12-30 23:35:31.954 Failed to mount /dev/sde.
2010-12-30 23:35:31.988 Failed to mount /dev/sdf.
2010-12-30 23:35:32.789 Found a handler - 'MythGallery Media Handler 1/2'
2010-12-30 23:35:32.793 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/ProjectGrayhem-wide/gallery-ui.xml
2010-12-30 23:35:32.793 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/default-wide/gallery-ui.xml
2010-12-30 23:35:32.973 Failed to mount /dev/sdc.
2010-12-30 23:35:33.016 Found a handler - 'MythGallery Media Handler 1/2'
2010-12-30 23:35:33.016 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/ProjectGrayhem-wide/gallery-ui.xml
2010-12-30 23:35:33.016 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/default-wide/gallery-ui.xml
2010-12-30 23:35:33.220 Failed to mount /dev/sdd.
2010-12-30 23:35:33.271 Found a handler - 'MythGallery Media Handler 1/2'
2010-12-30 23:35:33.272 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/ProjectGrayhem-wide/gallery-ui.xml
2010-12-30 23:35:33.272 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/default-wide/gallery-ui.xml
2010-12-30 23:35:33.468 Failed to mount /dev/sde.
2010-12-30 23:35:33.519 MythContext: Connecting to backend server: localhost:6543 (try 1 of 1)
2010-12-30 23:35:33.522 Using protocol version 23056
2010-12-30 23:35:33.804 Found a handler - 'MythGallery Media Handler 1/2'
2010-12-30 23:35:33.805 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/ProjectGrayhem-wide/gallery-ui.xml
2010-12-30 23:35:33.805 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/default-wide/gallery-ui.xml
2010-12-30 23:35:33.989 Failed to mount /dev/sdf.
2010-12-30 23:35:38.749 Loading menu theme from /usr/share/mythtv/themes/defaultmenu//library.xml
2010-12-30 23:35:39.710 New DB connection, total: 2
2010-12-30 23:35:39.710 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:39.711 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/ProjectGrayhem-wide/recordings-ui.xml
2010-12-30 23:35:39.711 XMLParseBase: Loading window theme from /usr/share/mythtv/themes/default-wide/recordings-ui.xml
2010-12-30 23:35:49.921 TV: StartTV() -- begin
2010-12-30 23:35:49.922 TV: ctor -- begin
2010-12-30 23:35:49.935 TV: ctor -- end
2010-12-30 23:35:49.935 TV: Init -- begin
2010-12-30 23:35:49.942 TV: Init -- end channel groups
2010-12-30 23:35:49.944 TV: DrawUnusedRects() -- begin
2010-12-30 23:35:49.945 TV: DrawUnusedRects() -- end
2010-12-30 23:35:49.945 TV: DrawUnusedRects() -- begin
2010-12-30 23:35:49.945 TV: DrawUnusedRects() -- end
2010-12-30 23:35:50.103 TV: Init -- end
2010-12-30 23:35:50.103 TV: tv->Playback() -- begin
2010-12-30 23:35:50.107 TV: tv->Playback() -- end
2010-12-30 23:35:50.107 TV: StartTV -- process events begin
2010-12-30 23:35:50.127 TV: HandleStateChange(0) -- begin
2010-12-30 23:35:50.127 TV: Attempting to change from None to WatchingPreRecorded
2010-12-30 23:35:50.130 RingBuf(/myth1/tv/3351_20101226200000.mpg): OpenFile(/myth1/tv/3351_20101226200000.mpg, 12)
2010-12-30 23:35:50.135 RingBuf(/myth1/tv/3351_20101226200000.mpg): CalcReadAheadThresh(0 KB)
          -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-12-30 23:35:50.197 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
2010-12-30 23:35:50.197 TV: Elapsed time since TV constructor was called: 275 ms
2010-12-30 23:35:50.289 AFD: Stream #0, has id 0x31 codec id MPEG2VIDEO, type Video, bitrate 38800000 at 0x9ab4b20
2010-12-30 23:35:50.293 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.293 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.294 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
2010-12-30 23:35:50.294 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt()
2010-12-30 23:35:50.294 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 23:35:50.294 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 23:35:50.294 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 23:35:50.297 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.297 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.297 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
2010-12-30 23:35:50.298 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt()
2010-12-30 23:35:50.298 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 23:35:50.298 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 23:35:50.298 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 23:35:50.301 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.301 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.302 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
2010-12-30 23:35:50.302 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt()
2010-12-30 23:35:50.302 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 23:35:50.302 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 23:35:50.302 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 23:35:50.302 Using 1 CPUs for decoding
2010-12-30 23:35:50.302 AFD: InitVideoCodec() 0x9ab4cf0 id(MPEG2VIDEO) type (Video).
2010-12-30 23:35:50.302 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2010-12-30 23:35:50.303 AFD: EIA-608 caption 1 is in the Undetermined language.
2010-12-30 23:35:50.303 AFD: EIA-708 caption service #1 is in the English language.
2010-12-30 23:35:50.303 AFD: Using ffmpeg for video decoding
2010-12-30 23:35:50.303 AFD: Looking for decoder for MPEG2VIDEO
2010-12-30 23:35:50.303 AFD: Opened codec 0x9ab4cf0, id(MPEG2VIDEO) type(Video)
2010-12-30 23:35:50.303 AFD: Stream #1, has id 0x34 codec id AC3, type Audio, bitrate 448000 at 0x9ab50d0
2010-12-30 23:35:50.303 AFD: codec AC3 has 6 channels
2010-12-30 23:35:50.303 AFD: Looking for decoder for AC3
2010-12-30 23:35:50.304 AFD: Opened codec 0x9eaa550, id(AC3) type(Audio)
2010-12-30 23:35:50.304 AFD: Stream #2, has id 0x35 codec id AC3, type Audio, bitrate 192000 at 0x9eaa900
2010-12-30 23:35:50.304 AFD: codec AC3 has 2 channels
2010-12-30 23:35:50.304 AFD: Looking for decoder for AC3
2010-12-30 23:35:50.305 AFD: Opened codec 0x9eaaad0, id(AC3) type(Audio)
2010-12-30 23:35:50.305 RingBuf(/myth1/tv/3351_20101226200000.mpg): CalcReadAheadThresh(0 KB)
          -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-12-30 23:35:50.325 Opening audio device 'default'. ch 2(6) sr 48000 (reenc 1)
2010-12-30 23:35:50.325 Opening ALSA audio device 'iec958:{ AES0 0x02 }'.
2010-12-30 23:35:50.330 Opening audio device 'default'. ch 2(6) sr 48000 (reenc 1)
2010-12-30 23:35:50.331 Opening ALSA audio device 'iec958:{ AES0 0x02 }'.
2010-12-30 23:35:50.332 Dec: Selected track #1 in the Undetermined language(7695972)
2010-12-30 23:35:50.332 Dec: Selected track #1 in the English language(6647399)
2010-12-30 23:35:50.332 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2010-12-30 23:35:50.387 Position map filled from DB to: 107542
2010-12-30 23:35:50.388 Dec: SyncPositionMap prerecorded, from DB: 7206 entries
2010-12-30 23:35:50.388 Dec: SyncPositionMap, new totframes: 107542, new length: 1794, posMap size: 7206
2010-12-30 23:35:50.388 AFD: Position map found
2010-12-30 23:35:50.388 AFD: Successfully opened decoder for file: "/myth1/tv/3351_20101226200000.mpg". novideo(0)
2010-12-30 23:35:50.393 Opening audio device 'default'. ch 2(6) sr 48000 (reenc 1)
2010-12-30 23:35:50.393 Opening ALSA audio device 'iec958:{ AES0 0x02 }'.
2010-12-30 23:35:50.396 VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,vdpau
2010-12-30 23:35:50.396 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,xv-blit,opengl,vdpau
2010-12-30 23:35:50.399 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.399 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.399 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
2010-12-30 23:35:50.399 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt()
2010-12-30 23:35:50.399 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 23:35:50.399 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 23:35:50.399 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 23:35:50.399 VideoOutput: Preferred renderer: xv-blit
2010-12-30 23:35:50.400 VideoOutput: Trying video renderer: 'xv-blit'
2010-12-30 23:35:50.407 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.407 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.407 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
2010-12-30 23:35:50.407 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt()
2010-12-30 23:35:50.408 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 23:35:50.408 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 23:35:50.411 VideoOutputXv: ctor
2010-12-30 23:35:50.411 VideoOutWindow::SetPIPState. pip_state: 0]
2010-12-30 23:35:50.411 VideoOutputXv: Creating gc
2010-12-30 23:35:50.412 VideoOutputXv: XJ_screen_num: '0'
2010-12-30 23:35:50.412 VideoOutputXv: XJ_curwin:     '14680070'
2010-12-30 23:35:50.412 VideoOutputXv: XJ_win:        '14680070'
2010-12-30 23:35:50.412 VideoOutputXv: XJ_root:       '422'
2010-12-30 23:35:50.412 VideoOutputXv: XJ_gc:         '0x9b7e9e8'
2010-12-30 23:35:50.412 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
2010-12-30 23:35:50.412 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 23:35:50.412 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 23:35:50.412 Display Rect  left: 0, top: 135, width: 1920, height: 810, aspect: 1.33333
2010-12-30 23:35:50.412 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 23:35:50.413 MythXGetRefreshRate(): Doubling refresh rate for interlaced display.
2010-12-30 23:35:50.414 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2010-12-30 23:35:50.414 VideoOutput: Actual display dimensions: 488x274 mm  Aspect: 1.78102
2010-12-30 23:35:50.414 VideoOutput: Estimated window dimensions: 488x274 mm  Aspect: 1.78102
2010-12-30 23:35:50.416 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2010-12-30 23:35:50.419 VDP: Ignoring profile item 66 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.419 VDP: Ignoring profile item 67 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-30 23:35:50.419 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
2010-12-30 23:35:50.419 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt()
2010-12-30 23:35:50.419 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-30 23:35:50.419 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-30 23:35:50.419 VDP: LoadBestPreferences(1280x720, 60)
2010-12-30 23:35:50.420 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2010-12-30 23:35:50.420 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2010-12-30 23:35:50.420 VideoOutputXv: Has XVideo flags...
2010-12-30 23:35:50.420 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2010-12-30 23:35:50.420 VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
2010-12-30 23:35:50.420 VideoOutputXv: Has XVideo flags...
2010-12-30 23:35:50.420 VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2010-12-30 23:35:50.420 VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2010-12-30 23:35:50.420 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2010-12-30 23:35:50.420 VideoOutputXv: Has XVideo flags...
2010-12-30 23:35:50.420 VideoOutputXv: Here...
2010-12-30 23:35:50.420 VideoOutputXv: Grabbed xv port 355
2010-12-30 23:35:50.420 VideoOutputXv: XVideo surface found on port 355
2010-12-30 23:35:50.420 VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2010-12-30 23:35:50.420 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2010-12-30 23:35:50.420 VideoOutputXv: XVideo Format #0 is 'YUY2'
2010-12-30 23:35:50.421 VideoOutputXv: XVideo Format #1 is 'YV12'
2010-12-30 23:35:50.421 VideoOutputXv: XVideo Format #2 is 'UYVY'
2010-12-30 23:35:50.421 VideoOutputXv: XVideo Format #3 is 'I420'
2010-12-30 23:35:50.421 VideoOutputXv: Using XVideo Format 'YV12'
2010-12-30 23:35:50.421 VideoOutputXv: CreateShmImages(32): video_dim: 1280x720
2010-12-30 23:35:50.537 VDP: SetVideoRenderer(xv-blit)
2010-12-30 23:35:50.537 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2010-12-30 23:35:50.537 VideoOutputXv: Chromakeying not possible with this XVideo port.
2010-12-30 23:35:50.537 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 23:35:50.537 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 23:35:50.539 Over/underscan. V: 0, H: 0
2010-12-30 23:35:50.539 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 23:35:50.539 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 23:35:50.539 VDP: LoadBestPreferences(1280x720, 59.9401)
2010-12-30 23:35:50.540 NVP(0): LoadFilters(''..) -> 0x0
2010-12-30 23:35:50.541 OSD Theme Dimensions W: 1280 H: 720
2010-12-30 23:35:50.660 playCtx: StartDecoderThread(): took 227 ms to start player.
2010-12-30 23:35:50.661 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
2010-12-30 23:35:50.661 TV: Changing from None to WatchingPreRecorded
2010-12-30 23:35:50.663 NVP(0): ClearAfterSeek(1)
2010-12-30 23:35:50.663 VideoOutputXv: ClearAfterSeek()
2010-12-30 23:35:50.663 VideoOutputXv: DiscardFrames(0)
2010-12-30 23:35:50.663 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 23:35:50.663 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 23:35:50.663 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:50.664 MythXGetRefreshRate(): Doubling refresh rate for interlaced display.
2010-12-30 23:35:50.664 VDP: GetFilteredDeint() : xv-blit -> 'yadifdoubleprocessdeint'
2010-12-30 23:35:50.665 FilterManager: GetFilterInfo(convert) returning: 0x0
2010-12-30 23:35:50.665 FilterManager: GetFilterInfo(yadifdoubleprocessdeint) returning: 0xa78a4f8
YadifDeint: size changed from 0 x 0 -> 1280 x 720
YadifDeint: Using existing thread.
2010-12-30 23:35:50.667 Using deinterlace method yadifdoubleprocessdeint
2010-12-30 23:35:50.668 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2010-12-30 23:35:50.668 RTCVideoSync: Could not set RTC frequency, Permission denied.
2010-12-30 23:35:50.668 Set video sync frame interval to 16683
2010-12-30 23:35:50.668 Video sync method can't support double framerate (refresh rate too low for bob deint)
2010-12-30 23:35:50.668 Set video sync frame interval to 16683
2010-12-30 23:35:50.668 VDP: GetFilteredDeint(yadifdeint) : xv-blit -> 'yadifdeint'
2010-12-30 23:35:50.670 FilterManager: GetFilterInfo(convert) returning: 0x0
2010-12-30 23:35:50.670 FilterManager: GetFilterInfo(yadifdeint) returning: 0xa789930
YadifDeint: size changed from 0 x 0 -> 1280 x 720
YadifDeint: Using existing thread.
2010-12-30 23:35:50.673 Using deinterlace method yadifdeint
2010-12-30 23:35:50.674 MythXGetRefreshRate(): Doubling refresh rate for interlaced display.
2010-12-30 23:35:50.675 Video timing method: USleep with busy wait
2010-12-30 23:35:50.675 Refresh rate: 16681, frame interval: 16683
2010-12-30 23:35:50.675 NVP(0): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 23:35:50.676 Realtime priority would require SUID as root.
2010-12-30 23:35:50.687 Opening audio device 'default'. ch 2(6) sr 48000 (reenc 0)
2010-12-30 23:35:50.687 Opening ALSA audio device 'iec958:{ AES0 0x02 }'.
2010-12-30 23:35:50.689 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-12-30 23:35:50.689 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2010-12-30 23:35:50.691 TV: HandleStateChange(0) -- end
2010-12-30 23:35:50.697 Detect Letterbox: YV12 frame format detected
2010-12-30 23:35:50.697 Detect Letterbox: The source is already in widescreen (aspect: 1.77778)
2010-12-30 23:35:50.698 AFD: DoFastForward(921 (1), do discard frames)
2010-12-30 23:35:50.698 Dec: DoFastForward(921 (1), do discard frames)
2010-12-30 23:35:50.698 Dec: FindPosition(921, search not adjusted) -->
         [68:912(28062384),69:927(28513396)]
2010-12-30 23:35:50.698 AFD: SeekReset(927, 0, do flush, do discard)
2010-12-30 23:35:50.698 AFD: SeekReset() flushing
2010-12-30 23:35:50.698 VideoOutputXv: DiscardFrames(1)
2010-12-30 23:35:50.698 VideoBuffers::DiscardFrames(1): UAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 23:35:50.698 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:50.698 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 23:35:50.698 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:50.698 NVP(0): ClearAfterSeek(0)
2010-12-30 23:35:50.716 PlaybackBoxHelper: Requesting preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:35:50.716 PlaybackBoxHelper: Requested preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:35:50.776 NVP(0): Waiting for prebuffer..  1 AAuLLAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 23:35:50.802 PlaybackBoxHelper: Requesting preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:35:50.802 PlaybackBoxHelper: Requested preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:35:50.838 WriteAudio: buffer underrun
2010-12-30 23:35:50.843 NVP(0): Waiting for prebuffer..  2 AAUUUULUULAAAAAAAAAAAAAAAAAAAAA
2010-12-30 23:35:50.884 NVP(0): progressive frame seen after 2 interlaced  frames
2010-12-30 23:35:50.946 NVP(0): Video is 5.61949 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:50.946 Set video sync frame interval to 16683
2010-12-30 23:35:50.946 Disabled deinterlacing
2010-12-30 23:35:50.958 NVP(0): Video is 9.48942 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:50.988 NVP(0): Video is 12.4518 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.022 NVP(0): Video is 14.4788 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.055 NVP(0): Video is 15.7443 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.088 NVP(0): Video is 16.4387 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.121 NVP(0): Video is 16.7197 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.155 NVP(0): Video is 16.6906 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.188 NVP(0): Video is 16.3991 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.222 NVP(0): Video is 15.9408 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.255 NVP(0): Video is 15.3423 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.288 NVP(0): Video is 14.6386 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.322 NVP(0): Video is 13.8711 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.355 NVP(0): Video is 13.0408 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.388 NVP(0): Video is 12.1782 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.422 NVP(0): Video is 11.2765 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.455 NVP(0): Video is 10.3455 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.488 NVP(0): Video is 9.40748 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.522 NVP(0): Video is 8.4492 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.537 mythbackend version: branches/release-0-23-fixes [25661M] www.mythtv.org
2010-12-30 23:35:51.537 Using runtime prefix = /usr
2010-12-30 23:35:51.537 Using configuration directory = /home/mythtv/.mythtv
2010-12-30 23:35:51.538 Using localhost value of Pippin
2010-12-30 23:35:51.555 NVP(0): Video is 7.47575 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.578 New DB connection, total: 1
2010-12-30 23:35:51.589 NVP(0): Video is 6.5059 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.600 mythbackend version: branches/release-0-23-fixes [25661M] www.mythtv.org
2010-12-30 23:35:51.601 Using runtime prefix = /usr
2010-12-30 23:35:51.601 Using configuration directory = /home/mythtv/.mythtv
2010-12-30 23:35:51.601 Using localhost value of Pippin
2010-12-30 23:35:51.603 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:51.603 Closing DB connection named 'DBManager0'
2010-12-30 23:35:51.607 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:51.611 Current MythTV Schema Version (DBSchemaVer): 1254
2010-12-30 23:35:51.612 ProgramInfo(): Updated pathname '':'' -> '3351_20101226200000.mpg'
2010-12-30 23:35:51.622 NVP(0): Video is 5.52377 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.637 New DB connection, total: 1
2010-12-30 23:35:51.648 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:51.648 Closing DB connection named 'DBManager0'
2010-12-30 23:35:51.655 NVP(0): Video is 4.5324 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.662 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:35:51.666 Current MythTV Schema Version (DBSchemaVer): 1254
2010-12-30 23:35:51.672 ProgramInfo(): Updated pathname '':'' -> '3351_20101226200000.mpg'
2010-12-30 23:35:51.689 NVP(0): Video is 3.53414 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:51.728 WriteAudio: buffer underrun
2010-12-30 23:35:51.763 WriteAudio: buffer underrun
2010-12-30 23:35:51.848 AFD: Opened codec 0x8dc2a30, id(MPEG2VIDEO) type(Video)
2010-12-30 23:35:51.849 AFD: codec AC3 has 6 channels
2010-12-30 23:35:51.863 AFD: Opened codec 0x8dd56a0, id(AC3) type(Audio)
2010-12-30 23:35:51.863 AFD: codec AC3 has 2 channels
2010-12-30 23:35:51.863 AFD: Opened codec 0x8dd5de0, id(AC3) type(Audio)
2010-12-30 23:35:51.926 WriteAudio: buffer underrun
2010-12-30 23:35:51.976 WriteAudio: buffer underrun
2010-12-30 23:35:52.028 WriteAudio: buffer underrun
2010-12-30 23:35:52.109 AFD: Opened codec 0x8bc1960, id(MPEG2VIDEO) type(Video)
2010-12-30 23:35:52.110 AFD: codec AC3 has 6 channels
2010-12-30 23:35:52.110 AFD: Opened codec 0x8bd4680, id(AC3) type(Audio)
2010-12-30 23:35:52.110 AFD: codec AC3 has 2 channels
2010-12-30 23:35:52.111 AFD: Opened codec 0x8bd4d80, id(AC3) type(Audio)
2010-12-30 23:35:52.225 WriteAudio: buffer underrun
2010-12-30 23:35:52.330 WriteAudio: buffer underrun
2010-12-30 23:35:52.546 WriteAudio: buffer underrun
2010-12-30 23:35:52.611 WriteAudio: buffer underrun
2010-12-30 23:35:52.712 WriteAudio: buffer underrun
2010-12-30 23:35:52.790 NVP(0): Video is 3.19895 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:52.823 NVP(0): Video is 3.1185 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:52.848 Preview: Grabbed preview '/myth1/tv/3351_20101226200000.mpg' 1280x720@64s
2010-12-30 23:35:52.929 WriteAudio: buffer underrun
2010-12-30 23:35:52.933 Preview: Grabbed preview '/myth1/tv/3351_20101226200000.mpg' 1280x720@64s
2010-12-30 23:35:52.978 WriteAudio: buffer underrun
2010-12-30 23:35:53.064 WriteAudio: buffer underrun
2010-12-30 23:35:53.102 ~MythContext waiting for threads to exit.
2010-12-30 23:35:53.140 ~MythContext waiting for threads to exit.
2010-12-30 23:35:53.157 Preview: Preview process returned 0.
2010-12-30 23:35:53.157 Preview: Preview process ran ok.
2010-12-30 23:35:53.158 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg' ready
2010-12-30 23:35:53.158 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg.png' done
2010-12-30 23:35:53.163 Preview: previewThreadDone took 5ms
2010-12-30 23:35:53.196 Preview: Preview process returned 0.
2010-12-30 23:35:53.196 Preview: Preview process ran ok.
2010-12-30 23:35:53.196 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg' ready
2010-12-30 23:35:53.196 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg.png' done
2010-12-30 23:35:53.201 Preview: previewThreadDone took 5ms
2010-12-30 23:35:53.213 WriteAudio: buffer underrun
2010-12-30 23:35:53.276 WriteAudio: buffer underrun
2010-12-30 23:35:53.358 WriteAudio: buffer underrun
'video_output' mean = '25008.90', std. dev. = '8644.22', fps = '39.99'
2010-12-30 23:35:53.424 NVP(0): Video is 3.03531 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:53.426 WriteAudio: buffer underrun
2010-12-30 23:35:53.457 NVP(0): Video is 3.47527 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:53.491 NVP(0): Video is 3.58047 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:53.524 NVP(0): Video is 3.4046 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:53.557 NVP(0): Video is 3.01798 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:53.626 WriteAudio: buffer underrun
2010-12-30 23:35:53.693 WriteAudio: buffer underrun
2010-12-30 23:35:53.743 WriteAudio: buffer underrun
2010-12-30 23:35:53.793 WriteAudio: buffer underrun
2010-12-30 23:35:54.060 WriteAudio: buffer underrun
2010-12-30 23:35:54.093 WriteAudio: buffer underrun
2010-12-30 23:35:54.226 WriteAudio: buffer underrun
2010-12-30 23:35:54.277 WriteAudio: buffer underrun
2010-12-30 23:35:54.327 WriteAudio: buffer underrun
2010-12-30 23:35:54.410 WriteAudio: buffer underrun
2010-12-30 23:35:54.627 WriteAudio: buffer underrun
2010-12-30 23:35:54.677 WriteAudio: buffer underrun
2010-12-30 23:35:54.760 WriteAudio: buffer underrun
2010-12-30 23:35:54.960 WriteAudio: buffer underrun
2010-12-30 23:35:55.010 WriteAudio: buffer underrun
2010-12-30 23:35:55.077 WriteAudio: buffer underrun
2010-12-30 23:35:55.277 WriteAudio: buffer underrun
2010-12-30 23:35:55.361 WriteAudio: buffer underrun
2010-12-30 23:35:55.394 WriteAudio: buffer underrun
2010-12-30 23:35:55.544 WriteAudio: buffer underrun
'video_output' mean = '21852.93', std. dev. = '7747.93', fps = '45.76'
2010-12-30 23:35:55.594 WriteAudio: buffer underrun
2010-12-30 23:35:55.678 WriteAudio: buffer underrun
2010-12-30 23:35:55.745 WriteAudio: buffer underrun
2010-12-30 23:35:55.776 NVP(0): Video is 3.02212 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:55.810 NVP(0): Video is 3.10574 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:56.579 WriteAudio: buffer underrun
2010-12-30 23:35:56.762 WriteAudio: buffer underrun
2010-12-30 23:35:56.846 WriteAudio: buffer underrun
2010-12-30 23:35:56.894 NVP(0): Video is 3.17341 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:56.927 NVP(0): Video is 3.35407 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:56.960 NVP(0): Video is 3.24984 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.046 WriteAudio: buffer underrun
2010-12-30 23:35:57.179 WriteAudio: buffer underrun
2010-12-30 23:35:57.194 NVP(0): Video is 3.48091 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.227 NVP(0): Video is 3.97435 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.261 NVP(0): Video is 4.32944 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.263 WriteAudio: buffer underrun
2010-12-30 23:35:57.294 NVP(0): Video is 4.85051 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.327 NVP(0): Video is 5.01648 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.361 NVP(0): Video is 4.88623 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.394 NVP(0): Video is 4.54876 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.427 NVP(0): Video is 4.04094 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.461 NVP(0): Video is 3.40532 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.463 WriteAudio: buffer underrun
2010-12-30 23:35:57.596 WriteAudio: buffer underrun
2010-12-30 23:35:57.678 NVP(0): Video is 3.06006 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.711 NVP(0): Video is 3.56878 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.713 WriteAudio: buffer underrun
2010-12-30 23:35:57.744 NVP(0): Video is 4.19007 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.778 NVP(0): Video is 4.43128 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.811 NVP(0): Video is 4.35743 frames ahead of audio,
         doubling video frame interval to slow down.
'video_output' mean = '22519.64', std. dev. = '7984.58', fps = '44.41'
2010-12-30 23:35:57.844 NVP(0): Video is 4.06228 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:57.878 NVP(0): Video is 3.58617 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:58.030 WriteAudio: buffer underrun
2010-12-30 23:35:58.097 WriteAudio: buffer underrun
2010-12-30 23:35:58.414 WriteAudio: buffer underrun
2010-12-30 23:35:58.564 WriteAudio: buffer underrun
2010-12-30 23:35:58.597 WriteAudio: buffer underrun
2010-12-30 23:35:58.631 WriteAudio: buffer underrun
2010-12-30 23:35:58.681 WriteAudio: buffer underrun
2010-12-30 23:35:59.198 WriteAudio: buffer underrun
2010-12-30 23:35:59.248 WriteAudio: buffer underrun
2010-12-30 23:35:59.431 WriteAudio: buffer underrun
2010-12-30 23:35:59.481 WriteAudio: buffer underrun
2010-12-30 23:35:59.565 WriteAudio: buffer underrun
2010-12-30 23:35:59.663 NVP(0): Video is 3.02865 frames ahead of audio,
         doubling video frame interval to slow down.
2010-12-30 23:35:59.749 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-12-30 23:35:59.749 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-12-30 23:35:59.790 TV: HandleStateChange(0) -- begin
2010-12-30 23:35:59.790 TV: Attempting to change from WatchingPreRecorded to None
2010-12-30 23:35:59.790 TV: StopStuff() for player ctx 0 -- begin
2010-12-30 23:35:59.790 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-12-30 23:35:59.790 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-12-30 23:35:59.790 TV: StopStuff(): stopping ring buffer
2010-12-30 23:35:59.804 NVP(0): Exited decoder loop.
2010-12-30 23:35:59.821 TV: StopStuff(): stopping player
2010-12-30 23:35:59.821 TV: StopStuff() -- end
2010-12-30 23:35:59.821 TV: Changing from WatchingPreRecorded to None
2010-12-30 23:35:59.821 TV: HandleStateChange(0) -- end
2010-12-30 23:35:59.821 TV: StartTV -- process events end
2010-12-30 23:35:59.821 TV: StartTV -- process events 2 begin
2010-12-30 23:35:59.825 TV: StartTV -- process events 2 end
2010-12-30 23:35:59.833 PlaybackBoxHelper: Requesting preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:35:59.833 PlaybackBoxHelper: Requested preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:35:59.836 VideoOutputXv: dtor
2010-12-30 23:35:59.836 VideoOutputXv: DiscardFrames(1)
2010-12-30 23:35:59.836 VideoBuffers::DiscardFrames(1): UULUUUUUUUAUUUUUUUUUUUUUUUUUUUu
2010-12-30 23:35:59.836 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:59.836 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 23:35:59.836 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:59.836 VideoOutputXv: DiscardFrames(1)
2010-12-30 23:35:59.836 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-12-30 23:35:59.836 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:59.836 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-12-30 23:35:59.836 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-12-30 23:35:59.839 VideoOutputXv: Closing XVideo port 355
2010-12-30 23:35:59.840 TV::~TV() -- begin
2010-12-30 23:35:59.955 TV::~TV() -- lock
2010-12-30 23:35:59.970 TV::~TV() -- end
2010-12-30 23:35:59.971 TV: StartTV -- end
2010-12-30 23:36:00.084 PlaybackBoxHelper: Requesting preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:36:00.084 PlaybackBoxHelper: Requested preview for '/myth1/tv/3351_20101226200000.mpg.png'
2010-12-30 23:36:00.434 mythbackend version: branches/release-0-23-fixes [25661M] www.mythtv.org
2010-12-30 23:36:00.434 Using runtime prefix = /usr
2010-12-30 23:36:00.434 Using configuration directory = /home/mythtv/.mythtv
2010-12-30 23:36:00.443 mythbackend version: branches/release-0-23-fixes [25661M] www.mythtv.org
2010-12-30 23:36:00.444 Using runtime prefix = /usr
2010-12-30 23:36:00.444 Using configuration directory = /home/mythtv/.mythtv
2010-12-30 23:36:00.444 Using localhost value of Pippin
2010-12-30 23:36:00.448 Using localhost value of Pippin
2010-12-30 23:36:00.458 New DB connection, total: 1
2010-12-30 23:36:00.463 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:36:00.464 Closing DB connection named 'DBManager0'
2010-12-30 23:36:00.465 New DB connection, total: 1
2010-12-30 23:36:00.466 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:36:00.470 Current MythTV Schema Version (DBSchemaVer): 1254
2010-12-30 23:36:00.472 ProgramInfo(): Updated pathname '':'' -> '3351_20101226200000.mpg'
2010-12-30 23:36:00.481 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:36:00.481 Closing DB connection named 'DBManager0'
2010-12-30 23:36:00.482 Connected to database 'mythconverg' at host: localhost
2010-12-30 23:36:00.486 Current MythTV Schema Version (DBSchemaVer): 1254
2010-12-30 23:36:00.488 ProgramInfo(): Updated pathname '':'' -> '3351_20101226200000.mpg'
2010-12-30 23:36:00.599 AFD: Opened codec 0x92ebab0, id(MPEG2VIDEO) type(Video)
2010-12-30 23:36:00.599 AFD: codec AC3 has 6 channels
2010-12-30 23:36:00.599 AFD: Opened codec 0x92fe820, id(AC3) type(Audio)
2010-12-30 23:36:00.599 AFD: codec AC3 has 2 channels
2010-12-30 23:36:00.600 AFD: Opened codec 0x92fef60, id(AC3) type(Audio)
2010-12-30 23:36:00.648 AFD: Opened codec 0x9c05a70, id(MPEG2VIDEO) type(Video)
2010-12-30 23:36:00.648 AFD: codec AC3 has 6 channels
2010-12-30 23:36:00.648 AFD: Opened codec 0x9c0b220, id(AC3) type(Audio)
2010-12-30 23:36:00.649 AFD: codec AC3 has 2 channels
2010-12-30 23:36:00.657 AFD: Opened codec 0x9c0b970, id(AC3) type(Audio)
2010-12-30 23:36:01.070 Preview: Grabbed preview '/myth1/tv/3351_20101226200000.mpg' 1280x720@64s
2010-12-30 23:36:01.087 Preview: Grabbed preview '/myth1/tv/3351_20101226200000.mpg' 1280x720@64s
2010-12-30 23:36:01.207 ~MythContext waiting for threads to exit.
2010-12-30 23:36:01.216 ~MythContext waiting for threads to exit.
2010-12-30 23:36:01.263 Preview: Preview process returned 0.
2010-12-30 23:36:01.263 Preview: Preview process ran ok.
2010-12-30 23:36:01.263 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg' ready
2010-12-30 23:36:01.263 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg.png' done
2010-12-30 23:36:01.268 Preview: previewThreadDone took 5ms
2010-12-30 23:36:01.293 Preview: Preview process returned 0.
2010-12-30 23:36:01.294 Preview: Preview process ran ok.
2010-12-30 23:36:01.294 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg' ready
2010-12-30 23:36:01.294 PlaybackBoxHelper: Preview for '/myth1/tv/3351_20101226200000.mpg.png' done
2010-12-30 23:36:01.299 Preview: previewThreadDone took 5ms
2010-12-30 23:46:39.672 Deleting UPnP client...

_________________
My System


Top
 Profile  
 
 Post subject:
PostPosted: Thu Dec 30, 2010 11:04 pm 
Offline
Joined: Tue Aug 15, 2006 11:14 am
Posts: 1343
Location: Orlando FL
to my naked Eye it appears to be an audio problem. I am using the built in audio card on my Winfast K8S 755A motherboard. It appears to be a "Realtek ALC655 5.1 channel audio (AC97 2.3)"

_________________
My System


Top
 Profile  
 
 Post subject: when?
PostPosted: Fri Dec 31, 2010 7:19 pm 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
mattbatt wrote:
to my naked Eye it appears to be an audio problem. I am using the built in audio card on my Winfast K8S 755A motherboard. It appears to be a "Realtek ALC655 5.1 channel audio (AC97 2.3)"

mattbatt,
did your playback problem start in the last 9 months or so? Mine did.
Specifically, mine started sometime during the summer of 2010. All my Fox recordings from May are smooth and fine. All my Fox recordings from September to the present stutter.

Eric

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Last edited by neutron68 on Sat Jan 01, 2011 10:55 am, edited 1 time in total.


Top
 Profile  
 
 Post subject:
PostPosted: Fri Dec 31, 2010 8:45 pm 
Offline
Joined: Thu Mar 25, 2004 11:00 am
Posts: 9551
Location: Arlington, MA
Interesting note, based on the logs you've got a FX5200 based card (NV17) but there's something odd about your playback profile. For one thing it doesn't want to do chromakey which the FX5200 is capable of.

Take a look at my posting about running HD with a Samsung TV and a FX5200. It should include details of the playback profile I used with R5.5 which worked pretty well.

You might get some leverage by overriding the artificial output clock limit that the nvidia drivers default to. That will give you enough bandwidth to do progressive scan rates, and that in turn will make it possible to use the bob2x de-interlacing.


Top
 Profile  
 
 Post subject: FX5200?
PostPosted: Fri Dec 31, 2010 9:29 pm 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
tjc wrote:
Interesting note, based on the logs you've got a FX5200 based card (NV17) but there's something odd about your playback profile. For one thing it doesn't want to do chromakey which the FX5200 is capable of.

Take a look at my posting about running HD with a Samsung TV and a FX5200. It should include details of the playback profile I used with R5.5 which worked pretty well.

You might get some leverage by overriding the artificial output clock limit that the nvidia drivers default to. That will give you enough bandwidth to do progressive scan rates, and that in turn will make it possible to use the bob2x de-interlacing.
tjc, are you adressing me or mattbatt?
Both of our posted logs reference NV17 as a display card. My system uses an on-motherboard nvidia 6150 chip.

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Top
 Profile  
 
 Post subject:
PostPosted: Sat Jan 01, 2011 10:50 am 
Offline
Joined: Thu Mar 25, 2004 11:00 am
Posts: 9551
Location: Arlington, MA
Honestly I lost track of who was posting what. I was just looking at the log files and noted the anomaly.


Top
 Profile  
 
 Post subject: FX6200
PostPosted: Sat Jan 01, 2011 10:54 am 
Offline
Joined: Tue Mar 28, 2006 8:26 pm
Posts: 804
Location: Minneapolis, MN
tjc wrote:
Honestly I lost track of who was posting what. I was just looking at the log files and noted the anomaly.
tjc,
I think you were looking at mattbatt's log. He has an FX6200 card in his system. Is it this thread you are referring to?
http://knoppmyth.net/phpBB2/viewtopic.php?t=19668&highlight=fx5200

Are there any how-to instructions on how to set/adjust the playback profile?
I've never been able to make sense of the choices in that window.
Why are there 2 lines there? One for each CPU core?
I've got a dual core AMD processor in my KM R5.5 box and just set the playback profile to CPU++.
All these settings worked great until Fox/KMSP made their change over the summer.
Image

_________________
KnoppMyth R5.5, Asus A8N-VM CSM (nvidia 6150 onboard video), AMD Athlon 64 dual-core 4200+, two 1GB sticks DDR 400, HD-3000 HDTV card, PVR-150 card, Iguanaworks RS-232 IR receiver/transmitter, Pioneer DVR-110 DVD burner


Top
 Profile  
 
 Post subject:
PostPosted: Sat Jan 01, 2011 11:17 am 
Offline
Joined: Thu Mar 25, 2004 11:00 am
Posts: 9551
Location: Arlington, MA
Still probably worth looking at the playback config, I spent a fair amount of time fiddling with the xorg.conf, codec selection and other playback settings, to minimize CPU load and maximize output quality. There's almost certainly one or two useful hints in there, like using NoMaxPClkCheck to get around the arbitrary "135.0 MHz maximum pixel clock" imposed on those cards.


Top
 Profile  
 

Display posts from previous:  Sort by  
Post new topic Reply to topic  [ 95 posts ] 
Go to page Previous  1, 2, 3, 4, 5, 6, 7  Next



All times are UTC - 6 hours




Who is online

Users browsing this forum: No registered users and 16 guests


You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot post attachments in this forum

Jump to:  
Powered by phpBB® Forum Software © phpBB Group

Theme Created By ceyhansuyu