[mythtv-users] Playback of recordings from C4HD slow to start

Robert Longbottom rongblor at googlemail.com
Sun Dec 9 16:26:05 UTC 2012


Hi all,

Is anyone else seeing this?  I've been seeing it for a while now, but 
have only just gotten round to looking into it.

I'm running MythTV 0.25.2 on Gentoo.  I have a master backend and a 
remote frontend.

When I start any recording from Channel 4 HD it spends about 8 seconds 
on the "Please wait" screen before the recording starts.  Turning on '-v 
playback' for the frontend logging, I can see this pause (two lines 
below, full log futher down) is happening during (?) ScanStreams at 
which point I also see CPU usage go to 100%.



2012-12-09 16:07:42.985376 I [23925/23925] CoreContext 
avformatdecoder.cpp:814 (InitByteContext) - AFD: Buffer size: 32768, 
streamed 0

2012-12-09 16:07:50.945431 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #0, has id 0x901 
codec id H264, type Video, bitrate 0 at 0x8aac9e0



The frontend is a 2.6GHz Pentium 4 and I have an nvidia 8400GS vdpau 
capable card.  Once playback is going, seek performance is fine.

The 100% CPU and the fact the seek performance is ok once playback has 
started imples to me that this isn't a network bandwidth problem between 
FE and BE (it's only 100Mbs at the minute, but even at gigabit it isn't 
any better).

Wierdly I only see this on Channel 4 HD recordings.  BBC HD starts much 
quicker, with only a 1 second delay at ScanStreams:



2012-12-09 16:22:40.359742 I [23925/23925] CoreContext 
avformatdecoder.cpp:814 (InitByteContext) - AFD: Buffer size: 32768, 
streamed 0

2012-12-09 16:22:41.068539 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #0, has id 0x157c 
codec id H264, type Video, bitrate 0 at 0x9003f20



Is this something wrong with my configuration?  Or do other people see 
the same thing?

Cheers,
Rob.

Full frontend log of starting C4HD playback below:

