Volumio Debian Buster Beta - Raspi images debugging

Holy… Great work. The Beta 0.015 already supports every feature I need. Spotify connect, Kodi Leia (:heart_eyes: :heart_eyes: :heart_eyes:) and my own plugin for light control AmbX. And no issues so far.

Only communication with other volumios and switching from numbers to names to access the dacs left :wink:

Is there anything required to enable airplay on the latest beta?

I’ve got it recently installed on an RPi. shows up on the network (able to login via wifi etc), but doesn’t show up as an airplay endpoint.

Edit: just saw the couple related posts above. Will try those steps first. < Didnt seem to help.

volumio@mancave:~$ shairport-sync --version
3.3.6-OpenSSL-Avahi-ALSA-soxr-metadata-dbus-sysconfdir:/etc
volumio@mancave:~$  systemctl status shairport-sync
● shairport-sync.service - LSB: Shairport Synchronous AirPlay
   Loaded: loaded (/etc/init.d/shairport-sync; masked; vendor preset: enabled)
   Active: active (exited) since Thu 2021-02-11 05:34:50 UTC; 9min ago
     Docs: man:systemd-sysv-generator(8)
  Process: 2125 ExecStart=/etc/init.d/shairport-sync start (code=exited, status=0/SUCCESS)

Feb 11 05:34:50 mancave systemd[1]: Starting LSB: Shairport Synchronous AirPlay...
Feb 11 05:34:50 mancave shairport-sync[2125]: shairport-sync was built without libdaemon, so does not support dae
Feb 11 05:34:50 mancave systemd[1]: Started LSB: Shairport Synchronous AirPlay.
...skipping...
● shairport-sync.service - LSB: Shairport Synchronous AirPlay
   Loaded: loaded (/etc/init.d/shairport-sync; masked; vendor preset: enabled)
   Active: active (exited) since Thu 2021-02-11 05:34:50 UTC; 9min ago
     Docs: man:systemd-sysv-generator(8)
  Process: 2125 ExecStart=/etc/init.d/shairport-sync start (code=exited, status=0/SUCCESS)

Feb 11 05:34:50 mancave systemd[1]: Starting LSB: Shairport Synchronous AirPlay...
Feb 11 05:34:50 mancave shairport-sync[2125]: shairport-sync was built without libdaemon, so does not support dae
Feb 11 05:34:50 mancave systemd[1]: Started LSB: Shairport Synchronous AirPlay.
~

To fix multiroom/mdns run and restart the volumio service

sudo apt install libnss-mdns
volumio vrestart

You still have the wrong systemd unit file. Please try steps from Volumio Debian Buster Beta - Raspi images debugging - #380 by ashthespy

Confirm that cat /lib/systemd/system/shairport-sync.service has similar looking things as the file from the repo.

1 Like

Perfect! Works now :sunglasses:
Please add it to new image 0.016. :wink:

Ok that worked. thanks!

Now that I have an airplay endpoint, I stream to Volumio, it plays, I see the track, time increment etc. But fails to light up the toslink digital-out on my HAT. I’d seen this previously with 2.8, but through fiddling with the DSD-Direct / PCM and the Mixer settings I got it to work previously, but not on this Buster build.

I tried putting some webRadio stations (DI.fm) and a flac audio file directly on the flash, but got alsa errors playing those also… so guessing something is not quite right with ALSA/MPD config?

Logs? :slight_smile:

Which logs do you need?

mpd.log

volumio@mancave:~$ sudo cat /var/log/mpd.log

[sudo] password for volumio:

Feb 12 02:03 : zeroconf: No global port, disabling zeroconf

Feb 12 02:04 : client: [0] opened from local

Feb 12 02:04 : exception: No such directory

Feb 12 02:04 : client: [1] opened from 127.0.0.1:44852

Feb 12 02:04 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM

Feb 12 02:07 : client: [2] opened from 127.0.0.1:45108

Feb 12 02:08 : client: [1] closed

ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card

Feb 12 02:11 : exception: Failed to open "alsa" (alsa)

Feb 12 02:11 : exception: nested: Failed to open ALSA device "hw:2,0": No such file or directory

Feb 12 02:11 : exception: Failed to open "alsa" (alsa)

Feb 12 02:11 : exception: nested: Failed to open ALSA device "hw:2,0": No such file or directory

Feb 12 02:11 : player: problems opening audio device while playing "INTERNAL/Calibre - Hard Wax In-store (January 2021).flac"

