[mythtv] VDPAU Fails after 3 prior successful plays

Richard Taylor richard at artaylor.co.uk
Sun Sep 27 19:22:39 UTC 2009 

Hi,

I am using an Acer Revo R3600, nVidia drivers 185.18.36 and Kernel  
2.6.31 with an svn build of MythTV (svn r.22076).

I have noticed a couple of issues with VDPAU under MythTV - both of the 
below are reproducible on my system.
I hope to help debug and fix if given an idea of where to start looking.

1) If VDPAU setup fails, the player does not fall back to the ffmpeg / 
XV setting in the playback settings list, but simply hangs.
2) The first 3 times I watch a recording work, at the 4th time of trying 
to watch a recording it will show "Please Wait..." for longer than 
normal followed by a corrupt image of a previously rendered UI screen. 
The only way I have found to remedy this is to kill off mythfrontend and 
restart.

I haven't yet determined whether (2) is time related or not. The system 
is solely used as a frontend, so there is nothing else happening on the 
system.

Any guidance appreciated!
Kind regards,
Richard.


The log for (2) is as follows:
2009-09-27 17:45:53.420 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/menu-ui.xml
2009-09-27 17:45:53.548 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//mainmenu.xml
2009-09-27 17:45:53.566 Found mainmenu.xml for theme 'metallurgy'
2009-09-27 17:45:53.573 Using NV NPOT texture extension
2009-09-27 17:45:54.280 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 17:45:54.281 Using protocol version 48
2009-09-27 19:07:48.299 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//library.xml
2009-09-27 19:07:50.653 New DB connection, total: 1
2009-09-27 19:07:50.656 Connected to database 'mythconverg' at host: 
192.168.1.3
2009-09-27 19:07:50.676 New DB connection, total: 2
2009-09-27 19:07:50.677 Connected to database 'mythconverg' at host: 
192.168.1.3
2009-09-27 19:07:51.113 Unable to find image file: type/subs.png
2009-09-27 19:07:51.114 Unable to find image file: type/subs_onscreen.png
2009-09-27 19:07:51.121 Unable to find image file: type/processing.png
2009-09-27 19:07:51.137 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/recordings-ui.xml
2009-09-27 19:07:51.545 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:07:51.547 Using protocol version 48
2009-09-27 19:07:51.847 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:07:51.848 Using protocol version 48
2009-09-27 19:07:55.935 TV: Attempting to change from None to Watching 
WatchingPreRecorded
2009-09-27 19:07:55.961 TV: StartPlayer(0, Watching WatchingPreRecorded, 
main) -- begin
2009-09-27 19:07:58.588 
RingBuf(myth://192.168.1.3:6543/1071_20090927181500.mpg): Waited 2.0 
seconds for data to become available...
2009-09-27 19:07:59.628 AFD: Opened codec 0xab4748b0, id(MPEG2VIDEO) 
type(Video)
2009-09-27 19:07:59.628 AFD: codec MP2 has 2 channels
2009-09-27 19:07:59.628 AFD: Opened codec 0xab4720a0, id(MP2) type(Audio)
2009-09-27 19:07:59.628 AFD: codec MP2 has 1 channels
2009-09-27 19:07:59.628 AFD: Opened codec 0xab46e9d0, id(MP2) type(Audio)
2009-09-27 19:07:59.629 AFD: Opened codec 0xab46ef60, id(DVB_SUBTITLE) 
type(Subtitle)
2009-09-27 19:07:59.639 Opening audio device 'hdmi_softvol'. ch 2(2) sr 
48000
2009-09-27 19:07:59.639 Opening ALSA audio device 'hdmi_softvol'.
2009-09-27 19:07:59.857 mixer unable to find control Master 1
2009-09-27 19:08:01.054 NVP(0): Forcing decode extra audio option on 
(Video method requires it).
2009-09-27 19:08:01.089 OSD Theme Dimensions W: 1280 H: 720
2009-09-27 19:08:02.351 TV: StartPlayer(0, Watching WatchingPreRecorded, 
main) -- end ok
2009-09-27 19:08:02.352 TV: Changing from None to Watching 
WatchingPreRecorded
2009-09-27 19:08:02.381 New DB connection, total: 3
2009-09-27 19:08:02.383 The realtime priority setting is not enabled.
2009-09-27 19:08:02.384 Connected to database 'mythconverg' at host: 
192.168.1.3
2009-09-27 19:08:02.434 OpenGLVideoSync()
2009-09-27 19:08:02.435 ScreenSaverX11Private: DPMS Deactivated 1
2009-09-27 19:08:02.701 Video timing method: SGI OpenGL
2009-09-27 19:10:44.005 ScreenSaverX11Private: DPMS Reactivated 1
2009-09-27 19:12:02.802 Marking recording as unwatched
2009-09-27 19:12:02.824 TV: Attempting to change from Watching 
WatchingPreRecorded to None
2009-09-27 19:12:02.834 TV: Changing from Watching WatchingPreRecorded 
to None
2009-09-27 19:12:02.848 ~OpenGLVideoSync() -- closing opengl vsync
2009-09-27 19:12:03.793 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:12:03.794 Using protocol version 48
2009-09-27 19:12:05.437 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:12:05.443 Using protocol version 48
2009-09-27 19:12:19.544 TV: Attempting to change from None to Watching 
WatchingPreRecorded
2009-09-27 19:12:19.589 TV: StartPlayer(0, Watching WatchingPreRecorded, 
main) -- begin
2009-09-27 19:12:19.812 [mp2 @ 0x5a82ba8]Header missing
2009-09-27 19:12:19.813 [mp2 @ 0x5a82ba8]Header missing
2009-09-27 19:12:20.688 AFD: Opened codec 0xab5abc70, id(MPEG2VIDEO) 
type(Video)
2009-09-27 19:12:20.688 AFD: codec MP2 has 2 channels
2009-09-27 19:12:20.688 AFD: Opened codec 0xab4d15d0, id(MP2) type(Audio)
2009-09-27 19:12:20.688 AFD: codec MP2 has 1 channels
2009-09-27 19:12:20.688 AFD: Opened codec 0xab4d1d40, id(MP2) type(Audio)
2009-09-27 19:12:20.688 AFD: Opened codec 0xab5af4b0, id(DVB_SUBTITLE) 
type(Subtitle)
2009-09-27 19:12:20.694 Opening audio device 'hdmi_softvol'. ch 2(2) sr 
48000
2009-09-27 19:12:20.694 Opening ALSA audio device 'hdmi_softvol'.
2009-09-27 19:12:20.843 mixer unable to find control Master 1
2009-09-27 19:12:21.370 NVP(1): Forcing decode extra audio option on 
(Video method requires it).
2009-09-27 19:12:21.375 OSD Theme Dimensions W: 1280 H: 720
2009-09-27 19:12:22.265 TV: StartPlayer(0, Watching WatchingPreRecorded, 
main) -- end ok
2009-09-27 19:12:22.267 TV: Changing from None to Watching 
WatchingPreRecorded
2009-09-27 19:12:22.269 The realtime priority setting is not enabled.
2009-09-27 19:12:22.439 OpenGLVideoSync()
2009-09-27 19:12:22.440 ScreenSaverX11Private: DPMS Deactivated 1
2009-09-27 19:12:22.618 Video timing method: SGI OpenGL
2009-09-27 19:16:57.200 Marking recording as unwatched
2009-09-27 19:16:57.241 TV: Attempting to change from Watching 
WatchingPreRecorded to None
2009-09-27 19:16:57.251 ~OpenGLVideoSync() -- closing opengl vsync
2009-09-27 19:16:57.266 TV: Changing from Watching WatchingPreRecorded 
to None
2009-09-27 19:16:57.266 ScreenSaverX11Private: DPMS Reactivated 1
2009-09-27 19:16:58.332 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:16:58.334 Using protocol version 48
2009-09-27 19:20:08.252 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//manage_recordings.xml
2009-09-27 19:20:10.316 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//tv_schedule.xml
2009-09-27 19:20:12.979 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/schedule-ui.xml
2009-09-27 19:20:56.711 Loading window theme from 
/usr/share/mythtv/themes/default-wide/schedule-ui.xml
2009-09-27 19:21:18.803 Loading window theme from 
/usr/share/mythtv/themes/default-wide/schedule-ui.xml
2009-09-27 19:27:10.123 Loading window theme from 
/usr/share/mythtv/themes/default-wide/schedule-ui.xml
2009-09-27 19:27:32.352 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/schedule-ui.xml
2009-09-27 19:27:54.072 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//tv_schedule.xml
2009-09-27 19:27:55.763 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/schedule-ui.xml
2009-09-27 19:28:16.927 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/schedule-ui.xml
2009-09-27 19:28:49.805 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//tv_schedule.xml
2009-09-27 19:28:51.211 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/schedule-ui.xml
2009-09-27 19:29:15.303 Using Idle Timer. 45 minutes
2009-09-27 19:29:15.403 TV: Attempting to change from None to Watching 
WatchingLiveTV
2009-09-27 19:29:15.409 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:29:15.410 Using protocol version 48
2009-09-27 19:29:15.412 Spawning LiveTV Recorder -- begin
2009-09-27 19:29:15.539 Spawning LiveTV Recorder -- end
2009-09-27 19:29:15.676 We have a 
playbackURL(myth://192.168.1.3:6543/1005_20090927192901.mpg) & 
cardtype(DUMMY)
2009-09-27 19:29:15.676 We have a RingBuffer
2009-09-27 19:29:15.712 TV: StartPlayer(0, Watching WatchingLiveTV, 
main) -- begin
2009-09-27 19:29:15.777 NVP(2): Disabling Audio, params(-1,2,44100)
2009-09-27 19:29:16.137 OSD Theme Dimensions W: 1280 H: 720
2009-09-27 19:29:17.054 TV: StartPlayer(0, Watching WatchingLiveTV, 
main) -- end ok
2009-09-27 19:29:17.054 TV: Changing from None to Watching WatchingLiveTV
2009-09-27 19:29:17.054 TV: State is LiveTV & mctx == ctx
2009-09-27 19:29:17.057 The realtime priority setting is not enabled.
2009-09-27 19:29:17.078 TV: UpdateOSDInput done
2009-09-27 19:29:17.089 TV: UpdateLCD done
2009-09-27 19:29:17.090 TV: ITVRestart done
2009-09-27 19:29:17.097 OpenGLVideoSync()
2009-09-27 19:29:17.174 ScreenSaverX11Private: DPMS Deactivated 1
2009-09-27 19:29:17.202 Video timing method: SGI OpenGL
2009-09-27 19:29:19.269 VidOutVDPAU Error: Failed to create VDPAU osd.
2009-09-27 19:29:19.285 NVP(2): Forcing decode extra audio option on 
(Video method requires it).
2009-09-27 19:29:19.637 AFD: Opened codec 0x9251bf0, id(MPEG2VIDEO) 
type(Video)
2009-09-27 19:29:19.637 AFD: codec MP2 has 2 channels
2009-09-27 19:29:19.637 AFD: Opened codec 0xa896c10, id(MP2) type(Audio)
2009-09-27 19:29:19.637 AFD: codec MP2 has 1 channels
2009-09-27 19:29:19.637 AFD: Opened codec 0xa896fc0, id(MP2) type(Audio)
2009-09-27 19:29:19.637 AFD: Opened codec 0x9480380, id(DVB_SUBTITLE) 
type(Subtitle)
2009-09-27 19:29:19.688 Opening audio device 'hdmi_softvol'. ch 2(2) sr 
48000
2009-09-27 19:29:19.688 Opening ALSA audio device 'hdmi_softvol'.
2009-09-27 19:29:19.773 mixer unable to find control Master 1
2009-09-27 19:29:19.776 NVP(2): Enabling Audio
2009-09-27 19:33:37.900 TV: Attempting to change from Watching 
WatchingLiveTV to None
2009-09-27 19:33:37.916 ~OpenGLVideoSync() -- closing opengl vsync
2009-09-27 19:33:38.079 TV: Changing from Watching WatchingLiveTV to None
2009-09-27 19:33:38.081 ScreenSaverX11Private: DPMS Reactivated 1
2009-09-27 19:33:42.479 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//manage_recordings.xml
2009-09-27 19:33:44.046 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//tv_schedule.xml
2009-09-27 19:33:45.423 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/schedule-ui.xml
2009-09-27 19:33:52.268 Loading window theme from 
/usr/share/mythtv/themes/default-wide/schedule-ui.xml
2009-09-27 19:34:00.920 Loading window theme from 
/usr/share/mythtv/themes/default-wide/schedule-ui.xml
2009-09-27 19:34:24.112 Loading menu theme from 
/usr/share/mythtv/themes/defaultmenu//library.xml
2009-09-27 19:34:26.042 Unable to find image file: type/subs.png
2009-09-27 19:34:26.042 Unable to find image file: type/subs_onscreen.png
2009-09-27 19:34:26.044 Unable to find image file: type/processing.png
2009-09-27 19:34:26.046 Loading window theme from 
/usr/share/mythtv/themes/metallurgy/recordings-ui.xml
2009-09-27 19:34:26.410 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:34:26.411 Using protocol version 48
2009-09-27 19:34:26.667 MythContext: Connecting to backend server: 
192.168.1.3:6543 (try 1 of 1)
2009-09-27 19:34:26.668 Using protocol version 48
2009-09-27 19:34:31.486 TV: Attempting to change from None to Watching 
WatchingPreRecorded
2009-09-27 19:34:31.514 TV: StartPlayer(0, Watching WatchingPreRecorded, 
main) -- begin
2009-09-27 19:34:32.291 AFD: Opened codec 0xaccdc4a0, id(MPEG2VIDEO) 
type(Video)
2009-09-27 19:34:32.291 AFD: codec MP2 has 2 channels
2009-09-27 19:34:32.291 AFD: Opened codec 0xab531460, id(MP2) type(Audio)
2009-09-27 19:34:32.291 AFD: codec MP2 has 1 channels
2009-09-27 19:34:32.292 AFD: Opened codec 0xa356dce0, id(MP2) type(Audio)
2009-09-27 19:34:32.292 AFD: Opened codec 0xa356e270, id(DVB_SUBTITLE) 
type(Subtitle)
2009-09-27 19:34:32.297 Opening audio device 'hdmi_softvol'. ch 2(2) sr 
48000
2009-09-27 19:34:32.298 Opening ALSA audio device 'hdmi_softvol'.
2009-09-27 19:34:32.432 mixer unable to find control Master 1
2009-09-27 19:34:32.728 VidOutVDPAU Error: Failed to initialise VDPAU
2009-09-27 19:34:32.747 VideoOutput, Error: Not compiled with any 
useable video output method.
2009-09-27 19:34:32.748 NVP(3), Error: Couldn't create VideoOutput 
instance. Exiting..
2009-09-27 19:34:32.748 Unable to initialize video.
2009-09-27 19:34:52.664 playCtx, Error: StartDecoderThread() Failed to 
startdecoder
2009-09-27 19:34:52.664 TV: StartPlayer(0, Watching WatchingPreRecorded, 
main) -- end error
2009-09-27 19:34:52.682 ScreenSaverX11Private: DPMS Deactivated 1

More information about the mythtv-dev mailing list