[mythtv-users] Trouble with HD Playback
Daniel Arfsten
darfsten at hotmail.com
Tue Oct 9 15:53:45 UTC 2007
RE below:
Is there a rule for the mailing list to use pastebin.org, IMHO, this is just
completely unneccessary clogging up the mailing list for no reason.
RE above:
>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.
Daniel
More information about the mythtv-users
mailing list