[mythtv-users] Trouble playing some XviD encoded files with internal player

John Westlund john.westlund at gmail.com
Tue Apr 21 00:21:12 UTC 2009


All of these files encoded from MPEG to XviD using roughly the same
settings, certain shows play most of the time with the occasional black
screen and then back to the menu - a retry usually gets it playing.

However a large number of shows won't play at all. I'll get a black screen
or the first frame before being kicked back out to the menu.

I've tried to adjust my playback profile, but only seem to be able to make
things worse.  I have a "Test" profile did does ffmpeg & XVideo which seems
to get the best results in terms of ability to play the most file - but I'm
still stuck on why I can play some files but not all.

I've attached (now slightly abbreviated) output from mythfrontend -v
playback

Any help would be greatly appreciated,

John
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20090420/33e0639a/attachment.htm>
-------------- next part --------------
... Successful play
2009-04-19 16:44:11.276 TV: Attempting to change from None to WatchingPreRecorded
2009-04-19 16:44:11.279 RingBuf(/var/storage/MythTV/1441_20090419010000.XviD.avi): OpenFile(/var/storage/MythTV/1441_20090419010000.XviD.avi, 12)
2009-04-19 16:44:11.279 RingBuf(/var/storage/MythTV/1441_20090419010000.XviD.avi): CalcReadAheadThresh(141868264 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:17.705 AFD Error: Could not find codec parameters. file was "/var/storage/MythTV/1441_20090419010000.XviD.avi".
2009-04-19 16:44:17.705 Couldn't open decoder for: /var/storage/MythTV/1441_20090419010000.XviD.avi
2009-04-19 16:44:17.710 AFD: Stream #0, has id 0x0 codec id MPEG4, type Video, bitrate 0 at 0x0x8706730
2009-04-19 16:44:17.714 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:17.714 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:17.714 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:17.724 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:17.724 Using 2 CPUs for decoding
2009-04-19 16:44:17.725 AFD: InitVideoCodec() 0x8743c20 id(MPEG4) type (Video).
2009-04-19 16:44:17.726 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 320) ->Interlaced Scan
2009-04-19 16:44:17.726 AFD: Using ffmpeg for video decoding
2009-04-19 16:44:17.726 AFD: Looking for decoder for MPEG4
2009-04-19 16:44:17.726 AFD: Opened codec 0x8743c20, id(MPEG4) type(Video)
2009-04-19 16:44:17.726 AFD: Stream #1, has id 0x1 codec id MP3, type Audio, bitrate 128000 at 0x0x8706820
2009-04-19 16:44:17.726 AFD: codec MP3 has 2 channels
2009-04-19 16:44:17.726 AFD: Looking for decoder for MP3
2009-04-19 16:44:17.754 AFD: Opened codec 0x874b7c0, id(MP3) type(Audio)
2009-04-19 16:44:17.754 RingBuf(myth://127.0.0.1:6543/1441_20090419010000.XviD.avi): CalcReadAheadThresh(7695972 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:17.755 Dec: Trying to select track (w/lang)
2009-04-19 16:44:17.755 Dec: Selecting first track
2009-04-19 16:44:17.755 Dec: Selected track #1 in the Unknown language(0)
2009-04-19 16:44:17.755 AFD: Recording has no position -- using libavformat seeking.
2009-04-19 16:44:17.755 AFD: Successfully opened decoder for file: "myth://127.0.0.1:6543/1441_20090419010000.XviD.avi". novideo(0)
2009-04-19 16:44:17.757 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:17.757 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:17.757 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:17.773 TV: StartPlayer(): took 6470 ms to start player.
2009-04-19 16:44:17.773 TV Error: StartPlayer(): NVP is not playing after 20000 msec
2009-04-19 16:44:17.774 TV: Changing from None to WatchingPreRecorded
2009-04-19 16:44:17.775 VideoOutputNull()
2009-04-19 16:44:17.775 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:17.776 Display Rect  left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2009-04-19 16:44:17.776 Video Rect    left: 0, top: 0, width: 576, height: 320, aspect: 1.8
2009-04-19 16:44:17.776 Created data @0x8a84ae0->0x8ac82e2
...snip...
2009-04-19 16:44:17.777 Marking recording as watched using offset 4 minutes
2009-04-19 16:44:17.777 TV Error: nvp->IsPlaying() timed out
2009-04-19 16:44:17.777 Created data @0x8de12a0->0x8e24aa2
2009-04-19 16:44:17.777 TV: Attempting to change from WatchingPreRecorded to None
2009-04-19 16:44:17.777 Created data @0x8e24b10->0x8e68312
2009-04-19 16:44:17.777 TV: StopStuff() -- begin
2009-04-19 16:44:17.778 TV: StopStuff(): stopping ring buffer[s]
2009-04-19 16:44:17.778 Created data @0x8e68380->0x8eabb82
...snip...
2009-04-19 16:44:17.802 VDP: SetVideoRenderer(null)
2009-04-19 16:44:17.802 VDP: Old preferences: rend(xv-blit) osd(softblend) deint(none,none) filt()
2009-04-19 16:44:17.803 VDP: New preferences: rend(null) osd(softblend) deint(none,none) filt()
2009-04-19 16:44:17.803 Display Rect  left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2009-04-19 16:44:17.803 Video Rect    left: 0, top: 0, width: 576, height: 320, aspect: 1.8
2009-04-19 16:44:17.804 NVP: LoadFilters(''..) -> 0
2009-04-19 16:44:17.804 NVP: ClearAfterSeek(1)
2009-04-19 16:44:17.805 NVP: Exited decoder loop.
2009-04-19 16:44:17.808 ~VideoOutputNull()
2009-04-19 16:44:17.816 TV: StopStuff(): stopping player[s] (1/2)
2009-04-19 16:44:17.816 TV: StopStuff(): stopping player[s] (2/2)
2009-04-19 16:44:17.841 TV: StopStuff() -- end
2009-04-19 16:44:17.841 TV: Changing from WatchingPreRecorded to None
2009-04-19 16:44:18.513 Starting preview generator 1 && (0 || ((2009-04-19T07:01:03<2009-04-19T16:44:17)->1 && (2009-04-19T07:01:03>=2009-04-19T02:00:00)->1)) && 1 && 1 && 1
2009-04-19 16:44:18.913 Using runtime prefix = /usr
2009-04-19 16:44:18.914 Empty LocalHostName.
2009-04-19 16:44:18.914 Using localhost value of twinion.xensoft.com
2009-04-19 16:44:18.940 New DB connection, total: 1
2009-04-19 16:44:18.957 Connected to database 'mythconverg' at host: localhost
2009-04-19 16:44:18.958 Closing DB connection named 'DBManager0'
2009-04-19 16:44:18.960 Connected to database 'mythconverg' at host: localhost
2009-04-19 16:44:18.961 New DB connection, total: 2
2009-04-19 16:44:18.964 Connected to database 'mythconverg' at host: localhost
2009-04-19 16:44:18.966 Current Schema Version: 1214
2009-04-19 16:44:19.014 RingBuf(myth://127.0.0.1:6543/1441_20090419010000.XviD.avi): OpenFile(myth://127.0.0.1:6543/1441_20090419010000.XviD.avi, 1)
2009-04-19 16:44:19.030 RingBuf(myth://127.0.0.1:6543/1441_20090419010000.XviD.avi): CalcReadAheadThresh(142749000 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:19.507 AFD: Stream #0, has id 0x0 codec id MPEG4, type Video, bitrate 0 at 0x0xb724f960
2009-04-19 16:44:19.510 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:19.510 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:19.510 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:19.510 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:19.510 Using 2 CPUs for decoding
2009-04-19 16:44:19.511 AFD: InitVideoCodec() 0xb08f2900 id(MPEG4) type (Video).
2009-04-19 16:44:19.511 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 320) ->Interlaced Scan
2009-04-19 16:44:19.511 AFD: Using ffmpeg for video decoding
2009-04-19 16:44:19.511 AFD: Looking for decoder for MPEG4
2009-04-19 16:44:19.511 AFD: Opened codec 0xb08f2900, id(MPEG4) type(Video)
2009-04-19 16:44:19.511 AFD: Stream #1, has id 0x1 codec id MP3, type Audio, bitrate 128000 at 0x0xb05eae00
2009-04-19 16:44:19.511 AFD: codec MP3 has 2 channels
2009-04-19 16:44:19.511 AFD: Looking for decoder for MP3
2009-04-19 16:44:19.511 AFD: Opened codec 0xb06243c0, id(MP3) type(Audio)
2009-04-19 16:44:19.511 RingBuf(myth://127.0.0.1:6543/1441_20090419010000.XviD.avi): CalcReadAheadThresh(7695972 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:19.511 Dec: Trying to select track (w/lang)
2009-04-19 16:44:19.511 Dec: Selecting first track
2009-04-19 16:44:19.511 Dec: Selected track #1 in the Unknown language(0)
2009-04-19 16:44:19.512 AFD: Recording has no position -- using libavformat seeking.
2009-04-19 16:44:19.512 AFD: Successfully opened decoder for file: "myth://127.0.0.1:6543/1441_20090419010000.XviD.avi". novideo(0)
2009-04-19 16:44:19.515 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:19.515 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:19.515 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:19.516 New DB connection, total: 3
2009-04-19 16:44:19.526 Connected to database 'mythconverg' at host: localhost
2009-04-19 16:44:19.530 VideoOutputNull()
2009-04-19 16:44:19.530 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:19.530 Display Rect  left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2009-04-19 16:44:19.530 Video Rect    left: 0, top: 0, width: 576, height: 320, aspect: 1.8
2009-04-19 16:44:19.530 Created data @0x882f070->0x8872872
...snip...
2009-04-19 16:44:19.540 VDP: SetVideoRenderer(null)
2009-04-19 16:44:19.540 VDP: Old preferences: rend(xv-blit) osd(softblend) deint(none,none) filt()
2009-04-19 16:44:19.541 VDP: New preferences: rend(null) osd(softblend) deint(none,none) filt()
2009-04-19 16:44:19.541 Display Rect  left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2009-04-19 16:44:19.541 Video Rect    left: 0, top: 0, width: 576, height: 320, aspect: 1.8
2009-04-19 16:44:19.541 NVP: LoadFilters(''..) -> 0
2009-04-19 16:44:19.541 NVP: ClearAfterSeek(1)
2009-04-19 16:44:19.541 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-19 16:44:19.556 TV: Attempting to change from None to WatchingPreRecorded
2009-04-19 16:44:19.558 RingBuf(/var/storage/MythTV/1441_20090419010000.XviD.avi): OpenFile(/var/storage/MythTV/1441_20090419010000.XviD.avi, 12)
2009-04-19 16:44:19.558 RingBuf(/var/storage/MythTV/1441_20090419010000.XviD.avi): CalcReadAheadThresh(145087624 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:19.565 NVP: progressive frame seen after 2 interlaced  frames
2009-04-19 16:44:19.598 Disabled deinterlacing
2009-04-19 16:44:19.631 AFD: Opened codec 0x8ce5d40, id(MPEG4) type(Video)
2009-04-19 16:44:19.631 AFD: codec MP3 has 2 channels
2009-04-19 16:44:19.662 NVP: Exited decoder loop.
2009-04-19 16:44:19.665 ~VideoOutputNull()
2009-04-19 16:44:19.738 AFD: Opened codec 0x8ce61c0, id(MP3) type(Audio)
2009-04-19 16:44:19.841 AFD: Stream #0, has id 0x0 codec id MPEG4, type Video, bitrate 0 at 0x0xb05ea190
2009-04-19 16:44:19.844 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:19.844 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:19.844 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:19.844 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:19.844 Using 2 CPUs for decoding
2009-04-19 16:44:19.848 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:19.848 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:19.848 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:19.848 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:19.848 AFD: InitVideoCodec() 0xb061f970 id(MPEG4) type (Video).
2009-04-19 16:44:19.848 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 320) ->Interlaced Scan
2009-04-19 16:44:19.848 AFD: Using ffmpeg for video decoding
2009-04-19 16:44:19.848 AFD: Looking for decoder for MPEG4
2009-04-19 16:44:19.849 AFD: Opened codec 0xb061f970, id(MPEG4) type(Video)
2009-04-19 16:44:19.849 AFD: Stream #1, has id 0x1 codec id MP3, type Audio, bitrate 128000 at 0x0xb7242930
2009-04-19 16:44:19.849 AFD: codec MP3 has 2 channels
2009-04-19 16:44:19.849 AFD: Looking for decoder for MP3
2009-04-19 16:44:19.849 AFD: Opened codec 0xb056acd0, id(MP3) type(Audio)
2009-04-19 16:44:19.866 RingBuf(/var/storage/MythTV/1441_20090419010000.XviD.avi): CalcReadAheadThresh(7695972 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:19.871 Opening audio device '/dev/dsp'. ch 2(2) sr 48000
2009-04-19 16:44:19.871 Opening OSS audio device '/dev/dsp'.
2009-04-19 16:44:19.883 Dec: Trying to select track (w/lang)
2009-04-19 16:44:19.883 Dec: Selecting first track
2009-04-19 16:44:19.883 Dec: Selected track #1 in the Unknown language(0)
2009-04-19 16:44:19.883 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2009-04-19 16:44:21.720 Position map filled from DB to: 108025
2009-04-19 16:44:21.721 SyncPositionMap prerecorded, from DB: 2057 entries
2009-04-19 16:44:21.721 SyncPositionMap, new totframes: 108025, new length: 3604, posMap size: 2057
2009-04-19 16:44:21.721 AFD: Position map found
2009-04-19 16:44:21.721 AFD: Successfully opened decoder for file: "/var/storage/MythTV/1441_20090419010000.XviD.avi". novideo(0)
2009-04-19 16:44:21.740 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib
2009-04-19 16:44:21.740 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,xv-blit,opengl
2009-04-19 16:44:21.742 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:21.742 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:21.742 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:21.742 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:21.743 VideoOutput: Preferred renderer: xv-blit
2009-04-19 16:44:21.743 VideoOutput: Trying video renderer: xv-blit
2009-04-19 16:44:21.744 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:21.744 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:21.744 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:21.747 Preview: Grabbed preview '/var/storage/MythTV/1441_20090419010000.XviD.avi' 576x320 at 69s
2009-04-19 16:44:21.754 VideoOutputXv: ctor
2009-04-19 16:44:21.756 XOff: 0, YOff: -1
2009-04-19 16:44:21.756 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:21.756 Display Rect  left: 0, top: 93, width: 916, height: 535, aspect: 1.33333
2009-04-19 16:44:21.756 Video Rect    left: 0, top: 0, width: 576, height: 320, aspect: 1.8
2009-04-19 16:44:21.757 VideoOutputXv: Pixel dimensions: Screen 1024x768, window 916x722
2009-04-19 16:44:21.759 VideoOutputXv: Estimated display dimensions: 347x260 mm  Aspect: 1.33462
2009-04-19 16:44:21.759 VideoOutputXv: Estimated window dimensions: 310x244 mm  Aspect: 1.27049
2009-04-19 16:44:21.759 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: opengl,xv-blit,xshm,xlib
2009-04-19 16:44:21.761 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:21.761 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:21.761 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:21.762 VDP: LoadBestPreferences(576x320, 60)
2009-04-19 16:44:21.762 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2009-04-19 16:44:21.762 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2009-04-19 16:44:21.762 VideoOutputXv: Has XVideo flags...
2009-04-19 16:44:21.762 VideoOutputXv: Has XV_BRIGHTNESS...
2009-04-19 16:44:21.762 VideoOutputXv: Here...
2009-04-19 16:44:21.763 VideoOutputXv: Grabbed xv port 280
2009-04-19 16:44:21.763 VideoOutputXv: XVideo surface found on port 280
2009-04-19 16:44:21.763 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2009-04-19 16:44:21.763 VideoOutputXv: XVideo Format #0 is 'YUY2'
2009-04-19 16:44:21.763 VideoOutputXv: XVideo Format #1 is 'YV12'
2009-04-19 16:44:21.764 VideoOutputXv: XVideo Format #2 is 'UYVY'
2009-04-19 16:44:21.764 VideoOutputXv: XVideo Format #3 is 'I420'
2009-04-19 16:44:21.764 VideoOutputXv: Using XVideo Format 'YV12'
2009-04-19 16:44:21.764 VideoOutputXv: CreateShmImages(32): video_dim: 576x320
2009-04-19 16:44:21.793 VDP: SetVideoRenderer(xv-blit)
2009-04-19 16:44:21.794 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2009-04-19 16:44:21.794 VideoOutputXv: Chromakeying not possible with this XVideo port.
2009-04-19 16:44:21.794 Display Rect  left: 0, top: 106, width: 916, height: 510, aspect: 1.27049
2009-04-19 16:44:21.794 Video Rect    left: 0, top: 0, width: 576, height: 320, aspect: 1.8
2009-04-19 16:44:21.796 Over/underscan. V: 0.01, H: 0
2009-04-19 16:44:21.796 Display Rect  left: 0, top: 106, width: 916, height: 510, aspect: 1.27049
2009-04-19 16:44:21.796 Video Rect    left: 0, top: 4, width: 576, height: 314, aspect: 1.8
2009-04-19 16:44:21.796 VDP: LoadBestPreferences(576x320, 29.97)
2009-04-19 16:44:21.796 NVP: LoadFilters(''..) -> 0
2009-04-19 16:44:21.798 OSD Theme Dimensions W: 640 H: 480
2009-04-19 16:44:21.926 Preview: Preview process returned 0.
2009-04-19 16:44:21.926 Preview: Preview process ran ok.
2009-04-19 16:44:21.931 Preview: previewThreadDone took 5ms
2009-04-19 16:44:22.932 NVP: ClearAfterSeek(1)
2009-04-19 16:44:22.932 TV: StartPlayer(): took 3362 ms to start player.
2009-04-19 16:44:22.933 VideoOutputXv: ClearAfterSeek()
2009-04-19 16:44:22.933 VideoOutputXv: DiscardFrames(0)
2009-04-19 16:44:22.934 TV: Changing from None to WatchingPreRecorded
2009-04-19 16:44:22.934 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-19 16:44:22.934 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-04-19 16:44:22.934 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-19 16:44:22.935 VDP: GetFilteredDeint() : xv-blit -> 'none'
2009-04-19 16:44:22.935 FilterManager: failed to load filter 'none', no such filter exists
2009-04-19 16:44:22.936 Couldn't load deinterlace filter none
2009-04-19 16:44:22.936 Using deinterlace method 
2009-04-19 16:44:22.939 The realtime priority setting is not enabled.
2009-04-19 16:44:23.037 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2009-04-19 16:44:23.038 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2009-04-19 16:44:23.038 RTCVideoSync: Could not set RTC frequency, Permission denied.
2009-04-19 16:44:23.038 Using audio as timebase
2009-04-19 16:44:23.039 Video timing method: USleep with busy wait
2009-04-19 16:44:23.039 Refresh rate: 16665, frame interval: 33366
2009-04-19 16:44:23.072 VideoOutputXv: UpdatePauseFrame() uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-19 16:44:23.073 AFD: DoFastForward(35 (1), do discard frames)
2009-04-19 16:44:23.073 Dec: DoFastForward(35 (1), do discard frames)
2009-04-19 16:44:23.073 AFD: SeekReset(0, 34, don't flush, do discard)
2009-04-19 16:44:23.073 VideoOutputXv: DiscardFrames(0)
2009-04-19 16:44:23.073 VideoBuffers::DiscardFrames(0): uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-19 16:44:23.073 VideoBuffers::DiscardFrames(0): aLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-04-19 16:44:23.073 VideoOutputXv: DiscardFrames() 3: aLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-19 16:44:23.090 mdb:34, lastbuf:0 skipping granule 0
2009-04-19 16:44:23.155 NVP: ClearAfterSeek(0)
2009-04-19 16:44:23.185 NVP: Waiting for prebuffer.. 0 AAALAaAAAAAAAAAAAAAAAAAAAAAAAAa
2009-04-19 16:44:23.186 bits_left=-3
2009-04-19 16:44:23.198 NVP: progressive frame seen after 2 interlaced  frames
2009-04-19 16:44:23.200 NVP: Video is 3.97111 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.200 Disabled deinterlacing
2009-04-19 16:44:23.200 NVP: Video is 6.70218 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.200 NVP: Video is 8.49571 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.201 NVP: Video is 9.35386 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.201 NVP: Video is 9.7427 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.201 NVP: Video is 9.78709 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.201 NVP: Video is 9.57313 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.202 NVP: Video is 9.16538 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.202 NVP: Video is 8.61233 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.202 NVP: Video is 7.95028 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.202 NVP: Video is 7.20647 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.203 NVP: Video is 6.40137 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.203 NVP: Video is 5.5428 frames behind audio (too slow), dropping frame to catch up.
2009-04-19 16:44:23.203 NVP: prebuffering pause
2009-04-19 16:44:23.203 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAuAUAALAAAAAAAAA
2009-04-19 16:44:23.217 NVP: Video is 4.40433 frames behind audio (too slow), dropping frame to catch up.
'video_output' mean = '31713.88', std. dev. = '15978.66', fps = '31.53'
'video_output' mean = '36035.29', std. dev. = '10079.44', fps = '27.75'
'video_output' mean = '33359.19', std. dev. = '837.68', fps = '29.98'
2009-04-19 16:44:34.438 Marking recording as unwatched
2009-04-19 16:44:34.438 TV: Attempting to change from WatchingPreRecorded to None
2009-04-19 16:44:34.438 TV: StopStuff() -- begin
2009-04-19 16:44:34.438 TV: StopStuff(): stopping ring buffer[s]
2009-04-19 16:44:34.463 TV: StopStuff(): stopping player[s] (1/2)
2009-04-19 16:44:34.463 TV: StopStuff(): stopping player[s] (2/2)
2009-04-19 16:44:34.467 NVP: Exited decoder loop.
2009-04-19 16:44:34.479 VideoOutputXv: dtor
2009-04-19 16:44:34.479 VideoOutputXv: DiscardFrames(1)
2009-04-19 16:44:34.479 VideoBuffers::DiscardFrames(1): UUAUUUUUUUUUUUUUUUUUUUUUuUUuULU
2009-04-19 16:44:34.480 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-19 16:44:34.480 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-04-19 16:44:34.480 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-19 16:44:34.480 VideoOutputXv: DiscardFrames(1)
2009-04-19 16:44:34.480 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-19 16:44:34.480 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-19 16:44:34.480 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-04-19 16:44:34.481 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-19 16:44:34.482 VideoOutputXv: Closing XVideo port 280
2009-04-19 16:44:34.846 TV: StopStuff() -- end
2009-04-19 16:44:34.846 TV: Changing from WatchingPreRecorded to None
2009-04-19 16:44:36.967 RingBuf(myth://127.0.0.1:6543/1071_20090418233500.mpg): OpenFile(myth://127.0.0.1:6543/1071_20090418233500.mpg, 1)
2009-04-19 16:44:37.068 RingBuf(myth://127.0.0.1:6543/1071_20090418233500.mpg): CalcReadAheadThresh(2958796520 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:37.715 AFD: Stream #0, has id 0x1984 codec id MPEG2VIDEO, type Video, bitrate 38810400 at 0x0x889a210
2009-04-19 16:44:37.717 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:37.717 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:37.717 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:37.717 VDP: LoadBestPreferences(1280x720, 60)
2009-04-19 16:44:37.718 Using 2 CPUs for decoding
2009-04-19 16:44:37.718 AFD: InitVideoCodec() 0x8f9df30 id(MPEG2VIDEO) type (Video).
2009-04-19 16:44:37.718 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2009-04-19 16:44:37.718 AFD: Using ffmpeg for video decoding
2009-04-19 16:44:37.718 AFD: Looking for decoder for MPEG2VIDEO
2009-04-19 16:44:37.718 AFD: Opened codec 0x8f9df30, id(MPEG2VIDEO) type(Video)
2009-04-19 16:44:37.718 AFD: Stream #1, has id 0x1985 codec id AC3, type Audio, bitrate 384000 at 0x0x8705f80
2009-04-19 16:44:37.718 AFD: codec AC3 has 6 channels
2009-04-19 16:44:37.718 AFD: Looking for decoder for AC3
2009-04-19 16:44:37.719 AFD: Opened codec 0x88a3990, id(AC3) type(Audio)
2009-04-19 16:44:37.719 RingBuf(myth://127.0.0.1:6543/1071_20090418233500.mpg): CalcReadAheadThresh(6647399 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-19 16:44:37.719 Dec: Trying to select track (w/lang)
2009-04-19 16:44:37.719 Dec: Selecting first track
2009-04-19 16:44:37.719 Dec: Selected track #1 in the Unknown language(0)
2009-04-19 16:44:37.719 AFD: Recording has no position -- using libavformat seeking.
2009-04-19 16:44:37.720 AFD: Successfully opened decoder for file: "myth://127.0.0.1:6543/1071_20090418233500.mpg". novideo(0)
2009-04-19 16:44:37.721 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-19 16:44:37.721 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-19 16:44:37.721 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-19 16:44:37.724 VideoOutputNull()
2009-04-19 16:44:37.724 VDP: LoadBestPreferences(1280x720, 60)
2009-04-19 16:44:37.724 Display Rect  left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2009-04-19 16:44:37.724 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2009-04-19 16:44:37.724 Created data @0x8c08790->0x8d59f92
...snip...
2009-04-19 16:44:37.788 VDP: SetVideoRenderer(null)
2009-04-19 16:44:37.788 VDP: Old preferences: rend(xv-blit) osd(softblend) deint(none,none) filt()
2009-04-19 16:44:37.789 VDP: New preferences: rend(null) osd(softblend) deint(none,none) filt()
2009-04-19 16:44:37.789 Display Rect  left: 0, top: 0, width: 0, height: 0, aspect: 1.33333
2009-04-19 16:44:37.789 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2009-04-19 16:44:37.789 NVP: LoadFilters(''..) -> 0
2009-04-19 16:44:37.789 NVP: ClearAfterSeek(1)
2009-04-19 16:44:37.789 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
CC length(19) seq_num(1) 0x4a 0x31 0x98 0x1b 0x46 0x0 0x0 0x1f 0x14 0x90 0x5 0x3 0x91 0x3f 0x0 0x3f 0x92 0x0 
2009-04-19 16:44:37.855 NVP: Waiting for prebuffer.. 1 LLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-19 16:44:37.865 NVP: Exited decoder loop.
2009-04-19 16:44:37.922 ~VideoOutputNull()
2009-04-19 16:44:38.094 RingBuf(/var/storage/MythTV/1922_20090419070000.XviD.avi): OpenFile(/var/storage/MythTV/1922_20090419070000.XviD.avi, 1)
2009-04-19 16:44:38.094 RingBuf(/var/storage/MythTV/1922_20090419070000.XviD.avi): CalcReadAheadThresh(2961793136 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)


More information about the mythtv-users mailing list