Mailing List Archive: MythTV: Users

Audio dropouts after upgrade to 0.24

Index | Next | Previous | View Threaded

enigma at thedonnerparty

Dec 6, 2010, 6:21 PM

Post #1 of 7 (488 views)
Permalink

Audio dropouts after upgrade to 0.24

I recently upgraded my 6 machine myth system to 0.24 and I am experiencing
several problems. On one of my frontends the audio is dropping out
occasionally when playing back h264 material from a HD-PVR (PVR-xxx MPEG and
OTA MPEG do not seem to have the same issue, nor does Mythmusic). This
machine is using analog stereo output and VDPAU video output. After
upgrading the box I went into settings and scanned for audio devices,
ALSA:Default no longer works or appears in the scan (but does work as an
output device with aplay) so I chose the option that seemed to be the
closest which is ALSA:front:CARD=ICH5,DEV=0. I also see the same behavior
using /dev/dsp. Using the default logging options myth does not write
anything relevant to the issue but if I use -v audio I get some output that
may be relevant. When the dropouts happen this is what is printed to the
log:

2010-12-06 19:10:07.504 AO: Pause 1
2010-12-06 19:10:07.511 AO: OutputAudioLoop: audio paused
2010-12-06 19:10:07.526 AO: Pause 0
2010-12-06 19:10:07.573 AO: OutputAudioLoop: Play Event
2010-12-06 19:10:34.969 AO: Pause 1
2010-12-06 19:10:34.978 AO: OutputAudioLoop: audio paused
2010-12-06 19:10:34.991 AO: Pause 0
2010-12-06 19:10:35.038 AO: OutputAudioLoop: Play Event
2010-12-06 19:10:54.459 AO: Pause 1
2010-12-06 19:10:54.468 AO: OutputAudioLoop: audio paused
2010-12-06 19:10:54.481 AO: Pause 0
2010-12-06 19:10:54.529 AO: OutputAudioLoop: Play Event

According to lspci the audio device is:
00:1f.5 Multimedia audio controller: Intel Corporation 82801EB/ER
(ICH5/ICH5R) AC'97 Audio Controller (rev 02)

Can anyone recommend steps I can take to debug or resolve this issue?

jyavenard at gmail

Dec 6, 2010, 7:08 PM

Post #2 of 7 (479 views)
Permalink

Re: Audio dropouts after upgrade to 0.24 [In reply to]

Hi

Post the log with -v audio,playback

those logs do not show anything special, just that you either paused,
or the player asked to pause the audio
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users

enigma at thedonnerparty

Dec 6, 2010, 10:09 PM

Post #3 of 7 (472 views)
Permalink

Re: Audio dropouts after upgrade to 0.24 [In reply to]

> Hi
>
> Post the log with -v audio,playback
>
> those logs do not show anything special, just that you either paused,
> or the player asked to pause the audio

Hmm, I tried to post the log for a minute of playback but it got
bounced because it was too big. Here is the first half:

