[mythtv-users] Trouble with HD Playback
David Brieck Jr.
dbrieck at gmail.com
Tue Oct 9 02:45:43 UTC 2007
On 10/8/07, Phil Bridges <gravityhammer at gmail.com> wrote:
> It may help to post your frontend logs.
Here the output with playback verbose. I can see why it's doing what
it's doing now, but I don't understand why or how to fix it.
2007-10-08 22:42:28.372 Using runtime prefix = /usr
2007-10-08 22:42:28.378 Gnome-Screensaver support enabled
2007-10-08 22:42:28.379 DPMS is active.
2007-10-08 22:42:28.387 New DB connection, total: 1
2007-10-08 22:42:28.390 Connected to database 'mythconverg' at host: mythtv
2007-10-08 22:42:28.391 Total desktop dim: 1280x720, with 1 screen[s].
2007-10-08 22:42:28.393 Using screen 0, 1280x720 at 0,0
2007-10-08 22:42:28.403 user: 1000 effective user: 1000 before privileged thread
2007-10-08 22:42:28.403 user: 1000 effective user: 1000 after privileged thread
2007-10-08 22:42:28.403 user: 1000 effective user: 1000 run_priv_thread
2007-10-08 22:42:28.406 Current Schema Version: 1160
2007-10-08 22:42:28.408 mythfrontend version: 0.20.20070821-1 www.mythtv.org
2007-10-08 22:42:28.408 Enabled verbose msgs: important general playback
2007-10-08 22:42:28.626 max_width: 1280 max_height: 720
2007-10-08 22:42:28.789 Total desktop dim: 1280x720, with 1 screen[s].
2007-10-08 22:42:28.790 Using screen 0, 1280x720 at 0,0
2007-10-08 22:42:28.790 Switching to square mode (MythCenter)
2007-10-08 22:42:28.804 Using the OpenGL painter
mythtv: unknown token "change" in /home/dbrieck/.mythtv/lircrc:255 ignored
2007-10-08 22:42:28.987 New DB connection, total: 2
2007-10-08 22:42:28.987 Joystick disabled.
2007-10-08 22:42:28.989 Connected to database 'mythconverg' at host: mythtv
2007-10-08 22:42:29.310 Loading from: /usr/share/mythtv/themes/default/base.xml
2007-10-08 22:42:29.504 Registering Internal as a media playback plugin.
2007-10-08 22:42:29.532 Registering MythDVD DVD Media Handler as a
media handler ext()
2007-10-08 22:42:29.533 Mediamonitor: Adding /dev/scd0
2007-10-08 22:42:29.659 Mediamonitor: AddDevice() -- Not adding
'/dev/scd0', it appears to be a duplicate.
2007-10-08 22:42:29.660 Registering MythDVD VCD Media Handler as a
media handler ext()
2007-10-08 22:42:29.698 Using NV NPOT texture extension
adding: 0,0,0 -- DVD-E616A3T
2007-10-08 22:42:30.161 Registering MythMusic Media Handler 1/2 as a
media handler ext()
2007-10-08 22:42:30.161 Registering MythMusic Media Handler 2/2 as a
media handler ext(ogg,mp3,aac,flac)
2007-10-08 22:42:30.161 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
2007-10-08 22:42:30.204 Starting media monitor.
2007-10-08 22:42:32.288 XMLParse::LoadTheme using
/usr/share/mythtv/themes/MythCenter/ui.xml
2007-10-08 22:42:32.384 Connecting to backend server:
192.168.1.105:6543 (try 1 of 5)
2007-10-08 22:42:32.385 Using protocol version 31
2007-10-08 22:42:36.778 TV: Attempting to change from None to
WatchingPreRecorded
2007-10-08 22:42:36.781
RingBuf(/home/mythtv/store/3431_20071008210000.mpg):
OpenFile(/home/mythtv/store/3431_20071008210000.mpg, 12)
2007-10-08 22:42:36.782
RingBuf(/home/mythtv/store/3431_20071008210000.mpg):
CalcReadAheadThresh(2971373072 KB)
-> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-10-08 22:42:36.827 Avg read interval was 197 msec. 64K block size
2007-10-08 22:42:36.859 Avg read interval was 197 msec. 96K block size
2007-10-08 22:42:36.880 DPMS Deactivated
2007-10-08 22:42:36.892 Avg read interval was 197 msec. 64K block size
2007-10-08 22:42:36.912 Avg read interval was 197 msec. 64K block size
2007-10-08 22:42:36.928 Avg read interval was 197 msec. 64K block size
0: start_time: 5373.755 duration: 350.734
1: start_time: 5373.706 duration: 350.741
stream: start_time: 59707.845 duration: 3897.590 bitrate=12498 kb/s
2007-10-08 22:42:36.945 AFD: Stream #0, has id 0x49 codec id
MPEG2VIDEO, type Video, bitrate 19392800 at 0x0x8c97990
2007-10-08 22:42:36.947 detectInterlace(Detect Scan, Interlaced Scan,
59.9401, 720) ->Progressive Scan
2007-10-08 22:42:36.947 AFD: Looking for decoder for MPEG2VIDEO
2007-10-08 22:42:36.947 AFD: Opened codec 0x8c8eb10, id(MPEG2VIDEO) type(Video)
2007-10-08 22:42:36.948 AFD: Stream #1, has id 0x52 codec id AC3, type
Audio, bitrate 448000 at 0x0x8c84390
2007-10-08 22:42:36.948 AFD: Looking for decoder for AC3
2007-10-08 22:42:36.948 AFD: Opened codec 0x8c8ee60, id(AC3) type(Audio)
2007-10-08 22:42:36.949
RingBuf(/home/mythtv/store/3431_20071008210000.mpg):
CalcReadAheadThresh(3086455552 KB)
-> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-10-08 22:42:36.951 Opening ALSA audio device 'default'.
2007-10-08 22:42:36.959 Avg read interval was 198 msec. 64K block size
2007-10-08 22:42:36.986 Avg read interval was 198 msec. 96K block size
2007-10-08 22:42:36.991 Mixer unable to find control Master
2007-10-08 22:42:36.991 Mixer unable to find control Master
2007-10-08 22:42:36.993 Dec: Trying to select track (w/lang)
2007-10-08 22:42:36.993 Dec: Selecting first track
2007-10-08 22:42:36.993 Dec: Selected track #1 in the Unknown language(0)
2007-10-08 22:42:36.994 Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(0)
2007-10-08 22:42:37.025 Avg read interval was 199 msec. 128K block size
2007-10-08 22:42:37.057 Avg read interval was 197 msec. 160K block size
2007-10-08 22:42:37.089 Avg read interval was 197 msec. 192K block size
2007-10-08 22:42:37.121 Avg read interval was 197 msec. 224K block size
2007-10-08 22:42:40.325 Position map filled from DB to: 233410
2007-10-08 22:42:40.327 SyncPositionMap prerecorded, from DB: 14478 entries
2007-10-08 22:42:40.327 SyncPositionMap, new totframes: 233410, new
length: 3894, posMap size: 14478
2007-10-08 22:42:40.327 AFD: Position map found
2007-10-08 22:42:40.327 AFD: Successfully opened decoder for file:
"/home/mythtv/store/3431_20071008210000.mpg". novideo(0)
2007-10-08 22:42:40.355 VideoOutputXv: ctor
2007-10-08 22:42:40.355 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
2007-10-08 22:42:40.355 Display Rect left: 0, top: 86, width: 1218,
height: 514, aspect: 1.33333
2007-10-08 22:42:40.355 Video Rect left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2007-10-08 22:42:40.356 VideoOutputXv: Pixel dimensions: Screen
1280x720, window 1218x686
2007-10-08 22:42:40.356 VideoOutputXv: Estimated display dimensions:
325x183 mm Aspect: 1.77596
2007-10-08 22:42:40.356 VideoOutputXv: Estimated window dimensions:
309x174 mm Aspect: 1.77586
2007-10-08 22:42:40.357 VideoOutputXv: XvMCTex: Init failed
2007-10-08 22:42:40.358 VideoOutputXv: @ j=3 Looking for flag[s]:
XvInputMask XvImageMask
2007-10-08 22:42:40.358 VideoOutputXv: Adaptor#0: NV17 Video Texture
has flag[s]: XvInputMask XvImageMask
2007-10-08 22:42:40.358 VideoOutputXv: Grabbed xv port 275
2007-10-08 22:42:40.358 VideoOutputXv: XVideo surface found on port 275
2007-10-08 22:42:40.358 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2007-10-08 22:42:40.358 VideoOutputXv: XVideo Format #0 is 'YUY2'
2007-10-08 22:42:40.358 VideoOutputXv: XVideo Format #1 is 'YV12'
2007-10-08 22:42:40.358 VideoOutputXv: XVideo Format #2 is 'UYVY'
2007-10-08 22:42:40.358 VideoOutputXv: XVideo Format #3 is 'I420'
2007-10-08 22:42:40.359 VideoOutputXv: Using XVideo Format 'I420'
2007-10-08 22:42:40.359 VideoOutputXv: CreateShmImages(32): video_dim: 1280x720
X Error: BadMatch (invalid parameter attributes) 8
Major opcode: 141
Minor opcode: 14
Resource id: 0x1a0
2007-10-08 22:42:40.407 VideoOutputXv: Chromakeying not possible with
this XVideo port.
2007-10-08 22:42:40.407 Snapping height to avoid scaling: height: 720, top: -17
2007-10-08 22:42:40.407 Display Rect left: 0, top: -17, width: 1218,
height: 720, aspect: 1.77778
2007-10-08 22:42:40.407 Video Rect left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2007-10-08 22:42:40.892 NVP: ClearAfterSeek(1)
2007-10-08 22:42:40.892 VideoOutputXv: ClearAfterSeek()
2007-10-08 22:42:40.892 VideoOutputXv: DiscardFrames(0)
2007-10-08 22:42:40.892 VideoBuffers::DiscardFrames(0):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-08 22:42:40.892 VideoBuffers::DiscardFrames(0):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2007-10-08 22:42:40.893 VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:40.898 Using deinterlace method kerneldeint
2007-10-08 22:42:40.899 The realtime priority setting is not enabled.
2007-10-08 22:42:40.899 TV: StartPlayer(): took 4081 ms to start player.
2007-10-08 22:42:40.900 TV: Changing from None to WatchingPreRecorded
2007-10-08 22:42:40.913 AFD: DoFastForward(3495 (1), do discard frames)
2007-10-08 22:42:40.913 Dec: DoFastForward(3495 (1), do discard frames)
2007-10-08 22:42:40.913 AFD: SeekReset(3509, 0, do flush, do discard)
2007-10-08 22:42:40.913 AFD: SeekReset() flushing
2007-10-08 22:42:40.913 VideoOutputXv: DiscardFrames(1)
2007-10-08 22:42:40.913 VideoBuffers::DiscardFrames(1):
UAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-08 22:42:40.913 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:40.914 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2007-10-08 22:42:40.914 VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:40.914 NVP: ClearAfterSeek(0)
[mpeg2video @ 0xb720b2e8]ac-tex damaged at 0 40
[mpeg2video @ 0xb720b2e8]ac-tex damaged at 4 41
[mpeg2video @ 0xb720b2e8]ac-tex damaged at 1 42
[mpeg2video @ 0xb720b2e8]ac-tex damaged at 3 43
[mpeg2video @ 0xb720b2e8]end mismatch left=2228 766D02
2007-10-08 22:42:40.984 Avg read interval was 188 msec. 64K block size
2007-10-08 22:42:41.000 nVidiaVideoSync: VBlank ioctl did not work,
unimplemented in this driver?
2007-10-08 22:42:41.000 DRMVideoSync: Could not open device
/dev/dri/card0, No such file or directory
2007-10-08 22:42:41.002 OpenGLVideoSync: GLX extensions:
GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig
GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control
GLX_EXT_texture_from_pixmap GLX_ARB_multisample GLX_NV_float_buffer
GLX_ARB_fbconfig_float GLX_ARB_get_proc_address
2007-10-08 22:42:41.002 OpenGLVideoSync: x,y -> 637, 361
2007-10-08 22:42:41.014 Using OpenGLVideoSync
2007-10-08 22:42:41.016 Avg read interval was 197 msec. 96K block size
2007-10-08 22:42:41.024 Using audio as timebase
2007-10-08 22:42:41.024 Video timing method: SGI OpenGL
2007-10-08 22:42:41.024 Refresh rate: 16646, frame interval: 16683
2007-10-08 22:42:41.041 NVP: Waiting for prebuffer.. 0
AAUUUUUUUuUULLAAAAAAAAAAAAAAAAA
2007-10-08 22:42:41.048 Avg read interval was 197 msec. 128K block size
2007-10-08 22:42:41.057 NVP: progressive frame seen after 2 interlaced frames
2007-10-08 22:42:41.080 Avg read interval was 197 msec. 160K block size
2007-10-08 22:42:41.112 Avg read interval was 197 msec. 192K block size
2007-10-08 22:42:41.125 NVP: Video is 8.45172 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.126 Disabled deinterlacing
2007-10-08 22:42:41.144 Avg read interval was 197 msec. 224K block size
2007-10-08 22:42:41.175 NVP: Video is 14.266 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.179 Avg read interval was 198 msec. 256K block size
2007-10-08 22:42:41.195 NVP: Video is 18.2072 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.242 NVP: Video is 21.118 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.261 NVP: Video is 22.8666 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.309 NVP: Video is 24.1481 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.328 NVP: Video is 24.5847 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.376 NVP: Video is 24.8972 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.395 NVP: Video is 24.5921 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.442 NVP: Video is 24.3483 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.461 NVP: Video is 23.6409 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.509 NVP: Video is 23.0954 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.528 NVP: Video is 22.2817 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.576 NVP: Video is 21.6863 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.595 NVP: Video is 20.7303 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.642 NVP: Video is 19.9834 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.661 NVP: Video is 18.8987 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.709 NVP: Video is 18.0702 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.729 NVP: Video is 17.1041 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.776 NVP: Video is 16.3197 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.795 NVP: Video is 15.2218 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.842 NVP: Video is 14.3834 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.862 NVP: Video is 13.2001 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.909 NVP: Video is 12.2977 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.929 NVP: Video is 11.1564 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.976 NVP: Video is 10.2704 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:41.995 NVP: Video is 9.09639 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.042 NVP: Video is 8.17095 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.062 NVP: Video is 7.14722 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.109 NVP: Video is 6.36438 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.128 NVP: Video is 5.25277 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.175 NVP: Video is 4.38914 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.195 NVP: Video is 3.2469 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-08 22:42:42.702 NVP: Video is 3.66691 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:42.702 NVP: Video is 4.47348 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:42.702 NVP: Video is 4.82365 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:42.702 NVP: Video is 4.83151 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:42.702 NVP: Video is 4.59767 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:42.702 NVP: Video is 4.16754 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:42.702 NVP: Video is 3.59018 frames behind audio (too
slow), dropping frame to catch up.
'video_output' mean = '22319.75', std. dev. = '15605.95', fps = '44.80'
2007-10-08 22:42:44.844 NVP: Video is 3.41647 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:44.844 NVP: Video is 3.56632 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:44.844 NVP: Video is 3.43895 frames behind audio (too
slow), dropping frame to catch up.
2007-10-08 22:42:44.844 NVP: Video is 3.08871 frames behind audio (too
slow), dropping frame to catch up.
'video_output' mean = '16830.81', std. dev. = '9540.54', fps = '59.41'
2007-10-08 22:42:45.344 Dec: Selected track #1 in the Unknown language(0)
2007-10-08 22:42:45.883 TV: Attempting to change from
WatchingPreRecorded to None
2007-10-08 22:42:45.883 TV: StopStuff() -- begin
2007-10-08 22:42:45.883 TV: StopStuff(): stopping ring buffer[s]
2007-10-08 22:42:45.925 TV: StopStuff(): stopping player[s] (1/2)
2007-10-08 22:42:45.925 TV: StopStuff(): stopping player[s] (2/2)
2007-10-08 22:42:45.925 NVP: Exited decoder loop.
2007-10-08 22:42:45.946 VideoOutputXv: dtor
2007-10-08 22:42:45.946 VideoOutputXv: DiscardFrames(1)
2007-10-08 22:42:45.946 VideoBuffers::DiscardFrames(1):
UUUUUUUUUUUUUUUUUUUAUuUULUUUUUU
2007-10-08 22:42:45.946 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:45.946 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2007-10-08 22:42:45.946 VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:45.946 VideoOutputXv: DiscardFrames(1)
2007-10-08 22:42:45.947 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-08 22:42:45.947 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:45.947 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2007-10-08 22:42:45.947 VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-10-08 22:42:45.949 VideoOutputXv: Closing XVideo port 275
2007-10-08 22:42:46.041 TV: StopStuff() -- end
2007-10-08 22:42:46.041 TV: Changing from WatchingPreRecorded to None
2007-10-08 22:42:46.431 DPMS Reactivated.
More information about the mythtv-users
mailing list