Feb 12 02:11 : client: [3] opened from 127.0.0.1:45216

Feb 12 02:12 : client: [3] closed

volumio@mancave:~$

journal while playing a local file:

volumio@mancave:~$ sudo journalctl -f

-- Logs begin at Fri 2021-02-12 02:12:54 UTC. --

Feb 12 17:53:22 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources

Feb 12 17:53:22 mancave volumio[892]: info: Listing playlists

Feb 12 17:53:22 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings

Feb 12 17:53:22 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard

Feb 12 17:53:22 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard

Feb 12 17:53:22 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings

Feb 12 17:53:24 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus

Feb 12 17:53:25 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus

Feb 12 17:54:00 mancave sudo[6928]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f

Feb 12 17:54:00 mancave sudo[6928]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)

Feb 12 17:54:12 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri

Feb 12 17:54:12 mancave volumio[892]: info: CURURI: artists://

Feb 12 17:54:12 mancave volumio[892]: info: CoreCommandRouter::volumioGetQueue

Feb 12 17:54:12 mancave volumio[892]: info: CoreStateMachine::getQueue

Feb 12 17:54:12 mancave volumio[892]: info: CorePlayQueue::getQueue

Feb 12 17:54:14 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri

Feb 12 17:54:14 mancave volumio[892]: info: CURURI: artists://Calibre

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::volumioReplaceandPlayItems

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::ClearQueue

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::stop

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::serviceStop

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::serviceStop

Feb 12 17:54:15 mancave volumio[892]: info: Airplay Stop

Feb 12 17:54:15 mancave volumio[892]: info: Stopping Airplay Playback and sending pause command to client via USR2

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::clearPlayQueue

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::saveQueue

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::volumioPushQueue

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::addQueueItems

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::addQueueItems

Feb 12 17:54:15 mancave volumio[892]: info: Adding Item to queue: artists://Calibre

Feb 12 17:54:15 mancave volumio[892]: info: Exploding uri artists://Calibre in service mpd

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::saveQueue

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::volumioPushQueue

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::updateTrackBlock

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::getTrackBlock

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::volumioPlay

Feb 12 17:54:15 mancave volumio[892]: UNSET VOLATILE

Feb 12 17:54:15 mancave volumio[892]: info: Stopping Airplay Playback and sending pause command to client via USR2

Feb 12 17:54:15 mancave sudo[6932]: pam_unix(sudo:auth): conversation failed

Feb 12 17:54:15 mancave sudo[6932]: pam_unix(sudo:auth): auth could not identify password for [volumio]

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::play index 0

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::stop

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined

Feb 12 17:54:15 mancave volumio[892]: UNSET VOLATILE

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::updateTrackBlock

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::getTrackBlock

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::stPlaybackTimer

Feb 12 17:54:15 mancave volumio[892]: info: CoreStateMachine::pushState

Feb 12 17:54:15 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:15 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::serviceStop

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::serviceStop

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::stop

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand stop

Feb 12 17:54:16 mancave volumio[892]: info:

Feb 12 17:54:16 mancave volumio[892]: ---------------------------- MPD announces state update: player

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::getState

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand status

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::play index undefined

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::setConsumeUpdateService undefined

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::startPlaybackTimer

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Calibre - Hard Wax In-store (January 2021).flac

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand stop

Feb 12 17:54:16 mancave volumio[892]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync)

Feb 12 17:54:16 mancave volumio[892]: sudo: no tty present and no askpass program specified

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::parseState

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand clear

Feb 12 17:54:16 mancave sudo[6937]: pam_unix(sudo:auth): conversation failed

Feb 12 17:54:16 mancave sudo[6937]: pam_unix(sudo:auth): auth could not identify password for [volumio]

Feb 12 17:54:16 mancave volumio[892]: info:

Feb 12 17:54:16 mancave volumio[892]: ---------------------------- MPD announces system playlist update

Feb 12 17:54:16 mancave volumio[892]: info: Ignoring MPD Status Update

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::parseTrackInfo

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Calibre - Hard Wax In-store (January 2021).flac"

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::servicePushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Wax In-store (January 2021)","artist":"Calibre","album":"Hard Wax In-store (January 2021)","uri":"INTERNAL/Calibre - Hard Wax In-store (January 2021).flac","trackType":"flac"}