2012-12-09 16:07:42.747886 I [23925/23925] CoreContext tv_play.cpp:987 
(TV) - TV: Creating TV object
2012-12-09 16:07:42.758298 I [23925/23925] CoreContext tv_play.cpp:1006 
(TV) - TV: Finished creating TV object
2012-12-09 16:07:42.758318 I [23925/23925] CoreContext tv_play.cpp:281 
(StartTV) - TV: StartTV() -- begin
2012-12-09 16:07:42.758453 N [23925/23925] CoreContext 
mythmainwindow.cpp:2586 (PauseIdleTimer) - Suspending idle timer
2012-12-09 16:07:42.758468 I [23925/23925] CoreContext tv_play.cpp:1132 
(Init) - TV: Init -- begin
2012-12-09 16:07:42.761894 I [23925/23925] CoreContext tv_play.cpp:1206 
(Init) - TV: Created TvPlayWindow.
2012-12-09 16:07:42.762474 I [23925/23925] CoreContext tv_play.cpp:8015 
(DrawUnusedRects) - TV: DrawUnusedRects() -- begin
2012-12-09 16:07:42.762497 I [23925/23925] CoreContext tv_play.cpp:8028 
(DrawUnusedRects) - TV: DrawUnusedRects() -- end
2012-12-09 16:07:42.765374 I [23925/23925] CoreContext tv_play.cpp:1252 
(Init) - TV: Init -- end
2012-12-09 16:07:42.766530 I [23925/23925] CoreContext tv_play.cpp:332 
(StartTV) - TV: tv->Playback() -- begin
2012-12-09 16:07:42.772104 I [23925/23925] CoreContext tv_play.cpp:2094 
(HandleStateChange) - TV: HandleStateChange(0) -- begin
2012-12-09 16:07:42.772133 I [23925/23925] CoreContext tv_play.cpp:2121 
(HandleStateChange) - TV: Attempting to change from None to 
WatchingPreRecorded
2012-12-09 16:07:42.777780 I [23925/23925] CoreContext 
fileringbuffer.cpp:190 (OpenFile) - 
FileRingBuf(myth://192.168.0.104:6543/56300_20121208205800.mpg): 
OpenFile(myth://192.168.0.104:6543/56300_20121208205800.mpg, 2000 ms)
2012-12-09 16:07:42.778972 I [23925/23925] CoreContext 
mythcorecontext.cpp:1178 (CheckProtoVersion) - Using protocol version 72
2012-12-09 16:07:42.780431 I [23925/23925] CoreContext 
mythcorecontext.cpp:1178 (CheckProtoVersion) - Using protocol version 72
2012-12-09 16:07:42.796290 I [23925/23925] CoreContext tv_play.cpp:5132 
(StartPlayer) - TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
2012-12-09 16:07:42.796315 I [23925/23925] CoreContext tv_play.cpp:5136 
(StartPlayer) - TV: Elapsed time since TV constructor was called: 49 ms
2012-12-09 16:07:42.980407 N [23925/23925] CoreContext 
audioplayer.cpp:167 (ReinitAudio) - AudioPlayer: Enabling Audio
2012-12-09 16:07:42.984835 I [23925/23925] CoreContext 
mythplayer.cpp:5101 (syncWithAudioStretch) - Player(3): Stretch Factor 
1, allow passthru
2012-12-09 16:07:42.985376 I [23925/23925] CoreContext 
avformatdecoder.cpp:814 (InitByteContext) - AFD: Buffer size: 32768, 
streamed 0
2012-12-09 16:07:50.945431 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #0, has id 0x901 
codec id H264, type Video, bitrate 0 at 0x8aac9e0
2012-12-09 16:07:50.963075 I [23925/23925] CoreContext 
videodisplayprofile.cpp:237 (VideoDisplayProfile) - VDP: Accepting: 
cmp(> 0 0) dec(vdpau) cpus(1) skiploop(disabled) rend(vdpau) osd(vdpau) 
osdfade(enabled) deint(vdpaubasicdoublerate,vdpauadvanced) 
filt(vdpaucolorspace=auto,vdpaustudio)
2012-12-09 16:07:50.963110 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 0)
2012-12-09 16:07:50.963156 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 60)
2012-12-09 16:07:50.963193 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(1920x1080, 60)
2012-12-09 16:07:50.963251 I [23925/23925] CoreContext 
avformatdecoder.cpp:1928 (ScanStreams) - AFD: Using 1 CPUs for decoding
2012-12-09 16:07:50.963277 I [23925/23925] CoreContext 
avformatdecoder.cpp:1253 (InitVideoCodec) - AFD: InitVideoCodec() 
0x8aacbd0 id(H264) type (Video).
2012-12-09 16:07:50.963347 I [23925/23925] CoreContext 
avformatdecoder.cpp:1165 (normalized_fps) - AFD: Selected FPS is 25 (avg 
77.4336 codec 25 container 90000 estimated 50)
2012-12-09 16:07:50.963372 I [23925/23925] CoreContext 
mythplayer.cpp:1705 (SetFrameInterval) - Player(3): SetFrameInterval 
ps:1 scan:3
2012-12-09 16:07:50.963412 I [23925/23925] CoreContext 
mythplayer.cpp:653 (detectInterlace) - Player(3): detectInterlace(Detect 
Scan, Interlaced Scan, 25, 1080) ->Interlaced Scan
2012-12-09 16:07:50.963470 I [23925/23925] CoreContext 
avformatdecoder.cpp:1941 (ScanStreams) - AFD: Using vdpau for video decoding
2012-12-09 16:07:50.963483 I [23925/23925] CoreContext 
avformatdecoder.cpp:2018 (ScanStreams) - AFD: Looking for decoder for H264
2012-12-09 16:07:50.963976 I [23925/23925] CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) - AFD: Opened codec 0x8aacbd0, 
id(H264) type(Video)
2012-12-09 16:07:50.964033 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #1, has id 0x903 
codec id MP2, type Audio, bitrate 192000 at 0x8aacfc0
2012-12-09 16:07:50.964051 I [23925/23925] CoreContext 
avformatdecoder.cpp:1960 (ScanStreams) - AFD: codec MP2 has 2 channels
2012-12-09 16:07:50.964063 I [23925/23925] CoreContext 
avformatdecoder.cpp:2018 (ScanStreams) - AFD: Looking for decoder for MP2
2012-12-09 16:07:50.964171 I [23925/23925] CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) - AFD: Opened codec 0x8ffca30, 
id(MP2) type(Audio)
2012-12-09 16:07:50.964291 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #2, has id 0x905 
codec id DVB_TELETEXT, type Subtitle, bitrate 0 at 0x8ffd010
2012-12-09 16:07:50.964354 I [23925/23925] CoreContext 
avformatdecoder.cpp:1636 (ScanTeletextCaptions) - AFD: Teletext stream 
#0 (Caption) is in the English language on page 8 136.
2012-12-09 16:07:50.964374 I [23925/23925] CoreContext 
avformatdecoder.cpp:1974 (ScanStreams) - AFD: subtitle codec (Subtitle)
2012-12-09 16:07:50.964398 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #3, has id 0x904 
codec id DVB_SUBTITLE, type Subtitle, bitrate 0 at 0x8960300
2012-12-09 16:07:50.964409 I [23925/23925] CoreContext 
avformatdecoder.cpp:1974 (ScanStreams) - AFD: subtitle codec (Subtitle)
2012-12-09 16:07:50.964420 I [23925/23925] CoreContext 
avformatdecoder.cpp:2018 (ScanStreams) - AFD: Looking for decoder for 
DVB_SUBTITLE
2012-12-09 16:07:50.964464 I [23925/23925] CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) - AFD: Opened codec 0x88a51c0, 
id(DVB_SUBTITLE) type(Subtitle)
2012-12-09 16:07:50.964489 I [23925/23925] CoreContext 
avformatdecoder.cpp:2120 (ScanStreams) - AFD: Subtitle track #1 is A/V 
stream #3 and is in the English language(6647399).
2012-12-09 16:07:50.964513 I [23925/23925] CoreContext 
avformatdecoder.cpp:1765 (ScanStreams) - AFD: Stream #4, has id 0x902 
codec id AC3, type Audio, bitrate 384000 at 0x88a5760
2012-12-09 16:07:50.964527 I [23925/23925] CoreContext 
avformatdecoder.cpp:1960 (ScanStreams) - AFD: codec AC3 has 2 channels
2012-12-09 16:07:50.964538 I [23925/23925] CoreContext 
avformatdecoder.cpp:2018 (ScanStreams) - AFD: Looking for decoder for AC3
2012-12-09 16:07:50.965200 I [23925/23925] CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) - AFD: Opened codec 0x9622d80, 
id(AC3) type(Audio)
2012-12-09 16:07:50.987423 I [23925/23925] CoreContext 
audio/audiooutputbase.cpp:791 (Reconfigure) - AO: Opening audio device 
'sysdefault:CARD=ICH5' ch 2(2) sr 48000 sf signed 32 bit reenc 0
2012-12-09 16:07:50.991657 E [23925/23925] CoreContext 
audio/audiooutputalsa.cpp:247 (IncPreallocBufferSize) - ALSA: Error 
opening /proc/asound/card0/pcm0p/sub0/prealloc. Fix reading permissions.
2012-12-09 16:07:50.991731 E [23925/23925] CoreContext 
audio/audiooutputalsa.cpp:545 (OpenDevice) - ALSA: Unable to 
sufficiently increase ALSA hardware buffer size - underruns are likely
2012-12-09 16:07:51.031261 I [23925/23925] CoreContext 
decoderbase.cpp:1001 (AutoSelectTrack) - Dec: Selected track #1 in the 
Unknown language(0)
2012-12-09 16:07:51.031292 I [23925/23925] CoreContext 
decoderbase.cpp:1001 (AutoSelectTrack) - Dec: Selected track #1 in the 
English language(6647399)
2012-12-09 16:07:51.031330 I [23925/23925] CoreContext 
decoderbase.cpp:1001 (AutoSelectTrack) - Dec: Selected track #1 in the 
English language(6647399)
2012-12-09 16:07:51.031352 I [23925/23925] CoreContext 
decoderbase.cpp:302 (SyncPositionMap) - Dec: Resyncing position map. 
posmapStarted = 0 livetv(0) watchingRec(0)
2012-12-09 16:07:51.115241 I [23925/23925] CoreContext 
decoderbase.cpp:211 (PosMapFromDb) - Dec: Position map filled from DB 
to: 211380
2012-12-09 16:07:51.117568 I [23925/23925] CoreContext 
decoderbase.cpp:345 (SyncPositionMap) - Dec: SyncPositionMap 
prerecorded, from DB: 7360 entries
2012-12-09 16:07:51.117628 I [23925/23925] CoreContext 
decoderbase.cpp:383 (SyncPositionMap) - Dec: SyncPositionMap, new 
totframes: 211380, new length: 8455, posMap size: 7360
2012-12-09 16:07:51.120437 I [23925/23925] CoreContext 
avformatdecoder.cpp:1081 (OpenFile) - AFD: Position map found
2012-12-09 16:07:51.120462 I [23925/23925] CoreContext 
avformatdecoder.cpp:1086 (OpenFile) - AFD: Successfully opened decoder 
for file: "myth://192.168.0.104:6543/56300_20121208205800.mpg". novideo(0)
2012-12-09 16:07:51.184899 I [23925/23925] CoreContext 
videooutbase.cpp:173 (Create) - VideoOutput: Allowed renderers: vdpau
2012-12-09 16:07:51.184950 I [23925/23925] CoreContext 
videooutbase.cpp:178 (Create) - VideoOutput: Allowed renderers (filt: 
vdpau): vdpau
2012-12-09 16:07:51.188013 I [23925/23925] CoreContext 
videodisplayprofile.cpp:237 (VideoDisplayProfile) - VDP: Accepting: 
cmp(> 0 0) dec(vdpau) cpus(1) skiploop(disabled) rend(vdpau) osd(vdpau) 
osdfade(enabled) deint(vdpaubasicdoublerate,vdpauadvanced) 
filt(vdpaucolorspace=auto,vdpaustudio)
2012-12-09 16:07:51.188046 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 0)
2012-12-09 16:07:51.188091 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 60)
2012-12-09 16:07:51.188128 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(1920x1080, 60)
2012-12-09 16:07:51.188159 I [23925/23925] CoreContext 
videooutbase.cpp:190 (Create) - VideoOutput: Preferred renderer: vdpau
2012-12-09 16:07:51.188184 I [23925/23925] CoreContext 
videooutbase.cpp:200 (Create) - VideoOutput: Trying video renderer: 'vdpau'
2012-12-09 16:07:51.194140 I [23925/23925] CoreContext 
videodisplayprofile.cpp:237 (VideoDisplayProfile) - VDP: Accepting: 
cmp(> 0 0) dec(vdpau) cpus(1) skiploop(disabled) rend(vdpau) osd(vdpau) 
osdfade(enabled) deint(vdpaubasicdoublerate,vdpauadvanced) 
filt(vdpaucolorspace=auto,vdpaustudio)
2012-12-09 16:07:51.194256 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 0)
2012-12-09 16:07:51.194307 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 60)
2012-12-09 16:07:51.194375 I [23925/23925] CoreContext 
videooutwindow.cpp:962 (SetPIPState) - VideoOutWindow::SetPIPState. 
pip_state: 0]
2012-12-09 16:07:51.194395 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(2048x2048, 25)
2012-12-09 16:07:51.194424 I [23925/23925] CoreContext 
mythpainter_ogl.cpp:62 (ClearCache) - Clearing OpenGL painter cache.
2012-12-09 16:07:51.231674 I [23925/23925] CoreContext 
videooutwindow.cpp:554 (PrintMoveResizeDebug) - Display Rect  left: 
-226, top: 0, width: 1813, height: 768, aspect: 1.33333
2012-12-09 16:07:51.231718 I [23925/23925] CoreContext 
videooutwindow.cpp:563 (PrintMoveResizeDebug) - Video Rect    left: 0, 
top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-12-09 16:07:51.231779 I [23925/23925] CoreContext 
videodisplayprofile.cpp:406 (LoadBestPreferences) - VDP: 
LoadBestPreferences(1920x1088, 25)
2012-12-09 16:07:51.231847 I [23925/23925] CoreContext 
videooutwindow.cpp:554 (PrintMoveResizeDebug) - Display Rect  left: 
-226, top: 0, width: 1813, height: 768, aspect: 1.33333
2012-12-09 16:07:51.231904 I [23925/23925] CoreContext 
videooutwindow.cpp:563 (PrintMoveResizeDebug) - Video Rect    left: 0, 
top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-12-09 16:07:51.231918 I [23925/23925] CoreContext 
videodisplayprofile.cpp:274 (SetVideoRenderer) - VDP: 
SetVideoRenderer(vdpau)
2012-12-09 16:07:51.231945 I [23925/23925] CoreContext 
videodisplayprofile.cpp:283 (SetVideoRenderer) - VDP: 
SetVideoRender(vdpau) == GetVideoRenderer()
2012-12-09 16:07:51.233852 I [23925/23925] CoreContext 
videooutbase.cpp:1774 (InitDisplayMeasurements) - VideoOutput: Pixel 
dimensions: Screen 1360x768, window 1360x768
2012-12-09 16:07:51.233911 I [23925/23925] CoreContext 
videooutbase.cpp:1807 (InitDisplayMeasurements) - VideoOutput: Actual 
display dimensions: 461x260 mm  Aspect: 1.77308
2012-12-09 16:07:51.233935 I [23925/23925] CoreContext 
videooutbase.cpp:1831 (InitDisplayMeasurements) - VideoOutput: Estimated 
window dimensions: 461x260 mm  Aspect: 1.77308
2012-12-09 16:07:51.408866 I [23925/23925] CoreContext 
mythrender_vdpau.cpp:1675 (CreatePresentationSurfaces) - VDPAU: Created 
2 output surfaces.
2012-12-09 16:07:51.408918 I [23925/23925] CoreContext 
mythrender_vdpau.cpp:439 (SetColorKey) - VDPAU: Set colorkey to 0x20202
2012-12-09 16:07:51.408969 I [23925/23925] CoreContext 
mythrender_vdpau.cpp:401 (Create) - VDPAU: Created VDPAU render device 
1360x768
2012-12-09 16:07:51.408994 I [23925/23925] CoreContext 
videoout_vdpau.cpp:140 (InitRender) - VidOutVDPAU: Created VDPAU osd 
(1360x768)
2012-12-09 16:07:52.108458 I [23925/23925] CoreContext 
videocolourspace.cpp:115 (SetSupportedAttributes) - ColourSpace: 
PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels,
2012-12-09 16:07:52.108484 I [23925/23925] CoreContext 
videoout_vdpau.cpp:879 (InitPictureAttributes) - VidOutVDPAU: Using ITU 
BT.709 colorspace
2012-12-09 16:07:52.108538 I [23925/23925] CoreContext 
videooutwindow.cpp:554 (PrintMoveResizeDebug) - Display Rect  left: 0, 
top: 0, width: 1360, height: 768, aspect: 1.77778
2012-12-09 16:07:52.108565 I [23925/23925] CoreContext 
videooutwindow.cpp:563 (PrintMoveResizeDebug) - Video Rect    left: 0, 
top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-12-09 16:07:52.108578 I [23925/23925] CoreContext 
videoout_vdpau.cpp:119 (Init) - VidOutVDPAU: Created VDPAU context (GPU 
decode)
2012-12-09 16:07:52.108621 I [23925/23925] CoreContext 
videooutwindow.cpp:665 (SetVideoScalingAllowed) - Over/underscan. V: 0, H: 0
2012-12-09 16:07:52.108648 I [23925/23925] CoreContext 
videooutwindow.cpp:554 (PrintMoveResizeDebug) - Display Rect  left: 0, 
top: 0, width: 1360, height: 768, aspect: 1.77778
2012-12-09 16:07:52.108675 I [23925/23925] CoreContext 
videooutwindow.cpp:563 (PrintMoveResizeDebug) - Video Rect    left: 0, 
top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-12-09 16:07:52.108696 N [23925/23925] CoreContext 
mythplayer.cpp:506 (CheckExtraAudioDecode) - Player(3): Forcing decode 
extra audio option on (Video method requires it).
2012-12-09 16:07:52.108740 I [23925/23925] CoreContext 
filtermanager.cpp:190 (GetFilterInfo) - FilterManager: 
GetFilterInfo(convert) returning: 0x0
2012-12-09 16:07:52.108788 I [23925/23925] CoreContext 
mythplayer.cpp:1066 (InitFilters) - Player(3): 
LoadFilters('vdpaucolorspace=auto,vdpaustudio'..) -> 0x0
2012-12-09 16:07:52.111759 I [23925/25477] Decoder mythplayer.cpp:94 
(run) - Player(3): Decoder thread starting.
2012-12-09 16:07:52.111798 I [23925/25477] Decoder 
avformatdecoder.cpp:540 (DoFastForward) - AFD: DoFastForward(3944 (0), 
do discard frames)
2012-12-09 16:07:52.111817 I [23925/25477] Decoder decoderbase.cpp:645 
(DoFastForward) - Dec: DoFastForward(3944 (0), do discard frames)
2012-12-09 16:07:52.111850 I [23925/25477] Decoder decoderbase.cpp:422 
(FindPosition) - Dec: FindPosition(3944, search not adjusted) --> 
[144:3944(199406524)]
2012-12-09 16:07:52.112900 I [23925/25477] Decoder 
avformatdecoder.cpp:661 (SeekReset) - AFD: SeekReset(3944, 0, do flush, 
do discard)
2012-12-09 16:07:52.113381 I [23925/25477] Decoder 
avformatdecoder.cpp:689 (SeekReset) - AFD: SeekReset() flushing
2012-12-09 16:07:52.113482 I [23925/25477] Decoder 
videoout_vdpau.cpp:993 (DiscardFrames) - VidOutVDPAU: DiscardFrames(1)
2012-12-09 16:07:52.113541 I [23925/25477] Decoder videobuffers.cpp:616 
(DiscardFrames) - VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA
2012-12-09 16:07:52.113586 I [23925/25477] Decoder videobuffers.cpp:673 
(DiscardFrames) - VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA -- done
2012-12-09 16:07:52.113625 I [23925/25477] Decoder 
videoout_vdpau.cpp:998 (DiscardFrames) - VidOutVDPAU: DiscardFrames() 3: 
AAAAAAAAAAAAAA -- done()
2012-12-09 16:07:52.113706 I [23925/23925] CoreContext 
mythplayer.cpp:3733 (ClearAfterSeek) - Player(3): ClearAfterSeek(0)
2012-12-09 16:07:52.136611 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window osd_message
2012-12-09 16:07:52.164225 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window osd_input
2012-12-09 16:07:52.222020 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window program_info
2012-12-09 16:07:52.260497 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window browse_info
2012-12-09 16:07:52.286565 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window osd_status
2012-12-09 16:07:52.312928 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window osd_program_editor
2012-12-09 16:07:52.361165 I [23925/23925] CoreContext osd.cpp:314 
(LoadWindows) - OSD: Loaded window osd_debug
2012-12-09 16:07:52.361367 I [23925/23925] CoreContext osd.cpp:194 
(Init) - OSD: Loaded OSD: size 1360x768 offset 0+0
2012-12-09 16:07:52.361600 I [23925/23925] CoreContext osd.cpp:1166 
(InitSubtitles) - OSD: Created window aa_OSD_SUBTITLES
2012-12-09 16:07:52.361703 I [23925/23925] CoreContext 
mythplayer.cpp:3733 (ClearAfterSeek) - Player(3): ClearAfterSeek(0)
2012-12-09 16:07:52.363581 I [23925/23925] CoreContext 
videodisplayprofile.cpp:354 (GetFilteredDeint) - VDP: GetFilteredDeint() 
: vdpau -> 'vdpaubasicdoublerate'
2012-12-09 16:07:52.365663 I [23925/23925] CoreContext 
videoout_vdpau.cpp:346 (SetupDeinterlace) - VidOutVDPAU: Enabled 
deinterlacing.
2012-12-09 16:07:52.365782 I [23925/23925] CoreContext vsync.cpp:265 
(TryInit) - VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, 
No such file or directory
2012-12-09 16:07:52.365825 E [23925/23925] CoreContext vsync.cpp:356 
(TryInit) - VSYNC: RTCVideoSync: Could not open /dev/rtc:
			eno: Permission denied (13)
