[mythtv-users] Sound Problem in Mythfronted

Thu Feb 23 19:08:16 UTC 2012

Hi

On 23 February 2012 06:03, Or Duek <orduek at gmail.com> wrote:
>
> Does the problem always occur, or is intermittent ?
>
> After a reboot, does it occur immediately or only later on?
>

two questions there which you haven't answered yet...

?Where is that video from? it changes from mono to stero in a very short
time.
This could be either because the file you are playing is corrupted. Or
you just have trimmed your log way too much for me to be able to
interpret it correctly

Hi,
For your questions:
1. The problem always occur.
2. It happens immediatley after reboot.

I attach a longer log, I take some from the beginnig and try to add longer
version of the end.

2012-02-23 21:00:43.676 mythfrontend version: fixes/0.24
[v0.24.2-14-g9743d9c] www.mythtv.org
2012-02-23 21:00:43.677 Using runtime prefix = /usr
2012-02-23 21:00:43.677 Using configuration directory =
/home/ormetal/.mythtv
2012-02-23 21:00:43.677 ThreadPool:HTTP: Initial 1, Max 25, Timeout 60000
2012-02-23 21:00:44.211 Empty LocalHostName.
2012-02-23 21:00:44.211 Using localhost value of HTPC
2012-02-23 21:00:44.211 Testing network connectivity to '192.168.1.6'
2012-02-23 21:00:44.321 New DB connection, total: 1
2012-02-23 21:00:44.327 Connected to database 'mythconverg' at host:
192.168.1.6
2012-02-23 21:00:44.331 Closing DB connection named 'DBManager0'
2012-02-23 21:00:44.332 Connected to database 'mythconverg' at host:
192.168.1.6
2012-02-23 21:00:44.334 Current locale EN_US
2012-02-23 21:00:44.334 Reading locale defaults from
/usr/share/mythtv//locales/en_us.xml
2012-02-23 21:00:44.514 ScreenSaverX11Private: XScreenSaver support enabled
2012-02-23 21:00:44.515 DPMS is disabled.
2012-02-23 21:00:44.540 Desktop video mode: 1024x768 60.004 Hz
2012-02-23 21:00:44.552 Enabled verbose msgs:  important general audio
2012-02-23 21:00:44.567 Connecting to lcd server: 127.0.0.1:6545 (try 1 of
10)
2012-02-23 21:00:44.570 Loading en_us translation for module mythfrontend
2012-02-23 21:00:44.570 New DB connection, total: 2
2012-02-23 21:00:44.570 Connected to database 'mythconverg' at host:
192.168.1.6
2012-02-23 21:00:44.577 LIRC: Successfully initialized '/dev/lircd' using
'/home/ormetal/.mythtv/lircrc' config
2012-02-23 21:00:44.578 JoystickMenuThread: Joystick disabled - Failed to
read /home/ormetal/.mythtv/joystickmenurc
2012-02-23 21:00:44.604 Using Frameless Window
2012-02-23 21:00:44.604 Using Full Screen Window
2012-02-23 21:00:44.726 Using the OpenGL painter
2012-02-23 21:00:44.779 OpenGL: OpenGL vendor  : NVIDIA Corporation
2012-02-23 21:00:44.779 OpenGL: OpenGL renderer: GeForce 9400 GT/PCI/SSE2
2012-02-23 21:00:44.779 OpenGL: OpenGL version : 3.2.0 NVIDIA 195.36.24
2012-02-23 21:00:44.779 OpenGL: Max texture size: 8192 x 8192
2012-02-23 21:00:44.779 OpenGL: Max texture units: 4
2012-02-23 21:00:44.779 OpenGL: Direct rendering: Yes
2012-02-23 21:00:44.779 OpenGL: Initialised MythRenderOpenGL
2012-02-23 21:00:45.156 New DB connection, total: 3
2012-02-23 21:00:45.157 Current MythTV Schema Version (DBSchemaVer): 1264
2012-02-23 21:00:45.159 Connected to database 'mythconverg' at host:
192.168.1.6
2012-02-23 21:00:45.250 ThemeInfo, Warning: Unable to open themeinfo.xml
for /usr/share/mythtv/themes/BlackCurves-OSD/themeinfo.xml
2012-02-23 21:00:45.250 ThemeInfo, Error: The theme
(/usr/share/mythtv/themes/BlackCurves-OSD) is missing a themeinfo.xml file.
2012-02-23 21:00:45.252 ThemeInfo, Warning: Unable to open themeinfo.xml
for /usr/share/mythtv/themes/Gray-OSD/themeinfo.xml
2012-02-23 21:00:45.252 ThemeInfo, Error: The theme
(/usr/share/mythtv/themes/Gray-OSD) is missing a themeinfo.xml file.
2012-02-23 21:00:45.414 Pulse: PulseAudio not running
2012-02-23 21:00:45.460 AO: Sample rate 48000 is supported
2012-02-23 21:00:45.460 AO: Format signed 24 bit MSB is supported
2012-02-23 21:00:45.460 AO: Format signed 32 bit is supported
2012-02-23 21:00:45.460 AO: 2 channel(s) are supported
2012-02-23 21:00:45.477 AO: Killing AudioOutputDSP
2012-02-23 21:00:45.477 Found ALSA:default:CARD=Intel
(ALSA:default:CARD=Intel
Device supports up to 2.0)
2012-02-23 21:00:45.582 Registering Internal as a media playback plugin.
2012-02-23 21:00:45.583 No plugins directory /usr/lib/mythtv/plugins
2012-02-23 21:00:45.593 MediaMonitorUnix::AddDevice() - empty device path.
2012-02-23 21:00:45.594 MediaMonitorUnix::AddDevice() - empty device path.
2012-02-23 21:00:45.594 MediaMonitorUnix::AddDevice() - empty device path.
2012-02-23 21:00:45.607 Found mainmenu.xml for theme 'blue-abstract-wide'
2012-02-23 21:00:45.692 MythCoreContext: Connecting to backend server:
192.168.1.6:6543 (try 1 of 1)
2012-02-23 21:00:45.692 Using protocol version 63
2012-02-23 21:00:51.178 TV: Attempting to change from None to WatchingLiveTV
2012-02-23 21:00:51.178 MythCoreContext: Connecting to backend server:
192.168.1.6:6543 (try 1 of 1)
2012-02-23 21:00:51.178 Using protocol version 63
2012-02-23 21:00:51.400 Spawning LiveTV Recorder -- begin
2012-02-23 21:00:51.922 Spawning LiveTV Recorder -- end
2012-02-23 21:00:51.928 We have a
playbackURL(/var/lib/mythtv/livetv/1002_20120223210051.mpg) &
cardtype(DUMMY)
2012-02-23 21:00:51.928 We have a RingBuffer
2012-02-23 21:00:52.019 Pulse: PulseAudio not running
2012-02-23 21:00:52.056 AO: Sample rate 48000 is supported
2012-02-23 21:00:52.056 AO: Format signed 24 bit MSB is supported
2012-02-23 21:00:52.056 AO: Format signed 32 bit is supported
2012-02-23 21:00:52.056 AO: 2 channel(s) are supported
2012-02-23 21:00:52.073 AO: Killing AudioOutputDSP
2012-02-23 21:00:52.097 Clearing OpenGL painter cache.
2012-02-23 21:00:52.207 VDPAU: Created 2 output surfaces.
2012-02-23 21:00:52.207 VDPAU: Version 1
2012-02-23 21:00:52.207 VDPAU: Information NVIDIA VDPAU Driver Shared
Library  195.36.24  Thu Apr 22 10:39:56 PDT 2010
2012-02-23 21:00:52.207 VDPAU: Created VDPAU render device 1024x768
2012-02-23 21:00:52.253 Player(0): Video timing method: USleep with busy
wait
2012-02-23 21:00:52.253 TV: Changing from None to WatchingLiveTV
2012-02-23 21:00:52.253 TV: State is LiveTV & mctx == ctx
2012-02-23 21:00:52.254 TV: UpdateOSDInput done
2012-02-23 21:00:52.254 TV: UpdateLCD done
2012-02-23 21:00:52.254 TV: ITVRestart done
2012-02-23 21:00:52.318 VDPAU: Added 2 output surfaces (total 4, max 4)
QPainter::begin: Paint device returned engine == 0, type: 3
QPainter::setRenderHint: Painter must be active to set rendering hints
QPainter::setPen: Painter not active
QPainter::setBrush: Painter not active
QPainter::drawRects: Painter not active
QPainter::end: Painter not active, aborted
2012-02-23 21:00:52.341 VDPAU Painter: Failed to create VDPAU UI bitmap.
QPainter::begin: Paint device returned engine == 0, type: 3
QPainter::setRenderHint: Painter must be active to set rendering hints
QPainter::setPen: Painter not active
QPainter::setBrush: Painter not active
QPainter::drawRects: Painter not active
QPainter::end: Painter not active, aborted