$ mythfrontend -v audio,playback
2010-12-06 21:29:14.357 mythfrontend version: trunk [es27305] www.mythtv.org
2010-12-06 21:29:14.358 Using runtime prefix = /usr
2010-12-06 21:29:14.358 Using configuration directory = /home/mythtv/.mythtv
2010-12-06 21:29:14.360 ThreadPool:HTTP: Initial 1, Max 25, Timeout 60000
2010-12-06 21:29:15.372 Empty LocalHostName.
2010-12-06 21:29:15.372 Using localhost value of hedonism-bot
2010-12-06 21:29:15.373 Testing network connectivity to 'burns'
2010-12-06 21:29:15.494 New DB connection, total: 1
2010-12-06 21:29:15.502 Connected to database 'mythconverg' at host: burns
2010-12-06 21:29:15.511 Closing DB connection named 'DBManager0'
2010-12-06 21:29:15.516 Connected to database 'mythconverg' at host: burns
2010-12-06 21:29:15.520 Current locale EN_US
2010-12-06 21:29:15.520 Reading locale defaults from
/usr/share/mythtv//locales/en_us.xml
2010-12-06 21:29:15.680 ScreenSaverX11Private: Gnome screen saver
support enabled
2010-12-06 21:29:15.682 DPMS is disabled.
2010-12-06 21:29:15.726 Desktop video mode: 1680x1050 59.954 Hz
2010-12-06 21:29:15.769 max_width: 1680 max_height: 1050
2010-12-06 21:29:15.772 user: 116 effective user: 116 before privileged thread
2010-12-06 21:29:15.772 user: 116 effective user: 116 after privileged thread
2010-12-06 21:29:15.773 Enabled verbose msgs: important general audio playback
2010-12-06 21:29:15.775 user: 116 effective user: 116 run_priv_thread
2010-12-06 21:29:15.783 Loading en_us translation for module mythfrontend
2010-12-06 21:29:15.812 LIRC: Successfully initialized
'/var/run/lirc/lircd' using '/home/mythtv/.mythtv/lircrc' config
2010-12-06 21:29:15.812 JoystickMenuThread: Joystick disabled - Failed
to read /home/mythtv/.mythtv/joystickmenurc
2010-12-06 21:29:15.906 Using Frameless Window
2010-12-06 21:29:15.906 Using Full Screen Window
2010-12-06 21:29:16.322 Using the OpenGL painter
2010-12-06 21:29:16.381 OpenGL: OpenGL vendor : NVIDIA Corporation
2010-12-06 21:29:16.382 OpenGL: OpenGL renderer: GeForce 8400 GS/PCI/SSE2
2010-12-06 21:29:16.382 OpenGL: OpenGL version : 3.2.0 NVIDIA 195.36.15
2010-12-06 21:29:16.382 OpenGL: Max texture size: 8192 x 8192
2010-12-06 21:29:16.382 OpenGL: Max texture units: 4
2010-12-06 21:29:16.382 OpenGL: Direct rendering: Yes
2010-12-06 21:29:16.382 OpenGL: Initialised MythRenderOpenGL
2010-12-06 21:29:17.033 Current MythTV Schema Version (DBSchemaVer): 1264
2010-12-06 21:29:17.035 VDP: decoder<->render support: ffmpeg
null directfb xlib xshm xv-blit opengl vdpau
2010-12-06 21:29:17.035 VDP: decoder<->render support: vdpau       vdpau
2010-12-06 21:29:17.035 VDP: decoder<->render support: libmpeg2
null directfb xlib xshm xv-blit opengl vdpau
2010-12-06 21:29:17.048 VDP: Ignoring profile item 332 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.048 VDP: Ignoring profile item 334 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.055 VDP: Ignoring profile item 330 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.059 VDP: Ignoring profile item 336 (decoder ivtv
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.059 VDP: Ignoring profile item 337 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.059 VDP: Ignoring profile item 338 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.060 VDP: Ignoring profile item 339 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.067 VDP: Ignoring profile item 343 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.067 VDP: Ignoring profile item 344 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.069 New DB connection, total: 2
2010-12-06 21:29:17.074 Connected to database 'mythconverg' at host: burns
2010-12-06 21:29:17.077 VDP: Ignoring profile item 347 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.077 VDP: Ignoring profile item 348 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.084 VDP: Ignoring profile item 351 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.084 VDP: Ignoring profile item 352 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:19.819 ThemeInfo, Warning: Unable to open
themeinfo.xml for
/usr/share/mythtv/themes/BlackCurves-OSD/themeinfo.xml
2010-12-06 21:29:19.820 ThemeInfo, Error: The theme
(/usr/share/mythtv/themes/BlackCurves-OSD) is missing a themeinfo.xml
file.
2010-12-06 21:29:19.825 ThemeInfo, Warning: Unable to open
themeinfo.xml for /usr/share/mythtv/themes/Gray-OSD/themeinfo.xml
2010-12-06 21:29:19.825 ThemeInfo, Error: The theme
(/usr/share/mythtv/themes/Gray-OSD) is missing a themeinfo.xml file.
2010-12-06 21:29:19.984 Pulse: PulseAudio not running
2010-12-06 21:29:20.002 AO: Sample rate 8000 is supported
2010-12-06 21:29:20.002 AO: Sample rate 11025 is supported
2010-12-06 21:29:20.003 AO: Sample rate 16000 is supported
2010-12-06 21:29:20.003 AO: Sample rate 22050 is supported
2010-12-06 21:29:20.003 AO: Sample rate 32000 is supported
2010-12-06 21:29:20.003 AO: Sample rate 44100 is supported
2010-12-06 21:29:20.003 AO: Sample rate 48000 is supported
2010-12-06 21:29:20.003 AO: Sample rate 96000 is supported
2010-12-06 21:29:20.003 AO: 2 channel(s) are supported
2010-12-06 21:29:20.003 AO: 4 channel(s) are supported
2010-12-06 21:29:20.003 AO: 6 channel(s) are supported
2010-12-06 21:29:20.028 AO: Killing AudioOutputDSP
2010-12-06 21:29:20.029 Found ALSA:front:CARD=ICH5,DEV=0
(ALSA:front:CARD=ICH5,DEV=0
Device supports up to 5.1 (AC3,DTS,multi-channels LPCM))
2010-12-06 21:29:20.405 Registering Internal as a media playback plugin.
2010-12-06 21:29:20.448 Current MythMusic Schema Version
(MusicDBSchemaVer): 1017
2010-12-06 21:29:20.573 MediaMonitorUnix::AddDevice() - empty device path.
2010-12-06 21:29:20.574 MediaMonitorUnix::AddDevice() - empty device path.
2010-12-06 21:29:20.575 MediaMonitorUnix::AddDevice() - empty device path.
2010-12-06 21:29:20.577 MonitorRegisterExtensions(0x40,
mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
2010-12-06 21:29:20.593 Loading en_us translation for module mythmusic
2010-12-06 21:29:20.614 Current MythVideo Schema Version
(mythvideo.DBSchemaVer): 1038
2010-12-06 21:29:20.668 Loading en_us translation for module mythvideo
2010-12-06 21:29:20.671 MythPlugin::init() dlerror:
libmythdb-0.23.1.so.0: cannot open shared object file: No such file or
directory
2010-12-06 21:29:20.671 Unable to initialize plugin 'mythzoneminder'.
2010-12-06 21:29:21.413 Found mainmenu.xml for theme 'MythCenter-wide'
2010-12-06 21:29:21.868 MythCoreContext: Connecting to backend server:
192.168.2.6:6543 (try 1 of 1)
2010-12-06 21:29:21.869 Using protocol version 63
2010-12-06 21:29:54.435 PreviewQueue: Requesting preview for
'4041_20101206203000.mpg_0x0_-1s'
2010-12-06 21:29:54.436 PreviewQueue: Requested preview for
'4041_20101206203000.mpg_0x0_-1s'
2010-12-06 21:30:07.674 TV: StartTV() -- begin
2010-12-06 21:30:07.687 TV: ctor -- begin
2010-12-06 21:30:07.688 TV: ctor -- end
2010-12-06 21:30:07.689 TV: Init -- begin
2010-12-06 21:30:07.716 TV: DrawUnusedRects() -- begin
2010-12-06 21:30:07.716 TV: DrawUnusedRects() -- end
2010-12-06 21:30:07.718 TV: Init -- end
2010-12-06 21:30:07.719 TV: tv->Playback() -- begin
2010-12-06 21:30:07.733 TV: tv->Playback() -- end
2010-12-06 21:30:07.733 TV: StartTV -- process events begin
2010-12-06 21:30:07.837 TV: HandleStateChange(0) -- begin
2010-12-06 21:30:07.837 TV: Attempting to change from None to WatchingRecording
2010-12-06 21:30:07.842
RingBuf(/home/share/video/9070_20101206183000.mpg):
OpenFile(/home/share/video/9070_20101206183000.mpg, 2000 ms)
2010-12-06 21:30:07.911 TV: DrawUnusedRects() -- begin
2010-12-06 21:30:07.911 TV: DrawUnusedRects() -- end
2010-12-06 21:30:07.915 MythCoreContext: Connecting to backend server:
192.168.2.92:6543 (try 1 of 1)
2010-12-06 21:30:07.955 Using protocol version 63
2010-12-06 21:30:08.001 TV: StartPlayer(0, WatchingRecording, main) -- begin
2010-12-06 21:30:08.001 TV: Elapsed time since TV constructor was called: 314 ms
2010-12-06 21:30:08.171 Pulse: PulseAudio not running
2010-12-06 21:30:08.180 AO: Sample rate 8000 is supported
2010-12-06 21:30:08.180 AO: Sample rate 11025 is supported
2010-12-06 21:30:08.180 AO: Sample rate 16000 is supported
2010-12-06 21:30:08.180 AO: Sample rate 22050 is supported
2010-12-06 21:30:08.181 AO: Sample rate 32000 is supported
2010-12-06 21:30:08.181 AO: Sample rate 44100 is supported
2010-12-06 21:30:08.181 AO: Sample rate 48000 is supported
2010-12-06 21:30:08.181 AO: Sample rate 96000 is supported
2010-12-06 21:30:08.181 AO: 2 channel(s) are supported
2010-12-06 21:30:08.181 AO: 4 channel(s) are supported
2010-12-06 21:30:08.181 AO: 6 channel(s) are supported
2010-12-06 21:30:08.207 AO: Killing AudioOutputDSP
2010-12-06 21:30:09.197 AFD: Stream #0, has id 0x1011 codec id H264,
type Video, bitrate 0 at 0xb21a4f0
2010-12-06 21:30:09.230 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:09.230 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:09.231 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:09.231 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:09.235 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:09.235 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:09.236 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:09.236 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:09.236 AFD: Using 1 CPUs for decoding
2010-12-06 21:30:09.236 AFD: InitVideoCodec() 0xb2bab50 id(H264) type (Video).
2010-12-06 21:30:09.237 AFD: Selected FPS is 59.9401 (avg 59.96 stream
59.9401 container 90000 estimated 59.9401)
2010-12-06 21:30:09.237 Player(0): detectInterlace(Detect Scan,
Interlaced Scan, 59.9401, 720) ->Progressive Scan
2010-12-06 21:30:09.237 AFD: Using vdpau for video decoding
2010-12-06 21:30:09.238 AFD: Looking for decoder for H264
2010-12-06 21:30:09.238 AFD: Opened codec 0xb2bab50, id(H264) type(Video)
2010-12-06 21:30:09.238 AFD: Stream #1, has id 0x1100 codec id AAC,
type Audio, bitrate 121500 at 0xb2baf30
2010-12-06 21:30:09.238 AFD: codec AAC has 2 channels
2010-12-06 21:30:09.238 AFD: Looking for decoder for AAC
2010-12-06 21:30:09.241 AFD: Opened codec 0xb2bb100, id(AAC) type(Audio)
2010-12-06 21:30:09.241 AFD: Audio Track #1 is A/V stream #1 and has 2
channels in the Undetermined language(7695972).
2010-12-06 21:30:09.241 AFD: Selected track 1: Undetermined AAC 2ch
(A/V Stream #1)
2010-12-06 21:30:09.242 AFD: Initializing audio parms from audio track #1
2010-12-06 21:30:09.242 AFD: Audio format changed
            from id(NONE)     -1Hz -1ch -1bps     to id( AAC) 48000Hz
2ch 16bps
2010-12-06 21:30:09.242 AO: Killing AudioOutputDSP
2010-12-06 21:30:09.373 Pulse: PulseAudio not running
2010-12-06 21:30:09.382 AO: Sample rate 8000 is supported
2010-12-06 21:30:09.383 AO: Sample rate 11025 is supported
2010-12-06 21:30:09.383 AO: Sample rate 16000 is supported
2010-12-06 21:30:09.383 AO: Sample rate 22050 is supported
2010-12-06 21:30:09.383 AO: Sample rate 32000 is supported
2010-12-06 21:30:09.383 AO: Sample rate 44100 is supported
2010-12-06 21:30:09.383 AO: Sample rate 48000 is supported
2010-12-06 21:30:09.384 AO: Sample rate 96000 is supported
2010-12-06 21:30:09.385 AO: 2 channel(s) are supported
2010-12-06 21:30:09.385 AO: 4 channel(s) are supported
2010-12-06 21:30:09.385 AO: 6 channel(s) are supported
2010-12-06 21:30:09.415 AO: Killing AudioOutputDSP
2010-12-06 21:30:09.416 AO: Original codec was AAC, signed 16 bit, 48
kHz, 2 channels
2010-12-06 21:30:09.416 AO: enc(0), passthru(0), canAC3(0), canDTS(0),
canLPCM(0), configured_channels(2), 2 channels supported(1)
2010-12-06 21:30:09.416 AO: Opening audio device
'front:CARD=ICH5,DEV=0' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2010-12-06 21:30:09.417 ALSA: SetParameters(format=2, channels=2,
rate=48000, buffer_time=200000, period_time=50000)
2010-12-06 21:30:09.418 ALSA: Buffer time = 200000 us
2010-12-06 21:30:09.418 ALSA: Period time = 50000 us
2010-12-06 21:30:09.418 ALSA: Buffer size = 9600 | Period size = 2400
2010-12-06 21:30:09.430 ALSA: found playback control Master on mixer
device default
2010-12-06 21:30:09.430 ALSA: mixer volume range on mixer device
default/Master - min 0, max 31, range 31
2010-12-06 21:30:09.430 ALSA: mixer device default/Master set up successfully
2010-12-06 21:30:09.432 ALSA: channel 0 volume set 85 => 26
2010-12-06 21:30:09.432 ALSA: channel 1 volume set 85 => 26
2010-12-06 21:30:09.432 ALSA: get volume channel 0: 84
2010-12-06 21:30:09.432 ALSA: channel 0 volume set 84 => 26
2010-12-06 21:30:09.432 ALSA: channel 1 volume set 84 => 26
2010-12-06 21:30:09.432 AO: Audio fragment size: 4800
2010-12-06 21:30:09.433 AO: Audio Stretch Factor: 1
2010-12-06 21:30:09.433 AO: Ending Reconfigure()
2010-12-06 21:30:09.433 AudioPlayer: Enabling Audio
2010-12-06 21:30:09.433 AO: Reconfigure(): No change -> exiting
2010-12-06 21:30:09.433 Dec: Selected track #1 in the Unknown language(0)
2010-12-06 21:30:09.433 Dec: Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(1)
2010-12-06 21:30:09.434 AO: kickoffOutputAudioLoop: pid = 1271
2010-12-06 21:30:09.435 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:10.680 Dec: Position map filled from DB to: 647165
2010-12-06 21:30:10.681 Dec: SyncPositionMap watchingrecording, from
DB: 5057 entries
2010-12-06 21:30:10.682 Player(0): Filling position map from 647166 to end
2010-12-06 21:30:10.699 Dec: Position map filled from Encoder to: 647421
2010-12-06 21:30:10.699 Dec: SyncPositionMap watchingrecording total:
5059 entries
2010-12-06 21:30:10.699 Dec: SyncPositionMap, new totframes: 647421,
new length: 10801, posMap size: 5059
2010-12-06 21:30:10.700 AFD: Partial position map found
2010-12-06 21:30:10.700 AFD: Successfully opened decoder for file:
"/home/share/video/9070_20101206183000.mpg". novideo(0)
2010-12-06 21:30:10.746 VideoOutput: Allowed renderers: vdpau
2010-12-06 21:30:10.747 VideoOutput: Allowed renderers (filt: vdpau): vdpau
2010-12-06 21:30:10.751 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:10.751 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:10.751 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:10.752 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:10.752 VideoOutput: Preferred renderer: vdpau
2010-12-06 21:30:10.752 VideoOutput: Trying video renderer: 'vdpau'
2010-12-06 21:30:10.805 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:10.805 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:10.806 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:10.806 VideoOutWindow::SetPIPState. pip_state: 0]
2010-12-06 21:30:10.806 Clearing OpenGL painter cache.
2010-12-06 21:30:10.816 Display Rect left: 0, top: 131, width: 1680,
height: 787, aspect: 1.33333
2010-12-06 21:30:10.816 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:10.816 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:10.816 Display Rect left: 0, top: 131, width: 1680,
height: 787, aspect: 1.33333
2010-12-06 21:30:10.816 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:10.817 VDP: SetVideoRenderer(vdpau)
2010-12-06 21:30:10.817 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2010-12-06 21:30:10.819 VideoOutput: Pixel dimensions: Screen
1680x1050, window 1680x1050
2010-12-06 21:30:10.819 VideoOutput: Actual display dimensions:
431x272 mm Aspect: 1.58456
2010-12-06 21:30:10.819 VideoOutput: Estimated window dimensions:
431x272 mm Aspect: 1.58456
2010-12-06 21:30:11.462 VDPAU: Created 2 output surfaces.
2010-12-06 21:30:11.462 VDPAU: Set colorkey to 0x20202
2010-12-06 21:30:11.462 VDPAU: Version 1
2010-12-06 21:30:11.462 VDPAU: Information NVIDIA VDPAU Driver Shared
Library 195.36.15 Thu Mar 11 23:42:13 PST 2010
2010-12-06 21:30:11.462 VDPAU: HQ Scaling not supported.
2010-12-06 21:30:11.462 VDPAU: MPEG4 hardware acceleration not supported.
2010-12-06 21:30:11.462 VDPAU: Created VDPAU render device 1680x1050
2010-12-06 21:30:11.463 VidOutVDPAU: Created VDPAU osd (1680x1050)
2010-12-06 21:30:11.916 Display Rect left: 0, top: 57, width: 1680,
height: 936, aspect: 1.58456
2010-12-06 21:30:11.916 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:11.916 VidOutVDPAU: Created VDPAU context (GPU decode)
2010-12-06 21:30:11.922 Over/underscan. V: 0, H: 0
2010-12-06 21:30:11.922 Display Rect left: 0, top: 57, width: 1680,
height: 936, aspect: 1.58456
2010-12-06 21:30:11.922 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:11.923 VDP: LoadBestPreferences(1280x720, 59.9401)
2010-12-06 21:30:11.923 Player(0): Forcing decode extra audio option
on (Video method requires it).
2010-12-06 21:30:11.923 Player(0): LoadFilters(''..) -> 0x0
2010-12-06 21:30:11.926 Player(0): Decoder thread starting.
2010-12-06 21:30:11.951 OSD: Loaded window osd_message
2010-12-06 21:30:11.970 VidOutVDPAU: Created VDPAU decoder (4 ref frames)
2010-12-06 21:30:11.976 Detect Letterbox: The source is not a
supported frame format (was 11)
2010-12-06 21:30:11.976 OSD: Loaded window osd_input
2010-12-06 21:30:12.050 OSD: Loaded window program_info
2010-12-06 21:30:12.080 OSD: Loaded window browse_info
2010-12-06 21:30:12.103 OSD: Loaded window osd_status
2010-12-06 21:30:12.132 OSD: Loaded window osd_program_editor
2010-12-06 21:30:12.132 OSD: Loaded OSD: size 1680x1050 offset 0+0
2010-12-06 21:30:12.137 Player(0): ClearAfterSeek(0)
2010-12-06 21:30:12.139 VDP: GetFilteredDeint() : vdpau ->
'vdpaubasicdoublerate'
2010-12-06 21:30:12.141 VidOutVDPAU: Enabled deinterlacing.
2010-12-06 21:30:12.141 VSYNC: DRMVideoSync: Could not open device
/dev/dri/card0, No such file or directory
2010-12-06 21:30:12.141 VSYNC: RTCVideoSync: Could not open /dev/rtc,
Permission denied.
2010-12-06 21:30:12.142 Player(0): Video sync method can't support
double framerate (refresh rate too low for 2x deint)
2010-12-06 21:30:12.147 VDP: GetFilteredDeint(vdpaubasic) : vdpau ->
'vdpaubasic'
2010-12-06 21:30:12.148 VidOutVDPAU: Enabled deinterlacing.
2010-12-06 21:30:12.152 Player(0): Video timing method: USleep with busy wait
2010-12-06 21:30:12.152 Player(0): Display Refresh Rate: 59.956 Video
Frame Rate: 59.941
2010-12-06 21:30:12.153 playCtx: StartPlaying(): took 0 ms to start player.
2010-12-06 21:30:12.153 TV: StartPlayer(0, WatchingRecording, main) -- end ok
2010-12-06 21:30:12.157 TV: Changing from None to WatchingRecording
2010-12-06 21:30:12.171 TV: HandleStateChange(0) -- end
2010-12-06 21:30:12.176 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:12.309 ScreenSaverX11Private: ResetTimer -- begin
2010-12-06 21:30:12.309 ScreenSaverX11Private: StopTimer
2010-12-06 21:30:12.311 ScreenSaverX11Private: StartTimer
2010-12-06 21:30:12.311 ScreenSaverX11Private: ResetTimer -- end
2010-12-06 21:30:12.312 Player(0): progressive frame seen after 2
interlaced frames
2010-12-06 21:30:12.315 VDPAU: Added 2 output surfaces (total 4, max 4)
2010-12-06 21:30:12.359 Player(0): Disabled deinterlacing
2010-12-06 21:30:12.389 ALSA, Error: WriteAudio: buffer underrun
2010-12-06 21:30:12.392 Player(0): Video is 4.43565 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.392 Player(0): Video is 7.89726 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.392 Player(0): Video is 10.2387 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.413 Player(0): Video is 11.755 frames behind audio
(too slow), dropping frame to catch up.
2010-12-06 21:30:12.413 Player(0): Video is 13.3718 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.414 Player(0): Video is 14.3296 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.434 Player(0): Video is 14.8082 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.434 Player(0): Video is 15.302 frames behind audio
(too slow), dropping frame to catch up.
2010-12-06 21:30:12.435 Player(0): Video is 15.4176 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.455 Player(0): Video is 15.2646 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.455 Player(0): Video is 15.2547 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.456 Player(0): Video is 14.9925 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.476 Player(0): Video is 14.5561 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.476 Player(0): Video is 13.9741 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.477 Player(0): Video is 13.2828 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.497 Player(0): Video is 12.5096 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.498 Player(0): Video is 12.0795 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.498 Player(0): Video is 11.5022 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.518 Player(0): Video is 10.8145 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.519 Player(0): Video is 10.4186 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.519 Player(0): Video is 9.86687 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.539 Player(0): Video is 9.19835 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.540 Player(0): Video is 8.45723 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.540 Player(0): Video is 7.64665 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.561 Player(0): Video is 6.78391 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.561 Player(0): Video is 5.89714 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.562 Player(0): Video is 4.97728 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.582 Player(0): Video is 4.03267 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.582 Player(0): Waiting for video buffers...
2010-12-06 21:30:12.582 AO: Pause 1
2010-12-06 21:30:12.607 AO: Pause 0
2010-12-06 21:30:12.607 Player(0): Video is 3.0844 frames behind audio
(too slow), dropping frame to catch up.
2010-12-06 21:30:12.611 Player(0): Waiting for video buffers...
2010-12-06 21:30:12.611 AO: Pause 1
2010-12-06 21:30:12.621 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:12.633 AO: Pause 0
2010-12-06 21:30:12.647 Player(0): Waiting for video buffers...
2010-12-06 21:30:12.647 AO: Pause 1
2010-12-06 21:30:12.669 AO: Pause 0
2010-12-06 21:30:12.670 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:12.700 Player(0): Video is 3.93347 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.733 Player(0): Video is 4.37367 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.766 Player(0): Video is 4.82371 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.800 Player(0): Video is 4.74165 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.833 Player(0): Video is 4.23053 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.866 Player(0): Video is 3.96709 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.900 Player(0): Video is 3.33495 frames ahead of audio,
            doubling video frame interval to slow down.
'video_output' mean = '15463.87', std. dev. = '10026.89', fps = '64.67'
'video_output' mean = '16671.56', std. dev. = '5730.24', fps = '59.98'
'video_output' mean = '16743.69', std. dev. = '5613.96', fps = '59.72'
2010-12-06 21:30:18.866 Player(0): 400 progressive frames seen.
'video_output' mean = '16617.24', std. dev. = '5259.74', fps = '60.18'
'video_output' mean = '16681.45', std. dev. = '5135.31', fps = '59.95'
'video_output' mean = '16740.08', std. dev. = '5136.89', fps = '59.74'
'video_output' mean = '16619.49', std. dev. = '5145.76', fps = '60.17'
2010-12-06 21:30:25.525 Player(0): 800 progressive frames seen.
'video_output' mean = '16679.96', std. dev. = '5253.11', fps = '59.95'
'video_output' mean = '16744.17', std. dev. = '5178.42', fps = '59.72'
'video_output' mean = '16616.38', std. dev. = '5391.01', fps = '60.18'
2010-12-06 21:30:29.439 Player(0): Waiting for video buffers...
2010-12-06 21:30:29.440 AO: Pause 1
2010-12-06 21:30:29.446 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:29.462 AO: Pause 0
2010-12-06 21:30:29.476 Player(0): Waiting for video buffers...
2010-12-06 21:30:29.476 AO: Pause 1
2010-12-06 21:30:29.499 AO: Pause 0
2010-12-06 21:30:29.499 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:30.547 Player(0): Waiting for video buffers...
2010-12-06 21:30:30.547 AO: Pause 1
2010-12-06 21:30:30.551 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:30.570 AO: Pause 0
2010-12-06 21:30:30.583 Player(0): Waiting for video buffers...
2010-12-06 21:30:30.583 AO: Pause 1
2010-12-06 21:30:30.606 AO: Pause 0
2010-12-06 21:30:30.606 AO: OutputAudioLoop: Play Event
'video_output' mean = '18037.81', std. dev. = '6812.47', fps = '55.44'
2010-12-06 21:30:31.604 Player(0): Waiting for video buffers...
2010-12-06 21:30:31.604 AO: Pause 1
2010-12-06 21:30:31.604 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:31.627 AO: Pause 0
2010-12-06 21:30:31.666 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:31.723 Player(0): Video is 3.27891 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:31.757 Player(0): Video is 3.4332 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:31.790 Player(0): Video is 3.29419 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:32.481 Player(0): 1200 progressive frames seen.
'video_output' mean = '18144.70', std. dev. = '6677.44', fps = '55.11'
2010-12-06 21:30:32.791 Player(0): Waiting for video buffers...
2010-12-06 21:30:32.791 AO: Pause 1
2010-12-06 21:30:32.796 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:32.814 AO: Pause 0
2010-12-06 21:30:32.861 AO: OutputAudioLoop: Play Event
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users

jyavenard at gmail

Dec 6, 2010, 10:36 PM

Post #4 of 7 (467 views)
Permalink

Re: Audio dropouts after upgrade to 0.24 [In reply to]

Hi

On 7 December 2010 17:09, Enigma <enigma [at] thedonnerparty> wrote:

> 2010-12-06 21:30:32.791 Player(0): Waiting for video buffers...
> 2010-12-06 21:30:32.791 AO: Pause 1
> 2010-12-06 21:30:32.796 AO: OutputAudioLoop: audio paused
> 2010-12-06 21:30:32.814 AO: Pause 0
> 2010-12-06 21:30:32.861 AO: OutputAudioLoop: Play Event

This is the culprit:
Player(0): Waiting for video buffers...

so the player pause the audio, before resuming...

Try to increase the size of the VDPAU buffer, and if that's not enough

If you could post a short sample somewhere, and raise a ticket on trac
to see if it's a similar issue to what have been earler reported with
some VDPAU playback.
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users

enigma at thedonnerparty

Dec 7, 2010, 1:21 PM

Post #5 of 7 (452 views)
Permalink

Re: Audio dropouts after upgrade to 0.24 [In reply to]

On 7 December 2010 javenyard [at] gmail wrote:

On 7 December 2010 17:09, Enigma <enigma [at] thedonnerparty> wrote:
>
> > 2010-12-06 21:30:32.791 Player(0): Waiting for video buffers...
> > 2010-12-06 21:30:32.791 AO: Pause 1
> > 2010-12-06 21:30:32.796 AO: OutputAudioLoop: audio paused
> > 2010-12-06 21:30:32.814 AO: Pause 0
> > 2010-12-06 21:30:32.861 AO: OutputAudioLoop: Play Event
>
> This is the culprit:
> Player(0): Waiting for video buffers...
>
> so the player pause the audio, before resuming...
>
> Try to increase the size of the VDPAU buffer, and if that's not enough
>
> If you could post a short sample somewhere, and raise a ticket on trac
> to see if it's a similar issue to what have been earler reported with
> some VDPAU playback.

I have set vdpaubuffersize to various values from 2-50 with no apparent
change. I also enabled vdpauskipchroma and disabled deinterlacing. The
TripleBuffer option is set to true in xorg.conf (and of course I have
compositing disabled). Nothing seems to change the issue.

This is a little frustrating because this machine worked fine under 0.23.
Were there major VDPAU changes for 0.24? I am using the same Nvidia drivers
and the same kernel as previously, the only thing that has changed is my
MythTV version and its dependencies. I would have just stayed at 0.23 but
the backend would deadlock fairly frequently (changeset 26827). Are there
any other options I can try to address this issue? This does not seem to be
too widespread, I have only seen one other post to the list that seems to be
the same issue. What are the chances of this issue being addressed? Is
this a regression in 0.24 or was I just getting lucky that it worked
before? This machine is not powerful enough to decode h264 in software - is
this likely to be another example of hardware that is no longer supported by
Myth?

jyavenard at gmail

Dec 7, 2010, 4:35 PM

Post #6 of 7 (447 views)
Permalink

Re: Audio dropouts after upgrade to 0.24 [In reply to]

Hi

On 8 December 2010 08:21, Enigma <enigma [at] thedonnerparty> wrote:
> the same issue. What are the chances of this issue being addressed? Is
> this a regression in 0.24 or was I just getting lucky that it worked
> before? This machine is not powerful enough to decode h264 in software - is
> this likely to be another example of hardware that is no longer supported by
> Myth?

the chances of getting it looked at would increase if you submitted a
bug report with a sample on trac (https://svn.mythtv.org)
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users

enigma at thedonnerparty

Dec 7, 2010, 9:04 PM

Post #7 of 7 (439 views)
Permalink

Re: Audio dropouts after upgrade to 0.24 [In reply to]

On 8 December 2010 javenyard [at] gmail wrote:
>
> On 8 December 2010 08:21, Enigma <enigma [at] thedonnerparty> wrote:
> > the same issue. What are the chances of this issue being addressed? Is
> > this a regression in 0.24 or was I just getting lucky that it worked
> > before? This machine is not powerful enough to decode h264 in software - is
> > this likely to be another example of hardware that is no longer supported by
> > Myth?
>
> the chances of getting it looked at would increase if you submitted a
> bug report with a sample on trac (https://svn.mythtv.org)

That link doesn't seem to go anywhere (and http://svn.mythtv.org just
says "This is a placeholder") but after finding Trac I was able to
submit the bug: http://svn.mythtv.org/trac/ticket/9344. Thank you for
your help Jean-Yves.
_______________________________________________
mythtv-users mailing list
mythtv-users [at] mythtv
http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users

Index | Next | Previous | View Threaded

Interested in having your list archived? Contact Gossamer Threads