2013-04-20, 16:02
I need to use the custom line "plughw:0,3" to get passthrough working right on my Revo, has worked fine for Eden and older versions. The option just isn't there on Frodo.... Where is it?
2013-04-20, 17:08
In Eden only, From Frodo >= AudioEngine (wiki) is a rewrite so this option is gone and never coming back. You remove any asound.conf or .asoundrc and let Audioengine do its thing.
Follow the link also for how to setup xbmc after.
uNi
Follow the link also for how to setup xbmc after.
uNi
2013-04-20, 18:06
This is a clean install, so there's no old configs to delete (After installing RaspBMC on 2 Pi's today, I wasn't impressed at having to go steal a mouse from another box to install XBMCbuntu).
Ok, time for more details then It's an Atom 230 / ION based Revo. Audio chain is HDMI>TV>Optical>Receiver.
I've set it up for HDMI, 5.1, AC3 & DTS as the options, since this is what my receiver supports.
Audio output device: HDA NVidia, HDMI (Only option that works)
Passthrough output device: HDA NVidia, HDMI (ONLY OPTION)
Gui sounds, music and stereo audio work fine, passthrough does nothing, no error messages, just nothing.
So how exactly do I get this to fly properly on this box? I'm happy to putty in to the box and edit stuff if you have suggestions. The only reason I'm even bothering to upgrade from Eden is to keep the versions inline with the 2 Pi's and fix the incompatibility of the official remote app with tv shows since we're chucking the MCE remotes in favor of the 3 android phones and 2 tablets that are in the household now.
Ok, time for more details then It's an Atom 230 / ION based Revo. Audio chain is HDMI>TV>Optical>Receiver.
I've set it up for HDMI, 5.1, AC3 & DTS as the options, since this is what my receiver supports.
Audio output device: HDA NVidia, HDMI (Only option that works)
Passthrough output device: HDA NVidia, HDMI (ONLY OPTION)
Gui sounds, music and stereo audio work fine, passthrough does nothing, no error messages, just nothing.
So how exactly do I get this to fly properly on this box? I'm happy to putty in to the box and edit stuff if you have suggestions. The only reason I'm even bothering to upgrade from Eden is to keep the versions inline with the 2 Pi's and fix the incompatibility of the official remote app with tv shows since we're chucking the MCE remotes in favor of the 3 android phones and 2 tablets that are in the household now.
2013-04-20, 21:13
Are you very sure your TV is able to passthrough DTS to the receiver ? What make and model is that?
2013-04-20, 22:05
It's a Toshiba 42AV635D.
It worked under Eden with DTS enabled and playing content marked as DTS, so I'm running under the (maybe dangerous) assumption that it can.
I'm getting no passthrough from AC3 content on Frodo either though, there's no reason that enabling DTS would cause AC3 to break as well is there?
It worked under Eden with DTS enabled and playing content marked as DTS, so I'm running under the (maybe dangerous) assumption that it can.
I'm getting no passthrough from AC3 content on Frodo either though, there's no reason that enabling DTS would cause AC3 to break as well is there?
2013-04-20, 22:06
The only way to be sure what TV is capable of is via /var/log/Xorg.0.log when xorg.conf is set to debug.
Im gonna go ahead and say this TV does not do that until I see such a log,
Eden vs Frodo when it comes to audio is like comparing rocket propulsion to a warp drive.
uNi
Im gonna go ahead and say this TV does not do that until I see such a log,
Eden vs Frodo when it comes to audio is like comparing rocket propulsion to a warp drive.
uNi
2013-06-21, 09:54
Regarding to my question here
http://forum.xbmc.org/showthread.php?tid...pid1447409
http://forum.xbmc.org/showthread.php?tid...pid1447409
(2013-04-20, 22:05)Amphetamine Wrote: I'm getting no passthrough from AC3 content on Frodo either though, there's no reason that enabling DTS would cause AC3 to break as well is there?Correct me if I'm wrong but I would say there is no frodo that delivers IEC compliant passthrough via optical (not talking about hdmi). Beside checking that in alsa API (for pcm and passthrough the same device without parameter is called, as given in pulseaudio config file) I also monitored the spdif stream that confirms this. To send it properly the non-audio bit must be set by some means (well, some receivers can do without, guessing the format but they do not have to). Currently, as one and the same device is used for pcm and passthrough, user can (using .asoundrc) force non-audio bit permanently but then any playback will be treated as non-audio. Thus the custom device option was the easiest way of delivering valid stream.
(2013-04-20, 17:08)uNiversal Wrote: In Eden only, From Frodo >= AudioEngine (wiki) is a rewrite so this option is gone and never coming back. You remove any asound.conf or .asoundrc and let Audioengine do its thing.If for reason unknown the option is removed then at least AES0 option should be added to the device name when passtrhough is used (as mplayer does). But currently frodo is useless, unless there is a link "how to setup xbmc after"?
Follow the link also for how to setup xbmc after.
2013-06-21, 10:47
Sure, will do, however not sooner that in 4 days. I'll enable debug prints in pulseaudio.
Anyway, as the same device can be selected for normal audio and passthrough, the xbmc calls pulseaudio -> that used its profile definition -> that uses its 'iec958:%f' to call alsa -> then it calls alsa 'iec958' device. And it calls the same for for audio data and passthrough data.
Anyway, as the same device can be selected for normal audio and passthrough, the xbmc calls pulseaudio -> that used its profile definition -> that uses its 'iec958:%f' to call alsa -> then it calls alsa 'iec958' device. And it calls the same for for audio data and passthrough data.
2013-06-21, 11:33
@pru1
You mention pulseaudio in your post, but AFAIK there is no support for Passthrough when using pulseaudio in Frodo.
You mention pulseaudio in your post, but AFAIK there is no support for Passthrough when using pulseaudio in Frodo.
2013-06-21, 14:28
Ah, yes, it's all getting worse, removed passthrough, removed custom device, it still plays video 
To clarify - I use raspbmc, downgraded, so pulseaudio is in v.2.1 actually.
Is removal of custom pasthrough device setting related to non working passthrough in pulseaudio 3 or some independent reason? From the statement few posts above one can read it's gone forever. Any plans then for doing passthrough?
To clarify - I use raspbmc, downgraded, so pulseaudio is in v.2.1 actually.
Is removal of custom pasthrough device setting related to non working passthrough in pulseaudio 3 or some independent reason? From the statement few posts above one can read it's gone forever. Any plans then for doing passthrough?
2013-06-21, 16:29
No. It is just not needed anymore. As Audio Engine is able to enumerate all the devices itself. But the PulseAudio Sink is not ready and it is very basic for now.
2013-07-01, 22:50
(2013-06-21, 10:10)fritsch Wrote: Post a logfile, remove all your custom asound.conf files and see.Please see the log files at the end of the post.
But short preface first:
In the system there is one active usb sound card, which provides default+iec958, however they cannot be used simultaneously at the same time (as this is one physical device).
If I understand pulseaudio correctly there are two different things:
- sink formats: specifies what formats particular sink supports, so for example
'pactl set-sink-formats 1 "pcm; ac3-iec61937; dts-iec61937"' says the pcm and both passthrough types are supported
- profiles: they group few (or one only) sinks available simultaneously, for example:
'pactl set-card-profile 0 output:iec958-stereo'
sets active one only output sink
or
'pactl set-card-profile 0 output:analog-stereo'
sets active one only output sink
or
'pactl set-card-profile 0 milti-sink'
sets active few outputs at once (in my case important are 'output:analog-stereo' and 'output:iec958-stereo')
As mentioned, the card can use only one sink at a time, thus for the sake of pulseaudio the 'output:iec958-stereo' is selected, and is set to supports all needed formats. In such case xbmc shows this sink in both audio and passthrough fields. Playing stereo or passthrough makes no difference in the meaning of alsa access (so always 'iec950:0' is used). As from the data-stream point of view the passthrough is treated as stereo, there must be additional information passed down to alsa to switch the passthrough on/off. In the mentioned scenarion there is no such information, so there is no way alsa can send passthrough correctly. For that purpose the custom passthrough device option in xbmc menu would to the thick, putting there e.g. 'iec958:AES0=0x02'.
The temporary workaround I found is:
- select multi-sink profile
- set only 'pcm' format for analog sink and only 'ac3-iec61937; dts-iec61937' for iec958
- edit pulseaudio profiles default.conf, changing 'iec958:%f' to 'iec958:%f,AES0=0x02' (set AESn as you wish) or do it in asound.conf/.asoundrc (so any access to iec958 will set passthrough mode)
- in xbmc select analog sink for audio device and iec958 for passthrough
So some proper handling from xbmc side is needed.
Log files - there were two playbacks, one pcm and second passthrough.
xbmc.log
Code:
02:54:40 T:3036107696 NOTICE: -----------------------------------------------------------------------
02:54:41 T:3036107696 NOTICE: Starting XBMC (12.1 Git:20130317-0d373cc), Platform: Linux (Debian GNU/Linux 7.0 (wheezy), 3.6.11 armv6l). Built on Mar 22 2013
02:54:41 T:3036107696 NOTICE: special://xbmc/ is mapped to: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc
02:54:41 T:3036107696 NOTICE: special://xbmcbin/ is mapped to: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/lib/xbmc
02:54:41 T:3036107696 NOTICE: special://masterprofile/ is mapped to: /home/pi/.xbmc/userdata
02:54:41 T:3036107696 NOTICE: special://home/ is mapped to: /home/pi/.xbmc
02:54:41 T:3036107696 NOTICE: special://temp/ is mapped to: /home/pi/.xbmc/temp
02:54:41 T:3036107696 NOTICE: The executable running is: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/lib/xbmc/xbmc.bin
02:54:41 T:3036107696 NOTICE: Local hostname: raspbmc
02:54:41 T:3036107696 NOTICE: Log File is located: /home/pi/.xbmc/temp/xbmc.log
02:54:41 T:3036107696 NOTICE: -----------------------------------------------------------------------
02:54:41 T:3036107696 NOTICE: load settings...
02:54:41 T:3036107696 NOTICE: special://profile/ is mapped to: special://masterprofile/
02:54:41 T:3036107696 NOTICE: loading special://masterprofile/guisettings.xml
02:54:41 T:3036107696 NOTICE: Getting hardware information now...
00:54:41 T:3036107696 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
00:54:41 T:3036107696 NOTICE: Loaded playercorefactory configuration
00:54:41 T:3036107696 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
00:54:41 T:3036107696 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
00:54:41 T:3036107696 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
00:54:41 T:3036107696 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
<advancedsettings>
<cputempcommand>/opt/vc/bin/vcgencmd measure_temp | sed -e 's/temp=\([0-9]*\).*/\1 C/'</cputempcommand>
<gputempcommand>/opt/vc/bin/vcgencmd measure_temp | sed -e 's/temp=\([0-9]*\).*/\1 C/'</gputempcommand>
<network>
<cachemembuffersize>2097152</cachemembuffersize>
</network>
<imageres>540</imageres>
<fanartres>720</fanartres>
<video>
<defaultplayer>omxplayer</defaultplayer>
<defaultdvdplayer>omxplayer</defaultdvdplayer>
</video>
<audio>
<defaultplayer>omxplayer</defaultplayer>
<streamsilence>false</streamsilence>
</audio>
</advancedsettings>
00:54:41 T:3036107696 NOTICE: Getting hardware information now...
00:54:41 T:3036107696 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
00:54:41 T:3036107696 NOTICE: Default DVD Player: omxplayer
00:54:41 T:3036107696 NOTICE: Default Video Player: omxplayer
00:54:41 T:3036107696 NOTICE: Default Audio Player: omxplayer
00:54:41 T:3036107696 NOTICE: Enabled debug logging due to GUI setting (2)
00:54:41 T:3036107696 NOTICE: Log level changed to 2
00:54:41 T:3036107696 NOTICE: Loading media sources from special://masterprofile/sources.xml
00:54:41 T:3036107696 INFO: creating subdirectories
00:54:41 T:3036107696 INFO: userdata folder: special://masterprofile/
00:54:41 T:3036107696 INFO: recording folder:
00:54:41 T:3036107696 INFO: screenshots folder:
00:54:41 T:3036107696 INFO: thumbnails folder: special://masterprofile/Thumbnails
00:54:41 T:3036107696 INFO: load language info file: special://xbmc/language/English/langinfo.xml
00:54:41 T:3036107696 INFO: global locale set to C
00:54:41 T:3036107696 INFO: load English language file, from path: special://xbmc/language/
00:54:41 T:3036107696 NOTICE: Running database version Addons15
00:54:42 T:3036107696 INFO: ADDON: cpluff: 'Could not read plug-in directory /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/lib/xbmc/addons: No such file or directory'
00:54:42 T:3036107696 NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001)
00:54:42 T:3018138640 NOTICE: Thread XBMC Peripherals start, auto delete: false
00:54:42 T:3026527248 NOTICE: Thread CEC Adapter start, auto delete: false
00:54:42 T:3009750032 NOTICE: Thread XBMC Peripherals start, auto delete: false
00:54:42 T:3036107696 INFO: LIRC Initialize: using: /var/run/lirc/lircd
00:54:42 T:3036107696 INFO: LIRC Initialize: sucessfully started
00:54:42 T:3036107696 INFO: UDisks: Added /tmp
00:54:43 T:3036107696 NOTICE: Raspberry PI firmware version: Mar 7 2013 19:34:17
Copyright (c) 2012 Broadcom
version 375458 (release)
00:54:43 T:3036107696 NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi
00:54:43 T:3036107696 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 720 x 576 for display 0 with 720 x 576 @ 50.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 50.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 24.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 75.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 75.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 960 x 720 for display 0 with 1024 x 768 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 960 x 720 for display 0 with 1024 x 768 @ 75.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 960 x 720 for display 0 with 1152 x 864 @ 75.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 900 x 720 for display 0 with 1280 x 1024 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 900 x 720 for display 0 with 1280 x 1024 @ 75.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 960 x 720 for display 0 with 1600 x 1200 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found resolution 1152 x 720 for display 0 with 1920 x 1200 @ 60.000000 Hz
00:54:43 T:3036107696 NOTICE: Found ([email protected]) at 18, setting to RES_DESKTOP at 16
00:54:43 T:3036107696 NOTICE: Checking resolution 16
00:54:43 T:3036107696 NOTICE: GL_VENDOR = Broadcom
00:54:43 T:3036107696 NOTICE: GL_RENDERER = VideoCore IV HW
00:54:43 T:3036107696 NOTICE: GL_VERSION = OpenGL ES 2.0
00:54:43 T:3036107696 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
00:54:43 T:3036107696 NOTICE: GL_EXTENSIONS = GL_OES_compressed_ETC1_RGB8_texture GL_OES_compressed_paletted_texture GL_OES_texture_npot GL_OES_depth24 GL_OES_vertex_half_float GL_OES_EGL_image GL_OES_EGL_image_external GL_EXT_discard_framebuffer GL_OES_rgb8_rgba8 GL_OES_depth32 GL_OES_mapbuffer GL_EXT_texture_format_BGRA8888 GL_APPLE_rgb_422 GL_EXT_debug_marker
00:54:43 T:3036107696 INFO: GLES: Maximum texture width: 2048
00:54:43 T:3036107696 INFO: load default splash image: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/media/Splash.png
00:54:43 T:3036107696 INFO: load keymapping
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/hama_mce.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
00:54:44 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/mouse.xml
00:54:45 T:3036107696 INFO: Loading special://xbmc/system/keymaps/remote.xml
00:54:45 T:3026527248 ERROR: CecLogMessage - failed to release the previous LA
00:54:45 T:3026527248 ERROR: CecLogMessage - could not open a connection (try 1)
00:54:46 T:3036107696 INFO: Loading special://xbmc/system/Lircmap.xml
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'mceusb'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'XboxDVDDongle'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'anysee'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'iMON-PAD'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'MCE_via_iMON'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'TwinHanRemote'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'linux-input-layer'
00:54:46 T:3036107696 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
00:54:46 T:3036107696 INFO: * Linking remote mapping for 'linux-input-layer' to 'devinput'
00:54:46 T:3036107696 INFO: * Adding remote mapping for device 'mediacenter'
00:54:46 T:3036107696 INFO: GUI format 1280x720, Display 1280x720 @ 60.00 - Full Screen
00:54:46 T:3036107696 NOTICE: Running database version Addons15
00:54:46 T:3036107696 NOTICE: Running database version ViewModes4
00:54:46 T:3036107696 NOTICE: Running database version Textures13
00:54:46 T:3036107696 NOTICE: Running database version MyMusic32
00:54:46 T:3036107696 NOTICE: Running database version MyVideos75
00:54:46 T:3036107696 NOTICE: Running database version TV22
00:54:46 T:3036107696 NOTICE: Running database version Epg7
00:54:46 T:3036107696 NOTICE: start dvd mediatype detection
00:54:46 T:3036107696 NOTICE: initializing playlistplayer
00:54:46 T:3036107696 NOTICE: DONE initializing playlistplayer
00:54:46 T:3036107696 INFO: DPMS: not supported on this platform
00:54:46 T:2974884880 NOTICE: Thread CDetectDVDMedia start, auto delete: false
00:54:46 T:3036107696 INFO: Unloading old skin ...
00:54:46 T:3036107696 INFO: load skin from: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence (version: 2.1.4)
00:54:46 T:3036107696 INFO: load fonts for skin...
00:54:46 T:3036107696 INFO: Loading fonts from /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p/Font.xml
00:54:46 T:3036107696 INFO: Loading skin includes from /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p/includes.xml
00:54:48 T:3036107696 INFO: load new skin...
00:54:48 T:3036107696 INFO: Loading user windows, path /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p
00:54:48 T:3036107696 INFO: initialize new skin...
00:54:48 T:3036107696 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
00:54:48 T:3036107696 INFO: Loading /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/sounds.xml
00:54:48 T:3036107696 INFO: CAEWAVLoader::Initialize - Sound Loaded: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/cursor.wav
00:54:48 T:3036107696 INFO: CAEWAVLoader::Initialize - Sound Loaded: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/click.wav
00:54:48 T:3036107696 INFO: CAEWAVLoader::Initialize - Sound Loaded: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/back.wav
00:54:48 T:3036107696 INFO: CAEWAVLoader::Initialize - Sound Loaded: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/shutter.wav
00:54:48 T:3036107696 INFO: CAEWAVLoader::Initialize - Sound Loaded: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/notify.wav
00:54:48 T:3036107696 INFO: CAEWAVLoader::Initialize - Sound Loaded: /home/pi/.upgrade/xbmc-rbp-12-1-20130322/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/out.wav
00:54:48 T:3036107696 INFO: skin loaded...
00:54:48 T:2868315152 NOTICE: Thread CEC Adapter Update Thread start, auto delete: false
00:54:49 T:3036107696 INFO: JSONRPC v6.0.3: Successfully initialized
00:54:49 T:3036107696 INFO: initializing python engine.
00:54:49 T:3036107696 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
00:54:49 T:2859389968 NOTICE: Thread XBPyThread start, auto delete: false
00:54:49 T:3036107696 INFO: removing tempfiles
00:54:49 T:3036107696 NOTICE: initialize done
00:54:49 T:3036107696 NOTICE: Running the application...
00:54:49 T:2859389968 NOTICE: -->Python Interpreter Initialized<--
00:54:50 T:3036107696 INFO: GLES: Enabling VSYNC
00:54:50 T:3036107696 ERROR: GLES: Vertical Blank Syncing unsupported
00:54:50 T:3036107696 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
00:54:50 T:2859389968 NOTICE: services: {'sys.service.avahi': 'true', 'sys.service.remote': 'true', 'sys.service.ftp': 'true', 'sys.service.samba': 'true', 'sys.service.ssh': 'true', 'sys.service.cron': 'false', 'sys.service.tvh': 'false'}
00:54:50 T:2859389968 NOTICE: enable service: avahi-daemon
00:54:50 T:2859389968 NOTICE: enable service: eventlircd
00:54:52 T:2848977936 NOTICE: Thread Jobworker start, auto delete: true
00:54:52 T:2859389968 NOTICE: enable service: ftp
00:54:52 T:3036107696 NOTICE: Webserver: Starting...
00:54:52 T:3036107696 NOTICE: WebServer: Started the webserver
00:54:52 T:3036107696 NOTICE: ES: Starting event server
00:54:52 T:3036107696 INFO: JSONRPC Server: Successfully initialized
00:54:52 T:3036107696 NOTICE: starting zeroconf publishing
00:54:52 T:2798646288 NOTICE: Thread CEventServer start, auto delete: false
00:54:52 T:2798646288 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
00:54:52 T:2798646288 NOTICE: UDP: Listening on port 9777
00:54:52 T:2790257680 NOTICE: Thread CTCPServer start, auto delete: false
00:54:52 T:2781869072 NOTICE: Thread Jobworker start, auto delete: true
00:54:53 T:3036107696 INFO: Found input device /dev/input/event0
00:54:53 T:3036107696 INFO: opened device 'A4Tech PS/2+USB Mouse' (file name /dev/input/event0), m_bSkipNonKeyEvents 0
00:54:53 T:2859389968 NOTICE: enable service: samba
00:54:55 T:2859389968 NOTICE: enable service: ssh
00:54:56 T:2859389968 NOTICE: disable service: cron
00:54:56 T:2859389968 NOTICE: disable service: tvheadend
00:54:59 T:2859389968 NOTICE: system settings from addon: {'sys.config.freq.arm': 800, 'sys.config.freq.gpu': 250, 'sys.config.freq.sdram': 400, 'sys.config.freq.core': 250, 'sys.config.freq.overvolt': 0, 'sys.config.decode.wvc1': '', 'sys.config.disable.overscan': '1', 'sys.config.decode.ac3': '', 'sys.config.freq.isp': 250, 'sys.config.decode.mpg2': '', 'sys.config.decode.dts': ''}
00:54:59 T:2859389968 NOTICE: system settings from /boot/config.txt: {'sys.config.freq.arm': 800, 'sys.config.freq.gpu': 250, 'sys.config.freq.sdram': 400, 'sys.config.freq.core': 250, 'sys.config.freq.overvolt': 0, 'sys.config.decode.wvc1': '', 'sys.config.disable.overscan': '1', 'sys.config.decode.ac3': '', 'sys.config.freq.isp': 250, 'sys.config.decode.mpg2': '', 'sys.config.decode.dts': ''}
00:54:59 T:2859389968 INFO: Scriptresult: Success
00:54:59 T:2859389968 INFO: Python script stopped
00:55:07 T:3036107696 INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
00:55:09 T:3036107696 INFO: Python, unloading python shared library because no scripts are running anymore
00:55:24 T:3036107696 INFO: Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY
00:55:24 T:3036107696 ERROR: Error: Requested setting (videoscreen.screen) was not found. It must be case-sensitive
00:55:24 T:3036107696 ERROR: Previous line repeats 2 times.
00:55:34 T:3036107696 INFO: Loading skin file: MyMusicSongs.xml, load type: KEEP_IN_MEMORY
00:55:35 T:2859389968 NOTICE: Thread Background Loader start, auto delete: false
00:55:37 T:2859389968 NOTICE: Thread Background Loader start, auto delete: false
00:55:37 T:2762990608 NOTICE: Thread CFileCache start, auto delete: false
00:55:37 T:2859389968 INFO: WAVCodec::Init - Sample Rate: 11025, Bits Per Sample: 16, Channels: 2
00:55:37 T:2859389968 INFO: WAVCodec::Init - WAVE_FORMAT_PCM detected
00:55:38 T:2762990608 NOTICE: Thread CFileCache start, auto delete: false
00:55:38 T:2859389968 INFO: WAVCodec::Init - Sample Rate: 44100, Bits Per Sample: 0, Channels: 2
00:55:38 T:2859389968 ERROR: WAVCodec::Init - Invalid data in WAVE header
00:55:38 T:2762990608 NOTICE: Thread CFileCache start, auto delete: false
00:55:38 T:2859389968 INFO: WAVCodec::Init - Sample Rate: 44100, Bits Per Sample: 16, Channels: 2
00:55:38 T:2859389968 INFO: WAVCodec::Init - WAVE_FORMAT_PCM detected
00:55:38 T:2859389968 NOTICE: Thread Background Loader start, auto delete: false
00:55:40 T:3036107696 NOTICE: COMXAEPlayer: Opening: /home/files/pcm-11025-16-2.wav
00:55:40 T:3036107696 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
00:55:40 T:2859389968 NOTICE: Thread COMXAEPlayer start, auto delete: false
00:55:40 T:2859389968 NOTICE: Creating InputStream
00:55:40 T:2859389968 NOTICE: OMXClock using video as reference
00:55:40 T:2859389968 NOTICE: Creating Demuxer
00:55:40 T:2859389968 INFO: ffmpeg[AA6ED410]: Input #0, wav, from '/home/files/pcm-11025-16-2.wav':
00:55:40 T:2859389968 INFO: ffmpeg[AA6ED410]: Duration: 00:00:05.96, bitrate: 352 kb/s
00:55:40 T:2859389968 INFO: ffmpeg[AA6ED410]: Stream #0:0: Audio: pcm_s16le ([1][0][0][0] / 0x0001), 11025 Hz, 2 channels, s16, 352 kb/s
00:55:40 T:2859389968 NOTICE: Opening audio stream: 0 source: 256
00:55:40 T:2859389968 NOTICE: Finding audio codec for: 65536
00:55:40 T:2859389968 NOTICE: Creating audio thread
00:55:40 T:2762990608 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
00:55:40 T:2859389968 NOTICE: OMXClock using video as reference
00:55:40 T:2859389968 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
00:55:40 T:2762990608 NOTICE: running thread: CDVDPlayerAudio::Process()
00:55:40 T:2762990608 NOTICE: Creating audio stream (codec id: 65536, channels: 2, sample rate: 11025, no pass-through)
00:55:40 T:2762990608 INFO: PulseAEStream::Initialized
00:55:40 T:2762990608 INFO: Sink Output : default
00:55:40 T:2762990608 INFO: Sample Rate : 11025
00:55:40 T:2762990608 INFO: Sample Format : AE_FMT_S16NE
00:55:40 T:2762990608 INFO: Channel Count : 2
00:55:40 T:2762990608 INFO: Channel Layout: FL,FR
00:55:40 T:2762990608 INFO: Frame Size : 4
00:55:40 T:2762990608 INFO: Cache Size : 24884
00:55:41 T:2744062992 NOTICE: Thread CFileCache start, auto delete: false
00:55:41 T:3036107696 INFO: WAVCodec::Init - Sample Rate: 11025, Bits Per Sample: 16, Channels: 2
00:55:41 T:3036107696 INFO: WAVCodec::Init - WAVE_FORMAT_PCM detected
00:55:41 T:2744062992 NOTICE: Thread CFileCache start, auto delete: false
00:55:41 T:3036107696 INFO: WAVCodec::Init - Sample Rate: 11025, Bits Per Sample: 16, Channels: 2
00:55:41 T:3036107696 INFO: WAVCodec::Init - WAVE_FORMAT_PCM detected
00:55:46 T:2968986640 WARNING: PulseAudio: Stream underflow
00:55:51 T:2859389968 NOTICE: COMXAEPlayer::OnExit()
00:55:51 T:2859389968 NOTICE: OMXAEPlayer: closing audio stream
00:55:51 T:2859389968 NOTICE: Closing audio stream
00:55:51 T:2859389968 NOTICE: Waiting for audio thread to exit
00:55:51 T:2762990608 NOTICE: thread end: CDVDPlayerAudio::OnExit()
00:55:51 T:2859389968 NOTICE: Closing audio device
00:55:51 T:2859389968 NOTICE: Deleting audio codec
00:55:51 T:2859389968 NOTICE: COMXAEPlayer::OnExit() deleting demuxer
00:55:51 T:2859389968 NOTICE: COMXAEPlayer::OnExit() deleting input stream
00:55:51 T:3036107696 INFO: ADDON: Dll Stopped - OpenGL Spectrum
00:55:51 T:3036107696 INFO: ADDON: Dll Destroyed - OpenGL Spectrum
00:55:51 T:3036107696 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
00:55:51 T:3036107696 NOTICE: OMXClock using video as reference
00:55:55 T:3036107696 NOTICE: COMXAEPlayer: Opening: /home/files/selva.ac3
00:55:55 T:3036107696 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
00:55:55 T:2859389968 NOTICE: Thread COMXAEPlayer start, auto delete: false
00:55:55 T:2859389968 NOTICE: Creating InputStream
00:55:55 T:2859389968 NOTICE: OMXClock using video as reference
00:55:55 T:2859389968 NOTICE: Creating Demuxer
00:55:55 T:2859389968 INFO: ffmpeg[AA6ED410]: Input #0, ac3, from '/home/files/selva.ac3':
00:55:55 T:2859389968 INFO: ffmpeg[AA6ED410]: Duration: 00:10:55.36, start: 0.000000, bitrate: 640 kb/s
00:55:55 T:2859389968 INFO: ffmpeg[AA6ED410]: Stream #0:0: Audio: ac3, 48000 Hz, 4 channels (FL+FR+SL+SR), s16, 640 kb/s
00:55:55 T:2859389968 NOTICE: Opening audio stream: 0 source: 256
00:55:55 T:2859389968 NOTICE: Finding audio codec for: 86019
00:55:55 T:2859389968 NOTICE: Creating audio thread
00:55:55 T:2762990608 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
00:55:55 T:2762990608 NOTICE: running thread: CDVDPlayerAudio::Process()
00:55:55 T:2859389968 NOTICE: OMXClock using video as reference
00:55:55 T:2859389968 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
00:55:55 T:2762990608 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (4 channels, 48000Hz)
00:55:55 T:2762990608 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)
00:55:55 T:2762990608 INFO: PulseAEStream::Initialized
00:55:55 T:2762990608 INFO: Sink Output : default
00:55:55 T:2762990608 INFO: Sample Rate : 48000
00:55:55 T:2762990608 INFO: Sample Format : AE_FMT_AC3
00:55:55 T:2762990608 INFO: Channel Count : 2
00:55:55 T:2762990608 INFO: Channel Layout: RAW,RAW
00:55:55 T:2762990608 INFO: Frame Size : 4
00:55:55 T:2762990608 INFO: Cache Size : 27840
00:56:28 T:2859389968 NOTICE: COMXAEPlayer::OnExit()
00:56:28 T:2859389968 NOTICE: OMXAEPlayer: closing audio stream
00:56:28 T:2859389968 NOTICE: Closing audio stream
00:56:28 T:2859389968 NOTICE: Waiting for audio thread to exit
00:56:28 T:2762990608 NOTICE: thread end: CDVDPlayerAudio::OnExit()
00:56:28 T:2859389968 NOTICE: Closing audio device
00:56:28 T:2859389968 NOTICE: Deleting audio codec
00:56:28 T:2859389968 NOTICE: COMXAEPlayer::OnExit() deleting demuxer
00:56:28 T:2859389968 NOTICE: COMXAEPlayer::OnExit() deleting input stream
00:56:28 T:3036107696 INFO: ADDON: Dll Stopped - OpenGL Spectrum
00:56:28 T:3036107696 INFO: ADDON: Dll Destroyed - OpenGL Spectrum
00:56:28 T:3036107696 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
00:56:28 T:3036107696 NOTICE: OMXClock using video as reference
00:56:28 T:2859389968 NOTICE: Thread Jobworker start, auto delete: truepulseaudio log (part only, with snd_pcm_open_conf printout showing what alsa device was requested)
Code:
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo is 0x0004, suspending
I: [alsa-sink] alsa-sink.c: Device suspended...
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
I: [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo:iec958-stereo-output.
I: [pulseaudio] module-device-restore.c: Synced.
I: [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:XBMC.
D: [pulseaudio] module-stream-restore.c: Not restoring volume for sink input sink-input-by-application-name:XBMC, because already set.
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo is 0x0000, resuming
I: [alsa-sink] alsa-sink.c: Trying resume...
snd_pcm_open_conf name=iec958:0
snd_pcm_open_conf name=iec958:0
snd_pcm_open_conf name=(null)
I: [alsa-sink] alsa-util.c: cannot disable ALSA period wakeups
D: [alsa-sink] alsa-util.c: Maximum hw buffer size is 5944 ms
D: [alsa-sink] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples).
I: [alsa-sink] alsa-util.c: ALSA period wakeups were not disabled
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
I: [alsa-sink] alsa-sink.c: Time scheduling watermark is 20.00ms
I: [alsa-sink] alsa-sink.c: Resumed successfully...
I: [alsa-sink] alsa-sink.c: Starting playback.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes idle, timeout in 5 seconds.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes busy.
I: [pulseaudio] resampler.c: Resampler 'ffmpeg' cannot do variable rate, reverting to resampler 'auto'.
I: [pulseaudio] resampler.c: Using resampler 'ffmpeg'
I: [pulseaudio] resampler.c: Using s16le as working format.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 3 "audio stream" on alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo with sample spec s16le 2ch 11025Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c: media.name = "audio stream"
I: [pulseaudio] sink-input.c: application.name = "XBMC"
I: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c: native-protocol.version = "26"
I: [pulseaudio] sink-input.c: application.process.id = "3362"
I: [pulseaudio] sink-input.c: application.process.user = "pi"
I: [pulseaudio] sink-input.c: application.process.host = "raspbmc"
I: [pulseaudio] sink-input.c: application.process.binary = "threaded-ml"
I: [pulseaudio] sink-input.c: application.language = "C"
I: [pulseaudio] sink-input.c: application.process.machine_id = "946979c4fb13542a97ae810151910220"
I: [pulseaudio] sink-input.c: application.process.session_id = "946979c4fb13542a97ae810151910220-1371344077.835534-191468694"
I: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:XBMC"
I: [pulseaudio] protocol-native.c: Requested tlength=1088.43 ms, minreq=20.05 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [pulseaudio] protocol-native.c: Requested latency=524.17 ms, Received latency=524.17 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=24884, base=4, prebuf=24004, minreq=884 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=24884, base=4, prebuf=24004, minreq=884 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 1088.43 ms = 524.17 ms + 2*20.05 ms + 524.17 ms
D: [alsa-sink] alsa-sink.c: Cutting sleep time for the initial iterations by half.
D: [alsa-sink] alsa-sink.c: Latency set to 524.17ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=260340
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 325028 bytes.
D: [alsa-sink] alsa-sink.c: before: 81257
D: [alsa-sink] alsa-sink.c: after: 81257
D: [alsa-sink] alsa-sink.c: Rewound 325028 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink-input.c: Have to rewind 325028 bytes on render memblockq.
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 90264 bytes.
D: [alsa-sink] alsa-sink.c: before: 22566
D: [alsa-sink] alsa-sink.c: after: 22566
D: [alsa-sink] alsa-sink.c: Rewound 90264 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink-input.c: Have to rewind 90264 bytes on render memblockq.
D: [alsa-sink] source.c: Processing rewind...
I: [pulseaudio] module-device-restore.c: Storing volume/mute for device+port sink:alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo:iec958-stereo-output.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 85852 bytes.
D: [alsa-sink] alsa-sink.c: before: 21463
D: [alsa-sink] alsa-sink.c: after: 21463
D: [alsa-sink] alsa-sink.c: Rewound 85852 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink-input.c: Have to rewind 85852 bytes on render memblockq.
D: [alsa-sink] source.c: Processing rewind...
D: [alsa-sink] protocol-native.c: Underrun on 'audio stream', 0 bytes in queue.
I: [pulseaudio] module-device-restore.c: Synced.
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 75444 bytes.
D: [alsa-sink] alsa-sink.c: before: 18861
D: [alsa-sink] alsa-sink.c: after: 18861
D: [alsa-sink] alsa-sink.c: Rewound 75444 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
I: [pulseaudio] sink-input.c: Freeing input 3 "audio stream"
I: [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-application-name:XBMC.
D: [pulseaudio] module-stream-restore.c: Not restoring volume for sink input sink-input-by-application-name:XBMC, because already set.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes busy.
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
I: [pulseaudio] sink-input.c: Created input 4 "audio stream" on alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo with sample spec s16le 2ch 48000Hz and channel map front-left,front-right
I: [pulseaudio] sink-input.c: media.name = "audio stream"
I: [pulseaudio] sink-input.c: application.name = "XBMC"
I: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client"
I: [pulseaudio] sink-input.c: native-protocol.version = "26"
I: [pulseaudio] sink-input.c: application.process.id = "3362"
I: [pulseaudio] sink-input.c: application.process.user = "pi"
I: [pulseaudio] sink-input.c: application.process.host = "raspbmc"
I: [pulseaudio] sink-input.c: application.process.binary = "threaded-ml"
I: [pulseaudio] sink-input.c: application.language = "C"
I: [pulseaudio] sink-input.c: application.process.machine_id = "946979c4fb13542a97ae810151910220"
I: [pulseaudio] sink-input.c: application.process.session_id = "946979c4fb13542a97ae810151910220-1371344077.835534-191468694"
I: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-application-name:XBMC"
I: [pulseaudio] protocol-native.c: Requested tlength=250.00 ms, minreq=20.00 ms
D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency.
D: [pulseaudio] protocol-native.c: Requested latency=105.00 ms, Received latency=105.00 ms
D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=27840, base=4, prebuf=24004, minreq=3840 maxrewind=0
D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=27840, base=4, prebuf=24004, minreq=3840 maxrewind=0
I: [pulseaudio] protocol-native.c: Final latency 250.00 ms = 105.00 ms + 2*20.00 ms + 105.00 ms
W: [pulseaudio] sink.c: Cannot change volume, Sink is connected to PASSTHROUGH input
D: [pulseaudio] source.c: Suspend cause of source alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo.monitor is 0x0010, suspending
D: [alsa-sink] alsa-sink.c: Latency set to 105.00ms
D: [alsa-sink] alsa-sink.c: hwbuf_unused=334280
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
D: [alsa-sink] alsa-sink.c: Requesting rewind due to latency change.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 301212 bytes.
D: [alsa-sink] alsa-sink.c: before: 75303
D: [alsa-sink] alsa-sink.c: after: 75303
D: [alsa-sink] alsa-sink.c: Rewound 301212 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink-input.c: Have to rewind 301212 bytes on render memblockq.
D: [alsa-sink] protocol-native.c: Requesting rewind due to end of underrun.
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 16852 bytes.
D: [alsa-sink] alsa-sink.c: before: 4213
D: [alsa-sink] alsa-sink.c: after: 4213
D: [alsa-sink] alsa-sink.c: Rewound 16852 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] sink-input.c: Have to rewind 16852 bytes on render memblockq.
D: [pulseaudio] source.c: Suspend cause of source alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo.monitor is 0x0000, resuming
D: [alsa-sink] alsa-sink.c: hwbuf_unused=0
D: [alsa-sink] alsa-sink.c: setting avail_min=87319
D: [alsa-sink] alsa-sink.c: Requested to rewind 352800 bytes.
D: [alsa-sink] alsa-sink.c: Limited to 16500 bytes.
D: [alsa-sink] alsa-sink.c: before: 4125
D: [alsa-sink] alsa-sink.c: after: 4125
D: [alsa-sink] alsa-sink.c: Rewound 16500 bytes.
D: [alsa-sink] sink.c: Processing rewind...
D: [alsa-sink] source.c: Processing rewind...
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo becomes idle, timeout in 5 seconds.
D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
I: [pulseaudio] sink-input.c: Freeing input 4 "audio stream"
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
D: [pulseaudio] flist.c: pulsecore/memblockq.c: list_items flist is full (don't worry)
I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo idle for too long, suspending ...
D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.usb-C-Media_INC._USB_Sound_Device-00-MicroII.iec958-stereo is 0x0004, suspending
2013-07-01, 22:58
This does not look like mainline code, where is this code coming from?
Edit: As I have written somewhere. The PA code is very basic, basically no real sink support. For alsa it is handled correctly and the sink opening switched accordingly. Still not sure, where your code is coming from.
Edit: As I have written somewhere. The PA code is very basic, basically no real sink support. For alsa it is handled correctly and the sink opening switched accordingly. Still not sure, where your code is coming from.
2013-07-01, 23:45
The code is raspbmc on rpi, with downgraded pulseaudio to 2.1.
The snd_pcm_open_conf printout is added by me to alsa lib for debug purposes.
Yes, I roughly saw the code and one can really find there AES= settings for passthrough, but as you said it is probably when using alsa directly, in my case there is pulseaudio in between.
I presumed the raspbmc changes things (e.g. adding usb card support via alsa+pulseaudio+AE) but my point was how this functionality could be missing. Well, if the story is that PA is very basic that could explain all the things...
The snd_pcm_open_conf printout is added by me to alsa lib for debug purposes.
Yes, I roughly saw the code and one can really find there AES= settings for passthrough, but as you said it is probably when using alsa directly, in my case there is pulseaudio in between.
I presumed the raspbmc changes things (e.g. adding usb card support via alsa+pulseaudio+AE) but my point was how this functionality could be missing. Well, if the story is that PA is very basic that could explain all the things...