Next, this chunk repeat itself many times:
2012-02-23 21:00:52.681 VDPAU Painter: Failed to create VDPAU UI bitmap.
2012-02-23 21:00:53.996 [h264 @ 0x14d9b00]non-existing PPS referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS 0 referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]decode_slice_header error
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]no frame!
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS 0 referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]decode_slice_header error
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]no frame!
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS 0 referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]decode_slice_header error
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]no frame!
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]non-existing PPS 0 referenced
2012-02-23 21:00:53.997 [h264 @ 0x14d9b00]decode_slice_header error

and then:
2012-02-23 21:00:56.581 VDPAU Painter: Clearing VDPAU painter cache.
2012-02-23 21:00:56.583 MythPainter: 22 images not yet de-allocated.
2012-02-23 21:00:56.597 Clearing OpenGL painter cache.
2012-02-23 21:00:56.694 VDPAU: Created 2 output surfaces.
2012-02-23 21:00:56.694 VDPAU: Created VDPAU render device 1024x768
2012-02-23 21:00:56.717 Player(0): Forcing decode extra audio option on
(Video method requires it).
2012-02-23 21:00:56.718 AFD: Opened codec 0xb478d990, id(H264) type(Video)
2012-02-23 21:00:56.718 AFD: codec AAC/LATM has 2 channels
2012-02-23 21:00:56.719 AFD: Opened codec 0xaca8e7b0, id(AAC/LATM)
type(Audio)
2012-02-23 21:00:56.719 AFD: Audio Track #1 is A/V stream #1 and has 1
channels in the Undetermined language(7695972).
2012-02-23 21:00:56.719 AFD: Opened codec 0xaca8ed70, id(DVB_SUBTITLE)
type(Subtitle)
2012-02-23 21:00:56.719 AFD: Opened codec 0xae532070, id(DVB_SUBTITLE)
type(Subtitle)
2012-02-23 21:00:56.719 AFD: Opened codec 0xae5327f0, id(DVB_SUBTITLE)
type(Subtitle)
2012-02-23 21:00:56.720 AFD: Selected track 1: Undetermined AAC_LATM 1ch
(A/V Stream #1)
2012-02-23 21:00:56.720 AFD: Initializing audio parms from audio track #1
2012-02-23 21:00:56.720 AFD: Audio format changed
from id(NONE)     -1Hz -1ch -1bps     to id(AAC/LATM)  48000Hz  1ch 16bps

2012-02-23 21:00:56.720 AO: Killing AudioOutputDSP
2012-02-23 21:00:56.834 Pulse: PulseAudio not running
2012-02-23 21:00:56.871 AO: Sample rate 48000 is supported
2012-02-23 21:00:56.871 AO: Format signed 24 bit MSB is supported
2012-02-23 21:00:56.871 AO: Format signed 32 bit is supported
2012-02-23 21:00:56.871 AO: 2 channel(s) are supported
2012-02-23 21:00:56.888 AO: Needs upmix from 1 -> 2 channels
2012-02-23 21:00:56.888 AO: Killing AudioOutputDSP
2012-02-23 21:00:56.888 AO: Original codec was AAC/LATM, signed 16 bit, 48
kHz, 1 channels
2012-02-23 21:00:56.888 AO: enc(0), passthru(0), canAC3(0), canDTS(0),
canLPCM(0), configured_channels(2), 2 channels supported(1)
2012-02-23 21:00:56.888 AO: Audio processing enabled
2012-02-23 21:00:56.888 AO: Opening audio device 'default:CARD=Intel' ch
2(1) sr 48000 sf signed 32 bit reenc 0
2012-02-23 21:00:56.921 ALSA: SetParameters(format=10, channels=2,
rate=48000, buffer_time=500000, period_time=4)
2012-02-23 21:00:56.922 ALSA: Buffer size range from 2048 to 16384
2012-02-23 21:00:56.922 ALSA: Period size range from 1024 to 1024
2012-02-23 21:00:56.922 ALSA: Requested 500000us got 341333 buffer time
2012-02-23 21:00:56.923 ALSA: Hardware audio buffer cur: 4096 need: 6016
max allowed: 32768
2012-02-23 21:00:56.923 ALSA, Error: Setting hardware audio buffer size to
6016
2012-02-23 21:00:56.925 ALSA, Error: Error opening
/proc/asound/card0/pcm0p/sub0/prealloc: Permission denied.
2012-02-23 21:00:56.925 ALSA, Error: Try to manually increase audio buffer
with: echo 6016 | sudo tee /proc/asound/card0/pcm0p/sub0/prealloc
2012-02-23 21:00:56.925 ALSA, Error: Unable to sufficiently increase ALSA
hardware buffer size - underruns are likely
2012-02-23 21:00:56.960 ALSA: SetParameters(format=10, channels=2,
rate=48000, buffer_time=500000, period_time=4)
2012-02-23 21:00:56.960 ALSA: Buffer size range from 2048 to 16384
2012-02-23 21:00:56.960 ALSA: Period size range from 1024 to 1024
2012-02-23 21:00:56.960 ALSA: Buffer time = 341333 us
2012-02-23 21:00:56.960 ALSA: Period time = 16 periods
2012-02-23 21:00:56.961 ALSA: Buffer size = 16384 | Period size = 1024
2012-02-23 21:00:56.963 ALSA: found playback control PCM on mixer device
default
2012-02-23 21:00:56.963 ALSA: mixer volume range on mixer device
default/PCM - min 0, max 255, range 255
2012-02-23 21:00:56.963 ALSA: mixer device default/PCM set up successfully
2012-02-23 21:00:56.964 ALSA: channel 0 volume set 72 => 184
2012-02-23 21:00:56.964 ALSA: channel 1 volume set 72 => 184
2012-02-23 21:00:56.964 ALSA: get volume channel 0: 72
2012-02-23 21:00:56.964 ALSA: channel 0 volume set 72 => 184
2012-02-23 21:00:56.964 ALSA: channel 1 volume set 72 => 184
2012-02-23 21:00:56.965 AO: Audio fragment size: 4096
2012-02-23 21:00:56.965 AO: Audio Stretch Factor: 1
2012-02-23 21:00:56.965 AO: Ending Reconfigure()
2012-02-23 21:00:56.965 AudioPlayer: Enabling Audio
2012-02-23 21:00:56.965 AO: Needs upmix from 1 -> 2 channels
2012-02-23 21:00:56.965 AO: Reconfigure(): No change -> exiting
2012-02-23 21:00:56.967 AO: kickoffOutputAudioLoop: pid = 2727
2012-02-23 21:00:56.967 AO: OutputAudioLoop: Play Event
2012-02-23 21:00:56.988 AFD: Resetting byte context eof (livetv 1 was eof 0)
2012-02-23 21:00:57.004 AO: Pause 0
2012-02-23 21:00:57.005 AO: Pause 1
2012-02-23 21:00:57.008 AO: OutputAudioLoop: audio paused
2012-02-23 21:00:57.014 [h264_vdpau @ 0x14d9b00]mmco: unref short failure
2012-02-23 21:00:57.028 AFD: Audio stream changed
2012-02-23 21:00:57.028 AFD: Selected track 1: Undetermined AAC_LATM 1ch
(A/V Stream #1)
2012-02-23 21:00:57.029 AFD: Initializing audio parms from audio track #1
2012-02-23 21:00:57.029 AFD: Audio format changed
from id(AAC/LATM)  48000Hz  1ch 16bps     to id(AAC/LATM)  48000Hz  2ch
16bps
2012-02-23 21:00:57.029 AO: SetEffDsp: 4800000
2012-02-23 21:00:57.029 AO: Killing AudioOutputDSP
2012-02-23 21:00:57.043 AO: OutputAudioLoop: Stop Event
2012-02-23 21:00:57.043 AO: kickoffOutputAudioLoop exiting
2012-02-23 21:00:57.044 AO: Original codec was AAC/LATM, signed 16 bit, 48
kHz, 2 channels
2012-02-23 21:00:57.044 AO: enc(0), passthru(0), canAC3(0), canDTS(0),
canLPCM(0), configured_channels(2), 2 channels supported(1)
2012-02-23 21:00:57.044 AO: Audio processing enabled
2012-02-23 21:00:57.044 AO: Opening audio device 'default:CARD=Intel' ch
2(2) sr 48000 sf signed 32 bit reenc 0
2012-02-23 21:00:57.079 ALSA: SetParameters(format=10, channels=2,
rate=48000, buffer_time=500000, period_time=4)
2012-02-23 21:00:57.079 ALSA: Buffer size range from 2048 to 16384
2012-02-23 21:00:57.079 ALSA: Period size range from 1024 to 1024
2012-02-23 21:00:57.079 ALSA: Buffer time = 341333 us
2012-02-23 21:00:57.079 ALSA: Period time = 16 periods
2012-02-23 21:00:57.080 ALSA: Buffer size = 16384 | Period size = 1024
2012-02-23 21:00:57.080 ALSA: found playback control PCM on mixer device
default
2012-02-23 21:00:57.080 ALSA: mixer volume range on mixer device
default/PCM - min 0, max 255, range 255
2012-02-23 21:00:57.080 ALSA: mixer device default/PCM set up successfully
2012-02-23 21:00:57.080 ALSA: channel 0 volume set 72 => 184
2012-02-23 21:00:57.080 ALSA: channel 1 volume set 72 => 184
2012-02-23 21:00:57.080 ALSA: get volume channel 0: 72
2012-02-23 21:00:57.080 ALSA: channel 0 volume set 72 => 184
2012-02-23 21:00:57.080 ALSA: channel 1 volume set 72 => 184
2012-02-23 21:00:57.080 AO: Audio fragment size: 4096
2012-02-23 21:00:57.080 AO: Audio Stretch Factor: 1
2012-02-23 21:00:57.080 AO: Ending Reconfigure()
2012-02-23 21:00:57.081 AO: Pause 0
2012-02-23 21:00:57.082 VDPAU: Added 2 output surfaces (total 4, max 4)
2012-02-23 21:00:57.082 [h264_vdpau @ 0x14d9b00]mmco: unref short failure
2012-02-23 21:00:57.082 AO: kickoffOutputAudioLoop: pid = 2727
2012-02-23 21:00:57.082 AO: OutputAudioLoop: Play Event
2012-02-23 21:00:57.094 [h264_vdpau @ 0x14d9b00]mmco: unref short failure
QPainter::begin: Paint device returned engine == 0, type: 3
QPainter::setRenderHint: Painter must be active to set rendering hints
QPainter::setPen: Painter not active
QPainter::setBrush: Painter not active
QPainter::drawRects: Painter not active
QPainter::end: Painter not active, aborted
2012-02-23 21:00:57.159 VDPAU Painter: Failed to create VDPAU UI bitmap.
QPainter::begin: Paint device returned engine == 0, type: 3
QPainter::setRenderHint: Painter must be active to set rendering hints
QPainter::setPen: Painter not active
QPainter::setBrush: Painter not active
QPainter::drawRects: Painter not active
QPainter::end: Painter not active, aborted

again, this last chunk repeat itself for a long time.

about what I run here - its liveTV based on DTT.
It was fine for a long while, until one update (using your repositories)
something like 3 months ago.
I had many things going on back then so I wasn't able to try and solve it
then.

Again,
I'm very grateful for your help.

-- 

* **Please consider the environment before printing this e-mail *
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://www.mythtv.org/pipermail/mythtv-users/attachments/20120223/a7baa4ee/attachment.html 