Feb 12 17:54:16 mancave volumio[892]: verbose: CURRENT POSITION 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::syncState stateService stop

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::syncState currentStatus stop

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:16 mancave volumio[892]: info: No code

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:16 mancave volumio[892]: info: ------------------------------ 176ms

Feb 12 17:54:16 mancave volumio[892]: error: Upnp client error: Error: This socket has been ended by the other party

Feb 12 17:54:16 mancave volumio[892]: error: updateQueue error: null

Feb 12 17:54:16 mancave volumio[892]: info:

Feb 12 17:54:16 mancave volumio[892]: ---------------------------- MPD announces system playlist update

Feb 12 17:54:16 mancave volumio[892]: info: Ignoring MPD Status Update

Feb 12 17:54:16 mancave volumio[892]: info: ------------------------------ 97ms

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand play

Feb 12 17:54:16 mancave volumio[892]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync)

Feb 12 17:54:16 mancave volumio[892]: sudo: no tty present and no askpass program specified

Feb 12 17:54:16 mancave volumio[892]: info:

Feb 12 17:54:16 mancave volumio[892]: ---------------------------- MPD announces state update: player

Feb 12 17:54:16 mancave volumio[892]: info: ------------------------------ 37ms

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::getState

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand status

Feb 12 17:54:16 mancave volumio[892]: info:

Feb 12 17:54:16 mancave volumio[892]: ---------------------------- MPD announces state update: player

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::getState

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand status

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand clearerror

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand clearerror

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::parseState

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::parseState

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::sendMpdCommand playlistinfo

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::parseTrackInfo

Feb 12 17:54:16 mancave volumio[892]: verbose: ControllerMpd::parseTrackInfo

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::servicePushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":4419,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Wax In-store (January 2021)","artist":"Calibre","album":"Hard Wax In-store (January 2021)","uri":"INTERNAL/Calibre - Hard Wax In-store (January 2021).flac","trackType":"flac"}

Feb 12 17:54:16 mancave volumio[892]: verbose: CURRENT POSITION 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::syncState stateService pause

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::syncState currentStatus stop

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:16 mancave volumio[892]: info: ControllerMpd::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::servicePushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":4419,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hard Wax In-store (January 2021)","artist":"Calibre","album":"Hard Wax In-store (January 2021)","uri":"INTERNAL/Calibre - Hard Wax In-store (January 2021).flac","trackType":"flac"}

Feb 12 17:54:16 mancave volumio[892]: verbose: CURRENT POSITION 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::syncState stateService pause

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::syncState currentStatus stop

Feb 12 17:54:16 mancave volumio[892]: info: CoreStateMachine::pushState

Feb 12 17:54:16 mancave volumio[892]: info: CorePlayQueue::getTrack 0

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::volumioPushState

