Ticket #6922: report.txt

File report.txt, 29.4 KB (added by dmndmn@…, 14 years ago)

mythfrontend log - atom-ION-vdpau 720p jitter but not many buffer messages

Line 
12009-10-24 12:20:45.915 mythfrontend version: trunk [22582] www.mythtv.org
22009-10-24 12:20:45.970 Using runtime prefix = /usr/local
32009-10-24 12:20:45.971 Using configuration directory = /root/.mythtv
42009-10-24 12:20:46.762 Empty LocalHostName.
52009-10-24 12:20:46.763 Using localhost value of linux-8wqa
62009-10-24 12:20:46.783 New DB connection, total: 1
72009-10-24 12:20:46.791 Connected to database 'mythconverg' at host: localhost
82009-10-24 12:20:46.793 Closing DB connection named 'DBManager0'
92009-10-24 12:20:46.820 DPMS is disabled.
102009-10-24 12:20:46.825 Primary screen: 0.
112009-10-24 12:20:46.826 Connected to database 'mythconverg' at host: localhost
122009-10-24 12:20:46.829 Using screen 0, 1920x1080 at 0,0
132009-10-24 12:20:46.980 MythUI Image Cache size set to 20971520 bytes
142009-10-24 12:20:46.981 user: 0 effective user: 0 before privileged thread
152009-10-24 12:20:46.981 user: 0 effective user: 0 after privileged thread
162009-10-24 12:20:46.981 user: 0 effective user: 0 run_priv_thread
172009-10-24 12:20:46.982 Enabled verbose msgs:  important general playback
182009-10-24 12:20:46.996 Primary screen: 0.
192009-10-24 12:20:46.998 Using screen 0, 1920x1080 at 0,0
202009-10-24 12:20:47.001 Using theme base resolution of 800x600
212009-10-24 12:20:47.022 LIRC: Successfully initialized '/dev/lircd' using '/root/.mythtv/lircrc' config
222009-10-24 12:20:47.222 Using the Qt painter
232009-10-24 12:20:47.232 Theme error: Unknown tag in font
24Type: 'size:small'
25Name: ''
26Line: 11
272009-10-24 12:20:47.232 Theme error: Specified base font 'small' does not exist for font medium
28Type: 'font'
29Name: 'medium'
30Line: 16
312009-10-24 12:20:47.232 Theme error: Specified base font 'small' does not exist for font large
32Type: 'font'
33Name: 'large'
34Line: 22
352009-10-24 12:20:47.234 Loaded base theme from /usr/local/share/mythtv/themes/G.A.N.T/base.xml
362009-10-24 12:20:47.800 Loaded base theme from /usr/local/share/mythtv/themes/default/base.xml
372009-10-24 12:20:47.819 Current MythTV Schema Version (DBSchemaVer): 1244
382009-10-24 12:20:48.231 Desktop video mode: 1920x1080 59.9341 Hz
392009-10-24 12:20:48.290 max_width: 1920 max_height: 1080
402009-10-24 12:20:48.686 Registering Internal as a media playback plugin.
412009-10-24 12:20:48.757 Registering WebBrowser as a media playback plugin.
422009-10-24 12:20:48.845 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
43                        eno: No such file or directory (2)
442009-10-24 12:20:48.856 MMUnix::AddDevice() Error: failed to stat /dev/power,
45                        eno: No such file or directory (2)
462009-10-24 12:20:48.869 MMUnix::AddDevice() Error: failed to stat /dev/trace,
47                        eno: No such file or directory (2)
482009-10-24 12:20:48.878 MonitorRegisterExtensions(0x100, gif,jpg,png)
492009-10-24 12:20:48.988 Current MythMusic Schema Version (MusicDBSchemaVer): 1017
502009-10-24 12:20:49.102 MonitorRegisterExtensions(0x40, mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
512009-10-24 12:20:49.146 Current MythVideo Schema Version (mythvideo.DBSchemaVer): 1028
522009-10-24 12:20:49.287 Loading window theme from /usr/local/share/mythtv/themes/G.A.N.T/menu-ui.xml
532009-10-24 12:20:49.298 Theme error: Specified base font 'small' does not exist for font clock
54Type: 'font'
55Name: 'clock'
56Line: 17
572009-10-24 12:20:49.662 Loading menu theme from /usr/local/share/mythtv/themes/defaultmenu//mainmenu.xml
582009-10-24 12:20:49.667 Found mainmenu.xml for theme 'G.A.N.T'
592009-10-24 12:20:49.848 MythContext: Connecting to backend server: 10.0.0.28:6543 (try 1 of 1)
602009-10-24 12:20:49.849 Using protocol version 50
612009-10-24 12:20:52.207 TV: StartTV() -- begin
622009-10-24 12:20:52.208 TV: ctor
632009-10-24 12:20:52.259 New DB connection, total: 2
642009-10-24 12:20:52.260 Connected to database 'mythconverg' at host: localhost
652009-10-24 12:20:52.366 TV: DrawUnusedRects() -- begin
662009-10-24 12:20:52.367 TV: DrawUnusedRects() -- end
672009-10-24 12:20:52.367 TV: DrawUnusedRects() -- begin
682009-10-24 12:20:52.367 TV: DrawUnusedRects() -- end
692009-10-24 12:20:52.399 TV: tv->LiveTV() -- begin
702009-10-24 12:20:52.407 TV: tv->LiveTV() -- end
712009-10-24 12:20:52.407 TV: StartTV -- process events begin
722009-10-24 12:20:52.448 TV: HandleStateChange(0) -- begin
732009-10-24 12:20:52.448 TV: Attempting to change from None to Watching WatchingLiveTV
742009-10-24 12:20:52.452 MythContext: Connecting to backend server: 10.0.0.28:6543 (try 1 of 1)
752009-10-24 12:20:52.453 Using protocol version 50
762009-10-24 12:20:52.454 Spawning LiveTV Recorder -- begin
772009-10-24 12:20:53.105 Spawning LiveTV Recorder -- end
782009-10-24 12:20:53.107 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): ReloadAll(): Added new recording
792009-10-24 12:20:53.128 We have a playbackURL(/mythtv/12100_20091024122052.mpg) & cardtype(DUMMY)
802009-10-24 12:20:53.128 We have a RingBuffer
812009-10-24 12:20:53.180 TV: StartRecorder(): took 1 ms to start recorder.
822009-10-24 12:20:53.181 TV: StartPlayer(0, Watching WatchingLiveTV, main) -- begin
832009-10-24 12:20:53.232 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
842009-10-24 12:20:53.238 NVP(0): Disabling Audio, params(-1,2,44100)
852009-10-24 12:20:53.242 VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,vdpau
862009-10-24 12:20:53.243 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,vdpau
872009-10-24 12:20:53.247 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
882009-10-24 12:20:53.248 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
892009-10-24 12:20:53.248 VDP: LoadBestPreferences(2048x2048, 0)
902009-10-24 12:20:53.248 VDP: LoadBestPreferences(2048x2048, 60)
912009-10-24 12:20:53.248 VDP: LoadBestPreferences(720x576, 60)
922009-10-24 12:20:53.249 VideoOutput: Preferred renderer: vdpau
932009-10-24 12:20:53.249 VideoOutput: Trying video renderer: 'vdpau'
942009-10-24 12:20:53.270 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
952009-10-24 12:20:53.271 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
962009-10-24 12:20:53.271 VDP: LoadBestPreferences(2048x2048, 0)
972009-10-24 12:20:53.271 VDP: LoadBestPreferences(2048x2048, 60)
982009-10-24 12:20:53.283 VideoOutWindow::SetPIPState. pip_state: 0]
992009-10-24 12:20:53.283 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
1002009-10-24 12:20:53.283 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
1012009-10-24 12:20:53.283 VDP: LoadBestPreferences(720x576, 60)
1022009-10-24 12:20:53.283 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
1032009-10-24 12:20:53.284 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
1042009-10-24 12:20:53.284 VDP: SetVideoRenderer(vdpau)
1052009-10-24 12:20:53.284 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
1062009-10-24 12:20:53.286 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24)
1072009-10-24 12:20:53.290 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
1082009-10-24 12:20:53.290 VideoOutput: Actual display dimensions: 524x323 mm  Aspect: 1.62229
1092009-10-24 12:20:53.291 VideoOutput: Estimated window dimensions: 524x323 mm  Aspect: 1.62229
1102009-10-24 12:20:53.332 VDPAU: Version 0
1112009-10-24 12:20:53.333 VDPAU: Information NVIDIA VDPAU Driver Shared Library  185.18.36  Fri Aug 14 17:50:51 PDT 2009
1122009-10-24 12:20:53.360 VidOutVDPAU: VDPAU video buffer size: 22 (default 17)
1132009-10-24 12:20:53.433 Display Rect  left: 171, top: 0, width: 1578, height: 1080, aspect: 1.62229
1142009-10-24 12:20:53.433 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
1152009-10-24 12:20:53.433 VidOutVDPAU: Created VDPAU context (software decode)
1162009-10-24 12:20:53.513 VDPAU: Created OSD (1920x1080)
1172009-10-24 12:20:53.519 Over/underscan. V: 0, H: 0
1182009-10-24 12:20:53.519 Display Rect  left: 171, top: 0, width: 1578, height: 1080, aspect: 1.62229
1192009-10-24 12:20:53.519 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
1202009-10-24 12:20:53.520 VDP: LoadBestPreferences(720x576, 25)
1212009-10-24 12:20:53.522 FilterManager: GetFilterInfo(convert) returning: 0x0
1222009-10-24 12:20:53.522 NVP(0): LoadFilters('vdpaubuffersize=22'..) -> 0x0
1232009-10-24 12:20:53.525 OSD Theme Dimensions W: 640 H: 480
1242009-10-24 12:20:53.570 New DB connection, total: 3
1252009-10-24 12:20:53.572 Connected to database 'mythconverg' at host: localhost
1262009-10-24 12:20:54.400 NVP(0): ClearAfterSeek(1)
1272009-10-24 12:20:54.400 playCtx: StartDecoderThread(): took 1162 ms to start player.
1282009-10-24 12:20:54.401 VidOutVDPAU: ClearAfterSeek()
1292009-10-24 12:20:54.401 VidOutVDPAU: DiscardFrames(0)
1302009-10-24 12:20:54.401 TV: StartPlayer(0, Watching WatchingLiveTV, main) -- end ok
1312009-10-24 12:20:54.401 TV: Changing from None to Watching WatchingLiveTV
1322009-10-24 12:20:54.402 TV: State is LiveTV & mctx == ctx
1332009-10-24 12:20:54.401 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA
1342009-10-24 12:20:54.404 TV: UpdateOSDInput done
1352009-10-24 12:20:54.404 TV: UpdateLCD done
1362009-10-24 12:20:54.404 TV: ITVRestart done
1372009-10-24 12:20:54.403 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA -- done
1382009-10-24 12:20:54.405 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA -- done()
1392009-10-24 12:20:54.409 The realtime priority setting is not enabled.
1402009-10-24 12:20:54.412 NVP(0): DoPause() -- begin
1412009-10-24 12:20:54.413 rate: 25 speed: 1 skip: 1 = interval 40000
1422009-10-24 12:20:54.413 NVP(0): DoPause() -- setting paused
1432009-10-24 12:20:54.413 VDP: GetFilteredDeint() : vdpau -> 'vdpauadvanced'
1442009-10-24 12:20:54.450 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
1452009-10-24 12:20:54.450 OpenGLVideoSync()
1462009-10-24 12:20:54.466 TV: HandleStateChange(0) -- end
1472009-10-24 12:20:54.476 Forcing GLX version to 1.2 (orig 1.3)
1482009-10-24 12:20:54.502 GLCtx: Created OpenGL window.
1492009-10-24 12:20:54.519 TV: DrawUnusedRects() -- begin
1502009-10-24 12:20:54.552 TV: DrawUnusedRects() -- end
1512009-10-24 12:20:54.552 GLCtx: Created GLX context.
1522009-10-24 12:20:54.553 GLCtx: GLX Version: 1.2
1532009-10-24 12:20:54.553 GLCtx: Direct rendering: Yes
1542009-10-24 12:20:54.596 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): ReloadAll(): Added new recording
1552009-10-24 12:20:54.597 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): SwitchTo(1)
1562009-10-24 12:20:54.597 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): Entry@1: '12100_20091024122054'
1572009-10-24 12:20:54.597 JumpToProgram(void)
1582009-10-24 12:20:54.638 FilterManager: GetFilterInfo(convert) returning: 0x0
1592009-10-24 12:20:54.639 NVP(0): LoadFilters('vdpaubuffersize=22'..) -> 0x0
1602009-10-24 12:20:54.644 RingBuf(/mythtv/12100_20091024122052.mpg): OpenFile(/mythtv/12100_20091024122054.mpg, 12)
1612009-10-24 12:20:54.647 GLCtx: OpenGL vendor  : NVIDIA Corporation
1622009-10-24 12:20:54.648 GLCtx: OpenGL renderer: ION/PCI/SSE2
1632009-10-24 12:20:54.648 GLCtx: OpenGL version : 3.0.0 NVIDIA 185.18.36
1642009-10-24 12:20:54.648 GLCtx: Max texture size: 8192 x 8192
1652009-10-24 12:20:54.649 GLCtx: Max texture units: 4
1662009-10-24 12:20:54.658 RingBuf(/mythtv/12100_20091024122054.mpg): CalcReadAheadThresh(0 KB)
167                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
1682009-10-24 12:20:54.659 Using audio as timebase
1692009-10-24 12:20:54.659 Video timing method: SGI OpenGL
1702009-10-24 12:20:54.659 Refresh rate: 16685, frame interval: 40000
1712009-10-24 12:20:55.211 VDPAU: Using 4 output surfaces (max 4)
1722009-10-24 12:20:55.273 [h264 @ 0xb66b9708]mmco: unref short failure
1732009-10-24 12:20:55.273 [h264 @ 0xb66b9708]mmco: unref short failure
1742009-10-24 12:20:55.573 AFD: Stream #0, has id 0x177a codec id H264, type Video, bitrate 0 at 0x89a54b0
1752009-10-24 12:20:55.578 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
1762009-10-24 12:20:55.579 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
1772009-10-24 12:20:55.579 VDP: LoadBestPreferences(2048x2048, 0)
1782009-10-24 12:20:55.579 VDP: LoadBestPreferences(2048x2048, 60)
1792009-10-24 12:20:55.580 VDP: LoadBestPreferences(1280x720, 60)
1802009-10-24 12:20:55.584 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
1812009-10-24 12:20:55.584 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
1822009-10-24 12:20:55.585 VDP: LoadBestPreferences(2048x2048, 0)
1832009-10-24 12:20:55.585 VDP: LoadBestPreferences(2048x2048, 60)
1842009-10-24 12:20:55.585 VDP: LoadBestPreferences(1280x720, 60)
1852009-10-24 12:20:55.585 Using 1 CPUs for decoding
1862009-10-24 12:20:55.585 AFD: InitVideoCodec() 0x89dc0f0 id(H264) type (Video).
1872009-10-24 12:20:55.590 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
1882009-10-24 12:20:55.590 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
1892009-10-24 12:20:55.590 VDP: LoadBestPreferences(2048x2048, 0)
1902009-10-24 12:20:55.590 VDP: LoadBestPreferences(2048x2048, 60)
1912009-10-24 12:20:55.591 VDP: LoadBestPreferences(1280x720, 60)
1922009-10-24 12:20:55.591 VidOutVDPAU: InputChanged(1280,720,1.77778) 'None'->'H.264 VDPAU'
1932009-10-24 12:20:55.591 VidOutVDPAU: DiscardFrames(1)
1942009-10-24 12:20:55.592 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAA
1952009-10-24 12:20:55.592 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAA -- done()
1962009-10-24 12:20:55.593 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAA -- done
1972009-10-24 12:20:55.593 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA -- done()
1982009-10-24 12:20:55.761 Display Rect  left: 0, top: 47, width: 1920, height: 986, aspect: 1.62229
1992009-10-24 12:20:55.762 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2002009-10-24 12:20:55.762 VDP: LoadBestPreferences(1280x720, 25)
2012009-10-24 12:20:55.762 Display Rect  left: 0, top: 47, width: 1920, height: 986, aspect: 1.62229
2022009-10-24 12:20:55.762 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2032009-10-24 12:20:55.762 VDP: SetVideoRenderer(vdpau)
2042009-10-24 12:20:55.763 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2052009-10-24 12:20:55.769 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24)
2062009-10-24 12:20:55.774 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2072009-10-24 12:20:55.774 VideoOutput: Actual display dimensions: 524x323 mm  Aspect: 1.62229
2082009-10-24 12:20:55.774 VideoOutput: Estimated window dimensions: 524x323 mm  Aspect: 1.62229
2092009-10-24 12:20:55.833 VidOutVDPAU: VDPAU video buffer size: 32 (default 17)
2102009-10-24 12:20:56.523 Display Rect  left: 0, top: 47, width: 1920, height: 986, aspect: 1.62229
2112009-10-24 12:20:56.523 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2122009-10-24 12:20:56.523 VidOutVDPAU: Created VDPAU context (GPU decode)
2132009-10-24 12:20:56.624 VDPAU: Created OSD (1920x1080)
2142009-10-24 12:20:56.625 VDP: GetFilteredDeint() : vdpau -> 'none'
2152009-10-24 12:20:56.625 VDP: GetFilteredDeint() : vdpau -> 'none'
2162009-10-24 12:20:56.625 VDP: LoadBestPreferences(1280x720, 50)
2172009-10-24 12:20:56.625 NVP(0): Forcing decode extra audio option on (Video method requires it).
2182009-10-24 12:20:57.055 NVP(0): ClearAfterSeek(1)
2192009-10-24 12:20:57.055 VidOutVDPAU: ClearAfterSeek()
2202009-10-24 12:20:57.056 VidOutVDPAU: DiscardFrames(0)
2212009-10-24 12:20:57.058 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA         
2222009-10-24 12:20:57.058 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA           -- done
2232009-10-24 12:20:57.059 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA           -- done()
2242009-10-24 12:20:57.059 FilterManager: GetFilterInfo(convert) returning: 0x0
2252009-10-24 12:20:57.060 NVP(0): LoadFilters('vdpaubuffersize=32'..) -> 0x0
2262009-10-24 12:20:57.060 detectInterlace(Detect Scan, Interlaced Scan, 50, 720) ->Progressive Scan
2272009-10-24 12:20:57.060 VDP: GetFilteredDeint() : vdpau -> 'none'
2282009-10-24 12:20:57.060 Disabled deinterlacing
2292009-10-24 12:20:57.061 AFD: Using vdpau for video decoding
2302009-10-24 12:20:57.061 AFD: Looking for decoder for H264
2312009-10-24 12:20:57.062 AFD: Opened codec 0x89dc0f0, id(H264) type(Video)
2322009-10-24 12:20:57.063 AFD: Stream #1, has id 0x1784 codec id MP2, type Audio, bitrate 256000 at 0x89a99c0
2332009-10-24 12:20:57.063 AFD: codec MP2 has 2 channels
2342009-10-24 12:20:57.063 AFD: Looking for decoder for MP2
2352009-10-24 12:20:57.063 AFD: Opened codec 0x94fcbb0, id(MP2) type(Audio)
2362009-10-24 12:20:57.066 AFD: Stream #2, has id 0x1786 codec id AC3, type Audio, bitrate 192000 at 0x89a9020
2372009-10-24 12:20:57.066 AFD: codec AC3 has 2 channels
2382009-10-24 12:20:57.066 AFD: Looking for decoder for AC3
2392009-10-24 12:20:57.068 AFD: Opened codec 0x94fcf70, id(AC3) type(Audio)
2402009-10-24 12:20:57.068 AFD: Stream #3, has id 0x178e codec id DVB_VBI, type Data, bitrate 0 at 0x89d4f80
2412009-10-24 12:20:57.069 AFD: data codec (Data)
2422009-10-24 12:20:57.184 RingBuf(/mythtv/12100_20091024122054.mpg): CalcReadAheadThresh(0 KB)
243                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2442009-10-24 12:20:57.218 Opening audio device 'default'. ch 2(2) sr 48000 (reenc 0)
2452009-10-24 12:20:57.219 Opening ALSA audio device 'default'.
2462009-10-24 12:20:57.234 Mixer unable to find control Master
2472009-10-24 12:20:57.234 Mixer unable to find control Master
2482009-10-24 12:20:57.236 NVP(0): Enabling Audio
2492009-10-24 12:20:57.237 Dec: Trying to select track (w/lang)
2502009-10-24 12:20:57.237 Dec: Selecting first track
2512009-10-24 12:20:57.237 Dec: Selected track #1 in the Unknown language(0)
2522009-10-24 12:20:57.237 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2532009-10-24 12:20:57.240 Position map filled from DB to: 0
2542009-10-24 12:20:57.240 Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2552009-10-24 12:20:57.241 NVP(0): Filling position map from 1 to end
2562009-10-24 12:20:57.242 Dec: Position map filled from Encoder to: 96
2572009-10-24 12:20:57.242 Dec: SyncPositionMap watchingrecording total: 4 entries
2582009-10-24 12:20:57.243 Dec: SyncPositionMap, new totframes: 96, new length: 1, posMap size: 4
2592009-10-24 12:20:57.243 AFD: Partial position map found
2602009-10-24 12:20:57.243 AFD: Successfully opened decoder for file: "/mythtv/12100_20091024122054.mpg". novideo(0)
2612009-10-24 12:20:57.249 NVP(0): DoPlay() -- begin
2622009-10-24 12:20:57.249 NVP(0): DoPlay: rate: 50 speed: 1 skip: 1 => new interval 20000
2632009-10-24 12:20:57.250 Set video sync frame interval to 20000
2642009-10-24 12:20:57.250 NVP(0): Stretch Factor 1, allow passthru
2652009-10-24 12:20:57.250 NVP(0): DoPlay() -- setting unpaused
2662009-10-24 12:20:57.257 NVP(0): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAAAAAAA         
2672009-10-24 12:20:57.307 RingBuf(/mythtv/12100_20091024122054.mpg): CalcReadAheadThresh(0 KB)
268                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2692009-10-24 12:20:57.307 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2702009-10-24 12:20:57.308 Position map filled from DB to: 0
2712009-10-24 12:20:57.309 Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2722009-10-24 12:20:57.309 NVP(0): Filling position map from 1 to end
2732009-10-24 12:20:57.311 Dec: Position map filled from Encoder to: 96
2742009-10-24 12:20:57.311 Dec: SyncPositionMap watchingrecording total: 4 entries
2752009-10-24 12:20:57.317 [h264_vdpau @ 0xb66b9708]mmco: unref short failure
2762009-10-24 12:20:57.317 [h264_vdpau @ 0xb66b9708]mmco: unref short failure
2772009-10-24 12:20:57.401 NVP(0): Waiting for prebuffer..  1 (AL)AAAAAAAAAAAAAAAAAAAAA         
2782009-10-24 12:20:57.500 NVP(0): Waiting for prebuffer..  2 (AL)AAAAAAAAAAAAAAAAAAAAA         
2792009-10-24 12:20:57.541 VDPAU: Created VDPAU decoder (5 ref frames)
2802009-10-24 12:20:57.556 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2812009-10-24 12:20:57.557 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2822009-10-24 12:20:57.557 [h264_vdpau @ 0xb66b9708]no frame!
2832009-10-24 12:20:57.557 AFD Error: Unknown decoding error
2842009-10-24 12:20:57.558 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2852009-10-24 12:20:57.558 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2862009-10-24 12:20:57.558 [h264_vdpau @ 0xb66b9708]no frame!
2872009-10-24 12:20:57.558 AFD Error: Unknown decoding error
2882009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2892009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2902009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]no frame!
2912009-10-24 12:20:57.559 AFD Error: Unknown decoding error
2922009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2932009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2942009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]no frame!
2952009-10-24 12:20:57.560 AFD Error: Unknown decoding error
2962009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2972009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2982009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]no frame!
2992009-10-24 12:20:57.560 AFD Error: Unknown decoding error
3002009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3012009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3022009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]no frame!
3032009-10-24 12:20:57.561 AFD Error: Unknown decoding error
3042009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3052009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3062009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]no frame!
3072009-10-24 12:20:57.562 AFD Error: Unknown decoding error
3082009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3092009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3102009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]no frame!
3112009-10-24 12:20:57.562 AFD Error: Unknown decoding error
3122009-10-24 12:20:57.563 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3132009-10-24 12:20:57.563 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3142009-10-24 12:20:57.563 [h264_vdpau @ 0xb66b9708]no frame!
3152009-10-24 12:20:57.563 AFD Error: Unknown decoding error
3162009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3172009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3182009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]no frame!
3192009-10-24 12:20:57.564 AFD Error: Unknown decoding error
3202009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3212009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3222009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]no frame!
3232009-10-24 12:20:57.565 AFD Error: Unknown decoding error
3242009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3252009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3262009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]no frame!
3272009-10-24 12:20:57.565 AFD Error: Unknown decoding error
3282009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3292009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3302009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]no frame!
3312009-10-24 12:20:57.566 AFD Error: Unknown decoding error
3322009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3332009-10-24 12:20:57.567 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3342009-10-24 12:20:57.567 [h264_vdpau @ 0xb66b9708]no frame!
3352009-10-24 12:20:57.567 AFD Error: Unknown decoding error
3362009-10-24 12:20:57.567 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
3372009-10-24 12:20:57.568 [h264_vdpau @ 0xb66b9708]decode_slice_header error
3382009-10-24 12:20:57.568 [h264_vdpau @ 0xb66b9708]no frame!
3392009-10-24 12:20:57.568 AFD Error: Unknown decoding error
3402009-10-24 12:20:57.579 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
3412009-10-24 12:20:57.582 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
3422009-10-24 12:20:57.583 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
3432009-10-24 12:20:57.584 [h264_vdpau @ 0xb66b9708]reference picture missing during reorder
3442009-10-24 12:20:57.584 [h264_vdpau @ 0xb66b9708]Missing reference picture
3452009-10-24 12:20:57.590 NVP(0): Waiting for prebuffer..  3 (au)AA(AL)(AL)(AL)(AL)(AL)(AL)AAAAAAAAAAAAA         
3462009-10-24 12:20:57.636 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
3472009-10-24 12:20:57.637 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
3482009-10-24 12:20:57.666 [h264_vdpau @ 0xb66b9708]mmco: unref short failure
3492009-10-24 12:20:57.682 NVP(0): prebuffering pause
3502009-10-24 12:20:57.683 NVP(0): Waiting for prebuffer..  0 AAAAAA(AL)A(AL)aLAAALLAAAAAA         
3512009-10-24 12:20:57.795 VDPAU: Using 4 output surfaces (max 4)
3522009-10-24 12:20:57.799 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
3532009-10-24 12:20:57.899 NVP(0): Video is 3.36255 frames ahead of audio,
354                        doubling video frame interval to slow down.
3552009-10-24 12:20:57.931 NVP(0): Video is 3.3094 frames ahead of audio,
356                        doubling video frame interval to slow down.
3572009-10-24 12:20:57.965 NVP(0): Video is 3.10705 frames ahead of audio,
358                        doubling video frame interval to slow down.
359'video_output' mean = '25100.74', std. dev. = '12635.59', fps = '39.84'
360'video_output' mean = '23607.55', std. dev. = '10161.06', fps = '42.36'
361'video_output' mean = '23516.79', std. dev. = '10500.41', fps = '42.52'
3622009-10-24 12:21:07.202 NVP(0): 400 progressive frames seen.
363'video_output' mean = '23688.16', std. dev. = '10021.62', fps = '42.22'
364'video_output' mean = '23692.99', std. dev. = '10945.15', fps = '42.21'
365'video_output' mean = '24022.48', std. dev. = '10009.22', fps = '41.63'
366'video_output' mean = '23029.88', std. dev. = '10409.29', fps = '43.42'
3672009-10-24 12:21:16.695 NVP(0): 800 progressive frames seen.
368'video_output' mean = '25037.38', std. dev. = '14957.88', fps = '39.94'
3692009-10-24 12:21:16.888 NVP(0): Video is 3.00275 frames behind audio (too slow), dropping frame to catch up.
3702009-10-24 12:21:17.123 NVP(0): Video is 3.00015 frames ahead of audio,
371                        doubling video frame interval to slow down.
3722009-10-24 12:21:17.145 NVP(0): Video is 3.2126 frames ahead of audio,
373                        doubling video frame interval to slow down.
3742009-10-24 12:21:17.179 NVP(0): Video is 3.35945 frames ahead of audio,
375                        doubling video frame interval to slow down.
3762009-10-24 12:21:17.212 NVP(0): Video is 3.29455 frames ahead of audio,
377                        doubling video frame interval to slow down.
3782009-10-24 12:21:17.262 NVP(0): Video is 3.0584 frames ahead of audio,
379                        doubling video frame interval to slow down.
380'video_output' mean = '22998.57', std. dev. = '10862.62', fps = '43.48'
381'video_output' mean = '23348.59', std. dev. = '10075.75', fps = '42.83'
382'video_output' mean = '23693.63', std. dev. = '10606.92', fps = '42.21'
3832009-10-24 12:21:26.189 NVP(0): 1200 progressive frames seen.
384'video_output' mean = '24021.52', std. dev. = '9850.19', fps = '41.63'
385'video_output' mean = '23682.90', std. dev. = '10400.20', fps = '42.22'
386'video_output' mean = '23360.20', std. dev. = '10744.93', fps = '42.81'
387'video_output' mean = '23857.40', std. dev. = '10244.08', fps = '41.92'
3882009-10-24 12:21:34.056 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
3892009-10-24 12:21:34.056 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
3902009-10-24 12:21:34.097 TV: HandleStateChange(0) -- begin
3912009-10-24 12:21:34.098 TV: Attempting to change from Watching WatchingLiveTV to None
3922009-10-24 12:21:34.098 TV: StopStuff() for player ctx 0 -- begin
3932009-10-24 12:21:34.098 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
3942009-10-24 12:21:34.098 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
3952009-10-24 12:21:34.098 TV: StopStuff(): stopping ring buffer
3962009-10-24 12:21:34.107 TV: StopStuff(): stopping player
3972009-10-24 12:21:34.108 TV: StopStuff(): stopping recorder
3982009-10-24 12:21:34.109 NVP(0): Exited decoder loop.
3992009-10-24 12:21:34.114 ~OpenGLVideoSync() -- closing opengl vsync
4002009-10-24 12:21:34.115 GLCtx: Deleting OpenGL Resources
4012009-10-24 12:21:34.116 GLCtx: Unmapping gl window
4022009-10-24 12:21:34.117 GLCtx: Destroying gl window
4032009-10-24 12:21:34.117 GLCtx: Destroying glx context
4042009-10-24 12:21:34.142 GLCtx: Closing display
4052009-10-24 12:21:34.144 VidOutVDPAU: DiscardFrames(1)
4062009-10-24 12:21:34.144 VideoBuffers::DiscardFrames(1): U(AU)Uu(AL)UUUUUUUUULUUUUUUu         
4072009-10-24 12:21:34.145 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAA           -- done()
4082009-10-24 12:21:34.145 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAA           -- done
4092009-10-24 12:21:34.146 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA           -- done()
4102009-10-24 12:21:34.438 TV: StopStuff() -- end
4112009-10-24 12:21:34.438 TV: Changing from Watching WatchingLiveTV to None
4122009-10-24 12:21:34.440 TV: HandleStateChange(0) -- end
4132009-10-24 12:21:34.440 TV: StartTV -- process events end
4142009-10-24 12:21:34.440 TV: StartTV -- process events 2 begin
4152009-10-24 12:21:34.447 TV: StartTV -- process events 2 end
4162009-10-24 12:21:34.452 TV::~TV() -- begin
4172009-10-24 12:21:34.504 TV::~TV() -- lock
4182009-10-24 12:21:34.912 TV::~TV() -- end
4192009-10-24 12:21:34.913 TV: StartTV -- end
4202009-10-24 12:21:37.662 Deleting UPnP client...