2012-12-09 16:07:52.367742 I [23925/23925] CoreContext 
mythplayer.cpp:1737 (InitAVSync) - Player(3): Video timing method: 
USleep with busy wait
2012-12-09 16:07:52.367796 I [23925/23925] CoreContext 
mythplayer.cpp:1741 (InitAVSync) - Player(3): Display Refresh Rate: 
60.017 Video Frame Rate: 25.000
2012-12-09 16:07:52.367853 I [23925/23925] CoreContext 
mythplayer.cpp:1705 (SetFrameInterval) - Player(3): SetFrameInterval 
ps:1 scan:1
2012-12-09 16:07:52.371785 I [23925/23925] CoreContext 
playercontext.cpp:469 (StartPlaying) - playCtx: StartPlaying(): took 0 
ms to start player.
2012-12-09 16:07:52.371831 I [23925/23925] CoreContext tv_play.cpp:5169 
(StartPlayer) - TV: Created player.
2012-12-09 16:07:52.371875 I [23925/23925] CoreContext tv_play.cpp:5176 
(StartPlayer) - TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
2012-12-09 16:07:52.376477 I [23925/23925] CoreContext tv_play.cpp:2360 
(HandleStateChange) - TV: Changing from None to WatchingPreRecorded
2012-12-09 16:07:52.410106 I [23925/23925] CoreContext tv_play.cpp:8015 
(DrawUnusedRects) - TV: DrawUnusedRects() -- begin
2012-12-09 16:07:52.410137 I [23925/23925] CoreContext tv_play.cpp:8028 
(DrawUnusedRects) - TV: DrawUnusedRects() -- end
2012-12-09 16:07:52.410267 I [23925/23925] CoreContext tv_play.cpp:2451 
(HandleStateChange) - TV: Main UI disabled.
2012-12-09 16:07:52.410290 I [23925/23925] CoreContext tv_play.cpp:2456 
(HandleStateChange) - TV: HandleStateChange(0) -- end
2012-12-09 16:07:52.410303 I [23925/23925] CoreContext tv_play.cpp:343 
(StartTV) - TV: tv->Playback() -- end
2012-12-09 16:07:52.410328 I [23925/23925] CoreContext tv_play.cpp:378 
(StartTV) - TV: Entering main playback loop.
2012-12-09 16:07:52.522114 I [23925/23925] CoreContext 
mythplayer.cpp:2049 (SetBuffering) - Player(3): Waiting for video buffers...
2012-12-09 16:07:52.562182 I [23925/25477] Decoder 
videoout_vdpau.cpp:627 (DrawSlice) - VidOutVDPAU: Added 2 new buffers. 
New buffer size 16 (4 decode and 12 process)
2012-12-09 16:07:52.621888 I [23925/25477] Decoder 
videoout_vdpau.cpp:666 (DrawSlice) - VidOutVDPAU: Created VDPAU decoder 
(4 ref frames)
2012-12-09 16:07:52.624296 N [23925/23925] CoreContext 
mythplayer.cpp:2078 (PrebufferEnoughFrames) - Player(3): Waited 102ms 
for video buffers AAAAAAALLLLuAAAA
2012-12-09 16:07:52.624518 I [23925/23925] CoreContext 
mythplayer.cpp:2123 (CheckAspectRatio) - Player(3): Video Aspect ratio 
changed from 1.33333 to 1.77778
2012-12-09 16:07:52.624564 I [23925/23925] CoreContext 
videooutwindow.cpp:554 (PrintMoveResizeDebug) - Display Rect  left: 0, 
top: 0, width: 1360, height: 768, aspect: 1.77778
2012-12-09 16:07:52.624591 I [23925/23925] CoreContext 
videooutwindow.cpp:563 (PrintMoveResizeDebug) - Video Rect    left: 0, 
top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-12-09 16:07:52.624638 I [23925/23925] CoreContext 
mythplayer.cpp:778 (SetScanType) - Player(3): Enabled deinterlacing
2012-12-09 16:07:52.629528 I [23925/23925] CoreContext 
mythrender_vdpau.cpp:587 (CheckOutputSurfaces) - VDPAU: Added 2 output 
surfaces (total 4, max 4)
2012-12-09 16:07:52.671116 I [23925/23925] CoreContext 
mythplayer.cpp:2049 (SetBuffering) - Player(3): Waiting for video buffers...
2012-12-09 16:07:53.384980 I [23925/23925] CoreContext tv_play.cpp:7462 
(UpdateOSDSeekMessage) - UpdateOSDSeekMessage(Paused, 0)
2012-12-09 16:07:53.385920 I [23925/23925] CoreContext 
mythplayer.cpp:3450 (ChangeSpeed) - Player(3): Play speed: rate: 25 
speed: 0 skip: 0 => new interval 40000
2012-12-09 16:07:53.387454 I [23925/23925] CoreContext 
videodisplayprofile.cpp:354 (GetFilteredDeint) - VDP: 
GetFilteredDeint(vdpauadvanced) : vdpau -> 'vdpauadvanced'
2012-12-09 16:07:53.389064 I [23925/23925] CoreContext 
videoout_vdpau.cpp:346 (SetupDeinterlace) - VidOutVDPAU: Enabled 
deinterlacing.
2012-12-09 16:07:53.429219 I [23925/23925] CoreContext 
videoout_vdpau.cpp:825 (UpdatePauseFrame) - VidOutVDPAU: 
UpdatePauseFrame() LUUAUUuuLDDFDUUu


More information about the mythtv-users mailing list