Feb 12 17:54:16 mancave volumio[892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Feb 12 17:54:16 mancave volumio[892]: info: ------------------------------ 121ms

Feb 12 17:54:16 mancave volumio[892]: info: ------------------------------ 117ms

^C

mpd.conf

volumio@mancave:~$ sudo find / -name mpd.conf
[sudo] password for volumio: 
/etc/mpd.conf
/usr/lib/tmpfiles.d/mpd.conf
/static/etc/mpd.conf
/static/usr/lib/tmpfiles.d/mpd.conf
volumio@mancave:~$ sudo cat /etc/mpd.conf
# Volumio MPD Configuration File

# Files and directories #######################################################
music_directory		"/var/lib/mpd/music"
playlist_directory		"/var/lib/mpd/playlists"
db_file			"/var/lib/mpd/tag_cache"
log_file			"/var/log/mpd.log"
#pid_file			"/var/run/mpd/pid"
#state_file			"/var/lib/mpd/state"
#sticker_file                   "/var/lib/mpd/sticker.sql"
###############################################################################

# General music daemon options ################################################
user				"mpd"
group                          "audio"
bind_to_address		"any"
#port				"6600"
#log_level			"default"
gapless_mp3_playback			"no"
#save_absolute_paths_in_playlists	"no"
#metadata_to_use	"artist,album,title,track,name,genre,date,composer,performer,disc"
auto_update    "yes"
#auto_update_depth "3"
###############################################################################
# Symbolic link behavior ######################################################
follow_outside_symlinks	"yes"
follow_inside_symlinks		"yes"
###############################################################################
# Input #######################################################################
#
input {
        plugin "curl"
#       proxy "proxy.isp.com:8080"
#       proxy_user "user"
#       proxy_password "password"
}
###############################################################################

# Decoder ################################################################

 

 

###############################################################################

# Audio Output ################################################################

resampler {      
  		plugin "soxr"
  		quality "high"
  		threads "1"
}

audio_output {
		type		"alsa"
		name		"alsa"
		device		"hw:2,0"
		dop			"no"
		
		
		

}





audio_output {
    type            "fifo"
    enabled         "no"
    name            "multiroom"
    path            "/tmp/snapfifo"
    format          "44100:16:2"
}

#replaygain			"album"
#replaygain_preamp		"0"
volume_normalization		"no"
###############################################################################

# MPD Internal Buffering ######################################################
audio_buffer_size		"2048"
buffer_before_play		"10%"
###############################################################################


# Resource Limitations ########################################################
#connection_timeout		"60"
max_connections			"20"
max_playlist_length		"81920"
max_command_list_size		"81920"
max_output_buffer_size		"81920"
###############################################################################

# Character Encoding ##########################################################
filesystem_charset		"UTF-8"
id3v1_encoding			"UTF-8"
###############################################################################

Looks like your device isn’t found. Can you perform a fresh reboot and Sending logs for troubleshooting - Volumio Documentation?

1 Like

http://logs.volumio.org/volumio/Rr6IZtR.html

This is the HAT I’m using, which was working on 2.8. I’ll try going back to that a little later to verify its still working there if need be.

It uses the “hifiberry DAC driver”.

volumio@mancave:~$ aplay -l
**** List of PLAYBACK Hardware Devices ****
card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
  Subdevices: 8/8
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
  Subdevice #4: subdevice #4
  Subdevice #5: subdevice #5
  Subdevice #6: subdevice #6
  Subdevice #7: subdevice #7
card 1: sndrpihifiberry [snd_rpi_hifiberry_dac], device 0: HifiBerry DAC HiFi pcm5102a-hifi-0 [HifiBerry DAC HiFi pcm5102a-hifi-0]
  Subdevices: 1/1
  Subdevice #0: subdevice #0

EDIT: I loaded an sd-card with fresh 2.8.61, selected the i2s hifiberry dac driver, rebooted and streamed airplay to it successfully. So I think the hardware is still ok.

Log from the fresh 2.8.61 as comparison, in case its helpful: http://logs.volumio.org/volumio/GxAxcet.html

I had to add “snd-bcm2835.enable_compat_alsa=1” to /boot/cmdline.txt otherwise my dac has not worked.
Can you check /boot/cmdline.txt?

# aplay -l ---------------
**** List of PLAYBACK Hardware Devices ****
card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
  Subdevices: 8/8
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
  Subdevice #4: subdevice #4
  Subdevice #5: subdevice #5
  Subdevice #6: subdevice #6
  Subdevice #7: subdevice #7
card 1: sndrpihifiberry [snd_rpi_hifiberry_dac], device 0: HifiBerry DAC HiFi pcm5102a-hifi-0 [HifiBerry DAC HiFi pcm5102a-hifi-0]
  Subdevices: 1/1
  Subdevice #0: subdevice #0

Yep, that is the issue – it’s looking for the hardcoded card 2, which doesn’t exist. You would need to add the line suggested by @The_Raven to get the headphone outputs again, pushing your I2S DAC back to card 2.

Ok, that definitely helped. There are no errors now when playing local files.

Still no output from the HAT though, or optical light turning on, same settings as I used on 2.8.61.

What steps should I take to troubleshoot?

Reboot and a fresh log please? :slight_smile:
Also would be nice to share the output of dmesg

1 Like

http://logs.volumio.org/volumio/H0BckFD.html

journalctl -k
– Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sat 2021-02-13 17:58:40 UTC. –

Feb 14 10:12:00 mancave kernel: Booting Linux on physical CPU 0xf00

Feb 14 10:12:00 mancave kernel: Linux version 5.10.3-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.

Feb 14 10:12:00 mancave kernel: CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d

Feb 14 10:12:00 mancave kernel: CPU: div instructions available: patching division code

Feb 14 10:12:00 mancave kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache

Feb 14 10:12:00 mancave kernel: OF: fdt: Machine model: Raspberry Pi 2 Model B Rev 1.1

Feb 14 10:12:00 mancave kernel: Memory policy: Data cache writealloc

Feb 14 10:12:00 mancave kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB

Feb 14 10:12:00 mancave kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool

Feb 14 10:12:00 mancave kernel: Zone ranges:

Feb 14 10:12:00 mancave kernel: DMA [mem 0x0000000000000000-0x000000003dffffff]

Feb 14 10:12:00 mancave kernel: Normal empty

Feb 14 10:12:00 mancave kernel: Movable zone start for each node

Feb 14 10:12:00 mancave kernel: Early memory node ranges

Feb 14 10:12:00 mancave kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff]

Feb 14 10:12:00 mancave kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff]

Feb 14 10:12:00 mancave kernel: On node 0 totalpages: 253952

Feb 14 10:12:00 mancave kernel: DMA zone: 2232 pages used for memmap

Feb 14 10:12:00 mancave kernel: DMA zone: 0 pages reserved

Feb 14 10:12:00 mancave kernel: DMA zone: 253952 pages, LIFO batch:63

Feb 14 10:12:00 mancave kernel: percpu: Embedded 20 pages/cpu s50572 r8192 d23156 u81920

Feb 14 10:12:00 mancave kernel: pcpu-alloc: s50572 r8192 d23156 u81920 alloc=20*4096

Feb 14 10:12:00 mancave kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3

Feb 14 10:12:00 mancave kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720

Feb 14 10:12:00 mancave kernel: Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=

Feb 14 10:12:00 mancave kernel: Kernel parameter elevator= does not have any effect anymore.

Please use sysfs to set IO scheduler for individual devices.

Feb 14 10:12:00 mancave kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)

Feb 14 10:12:00 mancave kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)

Feb 14 10:12:00 mancave kernel: mem auto-init: stack:off, heap alloc:off, heap free:off

Feb 14 10:12:00 mancave kernel: Memory: 901816K/1015808K available (9216K kernel code, 1310K rwdata, 2936K rodata, 1024K init, 860K bss, 48456K reserved, 65536K cma-reserve

Feb 14 10:12:00 mancave kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1

Feb 14 10:12:00 mancave kernel: ftrace: allocating 31803 entries in 63 pages

Feb 14 10:12:00 mancave kernel: ftrace: allocated 63 pages with 6 groups

Feb 14 10:12:00 mancave kernel: rcu: Hierarchical RCU implementation.

Feb 14 10:12:00 mancave kernel: Rude variant of Tasks RCU enabled.

Feb 14 10:12:00 mancave kernel: Tracing variant of Tasks RCU enabled.

Feb 14 10:12:00 mancave kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.

Feb 14 10:12:00 mancave kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16

Feb 14 10:12:00 mancave kernel: random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=0

Feb 14 10:12:00 mancave kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys).

Feb 14 10:12:00 mancave kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns

Feb 14 10:12:00 mancave kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns

Feb 14 10:12:00 mancave kernel: Switching to timer-based delay loop, resolution 52ns

Feb 14 10:12:00 mancave kernel: Console: colour dummy device 80x30

Feb 14 10:12:00 mancave kernel: printk: console [tty1] enabled

Feb 14 10:12:00 mancave kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)

Feb 14 10:12:00 mancave kernel: pid_max: default: 32768 minimum: 301

Feb 14 10:12:00 mancave kernel: LSM: Security Framework initializing

Feb 14 10:12:00 mancave kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)

Feb 14 10:12:00 mancave kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)

Feb 14 10:12:00 mancave kernel: Disabling memory control group subsystem

Feb 14 10:12:00 mancave kernel: CPU: Testing write buffer coherency: ok

Feb 14 10:12:00 mancave kernel: CPU0: update cpu_capacity 1024

Feb 14 10:12:00 mancave kernel: CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00

Feb 14 10:12:00 mancave kernel: Setting up static identity map for 0x100000 - 0x10003c

Feb 14 10:12:00 mancave kernel: rcu: Hierarchical SRCU implementation.

Feb 14 10:12:00 mancave kernel: smp: Bringing up secondary CPUs ...

Feb 14 10:12:00 mancave kernel: CPU1: update cpu_capacity 1024

Feb 14 10:12:00 mancave kernel: CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01

Feb 14 10:12:00 mancave kernel: CPU2: update cpu_capacity 1024

Feb 14 10:12:00 mancave kernel: CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02

Feb 14 10:12:00 mancave kernel: CPU3: update cpu_capacity 1024

Feb 14 10:12:00 mancave kernel: CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03

Feb 14 10:12:00 mancave kernel: smp: Brought up 1 node, 4 CPUs

Feb 14 10:12:00 mancave kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS).

Feb 14 10:12:00 mancave kernel: CPU: All CPU(s) started in HYP mode.

Feb 14 10:12:00 mancave kernel: CPU: Virtualization extensions available.

Feb 14 10:12:00 mancave kernel: devtmpfs: initialized

Feb 14 10:12:00 mancave kernel: VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5

Feb 14 10:12:00 mancave kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns

Feb 14 10:12:00 mancave kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)

Feb 14 10:12:00 mancave kernel: pinctrl core: initialized pinctrl subsystem

Feb 14 10:12:00 mancave kernel: NET: Registered protocol family 16

Feb 14 10:12:00 mancave kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations

Feb 14 10:12:00 mancave kernel: audit: initializing netlink subsys (disabled)

Feb 14 10:12:00 mancave kernel: audit: type=2000 audit(0.050:1): state=initialized audit_enabled=0 res=1

Feb 14 10:12:00 mancave kernel: thermal_sys: Registered thermal governor 'step_wise'

Feb 14 10:12:00 mancave kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.

Feb 14 10:12:00 mancave kernel: hw-breakpoint: maximum watchpoint size is 8 bytes.

Feb 14 10:12:00 mancave kernel: Serial: AMBA PL011 UART driver

Feb 14 10:12:00 mancave kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled

Feb 14 10:12:00 mancave kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2020-12-15T18:34:30, variant start

Feb 14 10:12:00 mancave kernel: raspberrypi-firmware soc:firmware: Firmware hash is 4b4aff21f72c5b9ba39d83c7b0f8fa910a6ef99b

Feb 14 10:12:00 mancave kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1

Feb 14 10:12:00 mancave kernel: SCSI subsystem initialized

Feb 14 10:12:00 mancave kernel: usbcore: registered new interface driver usbfs

Feb 14 10:12:00 mancave kernel: usbcore: registered new interface driver hub

Feb 14 10:12:00 mancave kernel: usbcore: registered new device driver usb

Feb 14 10:12:00 mancave kernel: clocksource: Switched to clocksource arch_sys_counter

Feb 14 10:12:00 mancave kernel: VFS: Disk quotas dquot_6.6.0

Feb 14 10:12:00 mancave kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)

Feb 14 10:12:00 mancave kernel: FS-Cache: Loaded

Feb 14 10:12:00 mancave kernel: CacheFiles: Loaded

Feb 13 17:50:20 mancave kernel: NET: Registered protocol family 2

Feb 13 17:50:20 mancave kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)

ahhh… looks like the hifiberry is at card 3 now? Assuming thats a problem?

Edit: I found the touch UI a little slow, so I have an rpi4 upgrade arriving tomorrow also… I’m assuming the hardware layout (and hence this card assignment) might be different with that change… will report back on that also.

are you using a RPi together with a DSI touchscreen?

if yes, could you post the response from uname -a?

1 Like

Yes, using a 5" DSI touchscreen.

volumio@mancave:~$ uname -a

Linux mancave 5.10.3-v7+ #1386 SMP Sun Dec 27 14:01:57 GMT 2020 armv7l GNU/Linux

Is changing the hardware address from hw:2 to hw:3 in mpd.conf viable?

Edit: changed hw to “3” in mpd.conf, rebooted and now have dsd lock with the DAC, getting output, but only for local files. Shows playing etc with airplay, but no lock/output from the HAT.

1 Like

your problem is related to the kernel, in the latest versions (from 5.4.79 onwards) the mapping of the device changes if a DSI touchscreen is connected (HDMI entry disappears, I2S DAC shifts to card1)

I have been playing with all the possible combinations of snd-bcm2835.enable_compat_alsa, snd_bcm2835.enable_headphones and snd_bcm2835.enable_hdmi, there is no way to have the DAC mapped as card2. disconnecting the DSI touchscreen makes the problem gone.

I have the very same setup, the trick to have your DAC working again is the following

  1. restore mpd.conf
  2. add the entry dtoverlay=hifiberry-dac in /boot/userconfig.txt
  3. Disable I2S DAC from Playback option menu
  4. select sndrpihifiberry from the dwop-down menu
  5. Apply and reboot

note: Volumio team is aware of the issue, a fix is already done and will be available in the future releases

1 Like

That did the trick! Local and airplay both now working.

Now I’m back to operation, time to start playing with the dev peppymeter plug-in to get these led vu meters bouncing :slight_smile:

Thanks so much for the support. I signed up for membership last night as well.

1 Like