Mar 27 21:58:03 dac-pi ntpd[980]: CLOCK: time stepped by 5358.062135 Mar 27 21:58:03 dac-pi ntpd[980]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 27 21:58:03 dac-pi systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 27 21:58:03 dac-pi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 27 21:58:03 dac-pi systemd[1]: setdatetime-helper.service: Consumed 1.565s CPU time. Mar 27 21:58:03 dac-pi systemd[1]: Reached target multi-user.target - Multi-User System. Mar 27 21:58:03 dac-pi systemd[1]: Reached target graphical.target - Graphical Interface. Mar 27 21:58:04 dac-pi systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Mar 27 21:58:04 dac-pi systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 27 21:58:04 dac-pi systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Mar 27 21:58:04 dac-pi systemd[1]: Startup finished in 15.704s (kernel) + 18.528s (userspace) = 34.233s. Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "network"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Refreshing Cached IP Addresses Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "services"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "volumio5onboarding"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "alsa_controller"... Mar 27 21:58:05 dac-pi sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 21:58:05 dac-pi sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 21:58:05 dac-pi sudo[1429]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 27 21:58:05 dac-pi sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:05 dac-pi sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:05 dac-pi sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:05 dac-pi sudo[1420]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:05 dac-pi volumio[1055]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. Mar 27 21:58:05 dac-pi sudo[1422]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:05 dac-pi sudo[1429]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:05 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "wizard"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "networkfs"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Starting Udev Watcher for removable devices Mar 27 21:58:05 dac-pi volumio[1055]: info: Ignoring mount for partition: boot Mar 27 21:58:05 dac-pi volumio[1055]: info: Ignoring mount for partition: volumio Mar 27 21:58:05 dac-pi volumio[1055]: info: Ignoring mount for partition: volumio_data Mar 27 21:58:05 dac-pi volumio[1055]: info: Mounting Device MP3 Mar 27 21:58:05 dac-pi sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/MP3 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 27 21:58:05 dac-pi sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:05 dac-pi kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 27 21:58:05 dac-pi kernel: FAT-fs (sda1): IO charset utf8 not found Mar 27 21:58:05 dac-pi sudo[1481]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:05 dac-pi volumio[1055]: mount: /media/MP3: wrong fs type, bad option, bad superblock on /dev/sda1, missing codepage or helper program, or other error. Mar 27 21:58:05 dac-pi volumio[1055]: dmesg(1) may have more information after failed mount system call. Mar 27 21:58:05 dac-pi volumio[1055]: error: Failed to mount MP3: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/MP3" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 27 21:58:05 dac-pi volumio[1055]: mount: /media/MP3: wrong fs type, bad option, bad superblock on /dev/sda1, missing codepage or helper program, or other error. Mar 27 21:58:05 dac-pi volumio[1055]: dmesg(1) may have more information after failed mount system call. Mar 27 21:58:05 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "volumio_command_line_client"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "upnp"... Mar 27 21:58:05 dac-pi volumio[1055]: info: [1774659485696] Starting Upmpd Daemon Mar 27 21:58:05 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "my_music"... Mar 27 21:58:05 dac-pi volumio[1055]: info: Loading plugin "mpd"... Mar 27 21:58:06 dac-pi volumio[1055]: info: Loading plugin "upnp_browser"... Mar 27 21:58:06 dac-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Mar 27 21:58:06 dac-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:58:06 dac-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:58:06 dac-pi upmpdcli[1501]: Could not open config: /tmp/upmpdcli.conf Mar 27 21:58:06 dac-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:06 dac-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 27 21:58:08 dac-pi volumio[1055]: info: Starting UPNP Browser Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "alarm-clock"... Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "airplay_emulation"... Mar 27 21:58:08 dac-pi volumio[1055]: info: Starting Shairport Sync Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "last_100"... Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "webradio"... Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "i2s_dacs"... Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "volumiodiscovery"... Mar 27 21:58:08 dac-pi volumio[1055]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 27 21:58:08 dac-pi volumio[1055]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 21:58:08 dac-pi node[1055]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 27 21:58:08 dac-pi volumio[1055]: *** WARNING *** For more information see Mar 27 21:58:08 dac-pi volumio[1055]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 27 21:58:08 dac-pi volumio[1055]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 21:58:08 dac-pi volumio[1055]: *** WARNING *** For more information see Mar 27 21:58:08 dac-pi node[1055]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 21:58:08 dac-pi node[1055]: *** WARNING *** For more information see Mar 27 21:58:08 dac-pi node[1055]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 27 21:58:08 dac-pi node[1055]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 21:58:08 dac-pi node[1055]: *** WARNING *** For more information see Mar 27 21:58:08 dac-pi volumio[1055]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 27 21:58:08 dac-pi volumio[1055]: info: Discovery: Started advertising with name: DAC-PI Mar 27 21:58:08 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 21:58:08 dac-pi volumio[1055]: info: Loading plugin "ytcr"... Mar 27 21:58:15 dac-pi volumio[1055]: info: Loading plugin "ytmusic"... Mar 27 21:58:17 dac-pi volumio[1055]: info: Loading plugin "autostart"... Mar 27 21:58:18 dac-pi systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 27 21:58:18 dac-pi volumio-remote-updater[728]: [2026-03-27 21:58:18] [connect] Successful connection Mar 27 21:58:18 dac-pi volumio[1055]: info: Applying required configuration parameters for plugin autostart Mar 27 21:58:18 dac-pi volumio[1055]: info: AutoStart - onVolumioStart - read config.json Mar 27 21:58:18 dac-pi volumio[1055]: info: Loading plugin "gpio-buttons"... Mar 27 21:58:20 dac-pi volumio[1055]: gpiox_arm32 V2.2.1 Mar 27 21:58:20 dac-pi volumio[1055]: info: gpio-buttons: Initialized Mar 27 21:58:20 dac-pi volumio[1055]: info: Loading plugin "gpio_button_led"... Mar 27 21:58:21 dac-pi volumio[1055]: gpiox_arm32 V2.2.1 Mar 27 21:58:21 dac-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Mar 27 21:58:21 dac-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:58:21 dac-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:58:21 dac-pi upmpdcli[1659]: Could not open config: /tmp/upmpdcli.conf Mar 27 21:58:21 dac-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:21 dac-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 27 21:58:22 dac-pi volumio[1055]: info: Plugin now_playing is not enabled Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "outputs"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "albumart"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Plugin example_plugin is not enabled Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "inputs"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "updater_comm"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Plugin mpdemulation is not enabled Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "rest_api"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "websocket"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Starting Socket.io Server version 1.7.4 Mar 27 21:58:22 dac-pi volumio[1055]: info: Loading plugin "minidlna"... Mar 27 21:58:22 dac-pi volumio[1055]: info: Applying required configuration parameters for plugin minidlna Mar 27 21:58:23 dac-pi volumio[1055]: info: Loading plugin "radio_paradise"... Mar 27 21:58:23 dac-pi volumio[1055]: info: Applying required configuration parameters for plugin radio_paradise Mar 27 21:58:23 dac-pi volumio[1055]: info: [1774659503809] [RadioParadise] API delay: 5 Mar 27 21:58:23 dac-pi volumio[1055]: info: Loading plugin "backup_restore"... Mar 27 21:58:23 dac-pi volumio[1660]: Forking 3 albumart workers Mar 27 21:58:24 dac-pi volumio[1055]: info: Applying required configuration parameters for plugin backup_restore Mar 27 21:58:24 dac-pi volumio[1055]: info: Loading plugin "mpd_oled"... Mar 27 21:58:26 dac-pi volumio[1055]: info: Applying required configuration parameters for plugin mpd_oled Mar 27 21:58:26 dac-pi volumio[1055]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Mar 27 21:58:26 dac-pi volumio[1055]: info: Loading plugin "rotaryencoder2"... Mar 27 21:58:28 dac-pi volumio[1055]: info: Loading plugin "Systeminfo"... Mar 27 21:58:30 dac-pi volumio[1673]: Starting albumart workers Mar 27 21:58:30 dac-pi volumio[1055]: info: Plugin randomizer is not enabled Mar 27 21:58:30 dac-pi volumio[1055]: info: Loading i18n strings for locale pt Mar 27 21:58:30 dac-pi volumio[1055]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 27 21:58:30 dac-pi volumio[1055]: Updating browse sources language Mar 27 21:58:30 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:58:31 dac-pi volumio[1672]: Starting albumart workers Mar 27 21:58:31 dac-pi volumio[1671]: Starting albumart workers Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::initPlayerControls Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 21:58:32 dac-pi volumio[1055]: Express server listening on port 3000 Mar 27 21:58:32 dac-pi volumio[1055]: [Metrics] WebUI: 32s 127.83ms Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreStateMachine::resetVolumioState Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreStateMachine::getcurrentVolume Mar 27 21:58:32 dac-pi volumio[1055]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:58:32 dac-pi sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 21:58:32 dac-pi sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:33 dac-pi sudo[1762]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:33 dac-pi sudo[1764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 21:58:33 dac-pi sudo[1764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:33 dac-pi sudo[1764]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:33 dac-pi volumio[1055]: info: Volumio Network Manager: Network status updated: 1 Mar 27 21:58:33 dac-pi volumio[1055]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:58:33 dac-pi volumio[1055]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 27 21:58:33 dac-pi volumio[1055]: wlan0 Interface doesn't support scanning. Mar 27 21:58:33 dac-pi volumio[1055]: info: Cannot use regular scanning, forcing with ap-force Mar 27 21:58:33 dac-pi sudo[1780]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 27 21:58:33 dac-pi sudo[1780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:33 dac-pi volumio-remote-updater[728]: [2026-03-27 21:58:33] [connect] Successful connection Mar 27 21:58:33 dac-pi sudo[1780]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:33 dac-pi volumio[1055]: nl80211 not found. Mar 27 21:58:33 dac-pi volumio[1055]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 27 21:58:33 dac-pi volumio[1055]: nl80211 not found. Mar 27 21:58:34 dac-pi volumio[1055]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreStateMachine::pushState Mar 27 21:58:34 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreStateMachine::updateTrackBlock Mar 27 21:58:34 dac-pi volumio[1055]: info: CorePlayQueue::getTrackBlock Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:58:34 dac-pi volumio[1055]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:34 dac-pi volumio[1055]: info: Reloading queue from file Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:34 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:34 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreStateMachine::setRepeat false single undefined Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreStateMachine::pushState Mar 27 21:58:34 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreStateMachine::setRandom true Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreStateMachine::pushState Mar 27 21:58:34 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:58:34 dac-pi volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 27 21:58:34 dac-pi volumio[1055]: info: Setting Device type: Raspberry PI Mar 27 21:58:35 dac-pi volumio[1055]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 27 21:58:35 dac-pi volumio[1055]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a52082 Mar 27 21:58:35 dac-pi volumio[1055]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:35 dac-pi volumio[1055]: info: Completed loading Core Plugins Mar 27 21:58:35 dac-pi volumio[1055]: info: Preparing to generate the ALSA configuration file Mar 27 21:58:35 dac-pi volumio[1055]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreStateMachine::pushState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:35 dac-pi volumio[1055]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Mar 27 21:58:35 dac-pi volumio[1055]: info: Reading ALSA contributions from plugins. Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:35 dac-pi sudo[1820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 27 21:58:35 dac-pi sudo[1820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: adding 610b1086-e212-4b9a-9659-b89851773d7e Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Found device DAC-PI Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 27 21:58:35 dac-pi volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: this is already registered, 610b1086-e212-4b9a-9659-b89851773d7e Mar 27 21:58:35 dac-pi volumio[1055]: info: Discovery: Found device DAC-PI Mar 27 21:58:35 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:35 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:35 dac-pi volumio-remote-updater[728]: [2026-03-27 21:58:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774659513 101 Mar 27 21:58:35 dac-pi volumio[1055]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Mar 27 21:58:36 dac-pi volumio[1055]: info: Asound.conf file unchanged, so no further update is needed Mar 27 21:58:36 dac-pi volumio[1055]: info: Output device has changed, restarting MPD Mar 27 21:58:36 dac-pi volumio[1055]: info: Output device has changed, restarting Shairport Sync Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:36 dac-pi sudo[1860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:58:36 dac-pi sudo[1860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:36 dac-pi sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:58:36 dac-pi volumio[1055]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:58:36 dac-pi volumio[1055]: info: ___________ START PLUGINS ___________ Mar 27 21:58:36 dac-pi sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:36 dac-pi sudo[1858]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:36 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:58:36 dac-pi volumio[1055]: info: ControllerMpd::onStart: Initializing MPD Mar 27 21:58:36 dac-pi volumio[1055]: info: Creating MPD Configuration file Mar 27 21:58:36 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:58:36 dac-pi sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 21:58:36 dac-pi sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:58:36 dac-pi sudo[1874]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:36 dac-pi volumio[1055]: info: [1774659516597] CoreMusicLibrary::Adding element Servidores multimédia Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:58:36 dac-pi sudo[1871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 27 21:58:36 dac-pi sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:36 dac-pi sudo[1872]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:58:36 dac-pi sudo[1872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:58:36 dac-pi sudo[1879]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 27 21:58:36 dac-pi sudo[1872]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:36 dac-pi volumio[1055]: info: UPNP Browser: Client initialized successfully Mar 27 21:58:36 dac-pi sudo[1876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:58:36 dac-pi sudo[1876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:36 dac-pi systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:36 dac-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Mar 27 21:58:36 dac-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:58:36 dac-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:58:36 dac-pi sudo[1820]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:36 dac-pi sudo[1871]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:36 dac-pi systemd[1]: mpd.service: Deactivated successfully. Mar 27 21:58:36 dac-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 21:58:36 dac-pi systemd[1]: mpd.socket: Deactivated successfully. Mar 27 21:58:36 dac-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 21:58:36 dac-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 21:58:36 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:58:37 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:58:37 dac-pi volumio[1055]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:37 dac-pi sudo[1889]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:58:37 dac-pi sudo[1889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:58:37 dac-pi volumio[1055]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:58:37 dac-pi volumio[1055]: info: [1774659517342] CoreMusicLibrary::Adding element Last_100 Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:58:37 dac-pi sudo[1909]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 27 21:58:37 dac-pi sudo[1889]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:58:37 dac-pi volumio[1055]: info: [1774659517364] CoreMusicLibrary::Adding element Webradio Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:58:37 dac-pi volumio[1055]: info: Initializing BBC Radios Mar 27 21:58:37 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:37.626-03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:58:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:38 dac-pi volumio[1055]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:58:38 dac-pi volumio[1055]: info: [1774659518086] CoreMusicLibrary::Adding element YouTube Music Mar 27 21:58:38 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:58:38 dac-pi volumio[1055]: Cannot find translation for source YouTube Music Mar 27 21:58:38 dac-pi volumio[1055]: info: AutoStart - onStart - waiting for system ready state Mar 27 21:58:38 dac-pi volumio[1055]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Mar 27 21:58:38 dac-pi volumio[1055]: info: AutoStart - Maximum wait time: 300 seconds Mar 27 21:58:38 dac-pi volumio[1055]: info: AutoStart - Startup volume disabled Mar 27 21:58:38 dac-pi volumio[1055]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:58:38 dac-pi volumio[1055]: info: gpio-buttons: Creating GPIO triggers... Mar 27 21:58:38 dac-pi volumio[1055]: info: gpio-buttons: GPIO16 configured: button1 -> next Mar 27 21:58:38 dac-pi volumio[1055]: info: gpio-buttons: GPIO26 configured: button2 -> previous Mar 27 21:58:38 dac-pi volumio[1055]: info: gpio-buttons: GPIO22 configured: button3 -> playPause Mar 27 21:58:38 dac-pi volumio[1055]: info: GPIOButtonLED: Starting plugin Mar 27 21:58:38 dac-pi volumio[1055]: info: Loading i18n strings for locale pt Mar 27 21:58:38 dac-pi volumio[1055]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 27 21:58:38 dac-pi volumio[1055]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:58:38 dac-pi volumio[1055]: info: [1774659518410] CoreMusicLibrary::Adding element Radio Paradise Mar 27 21:58:38 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:58:38 dac-pi volumio[1055]: Cannot find translation for source YouTube Music Mar 27 21:58:38 dac-pi volumio[1055]: Cannot find translation for source Radio Paradise Mar 27 21:58:38 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 27 21:58:38 dac-pi sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Mar 27 21:58:38 dac-pi sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:38 dac-pi sudo[1942]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:38 dac-pi sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Mar 27 21:58:38 dac-pi sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:38 dac-pi volumio[1055]: info: Volumio Calling Home Mar 27 21:58:39 dac-pi sudo[1948]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 27 21:58:39 dac-pi sudo[1948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:39 dac-pi sudo[1948]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:39 dac-pi volumio[1055]: info: gpio-buttons: Started successfully Mar 27 21:58:39 dac-pi volumio[1055]: info: Preparing to generate the ALSA configuration file Mar 27 21:58:42 dac-pi volumio[1055]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Mar 27 21:58:42 dac-pi volumio[1055]: info: Reading ALSA contributions from plugins. Mar 27 21:58:43 dac-pi volumio[1055]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Mar 27 21:58:43 dac-pi volumio[1055]: info: minidlna: Found miniDLNA version 1.3.0 Mar 27 21:58:43 dac-pi volumio[1055]: info: minidlna: Starting minidlna.service Mar 27 21:58:43 dac-pi sudo[1955]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=24 relative_axis=true steps-per-period=2 Mar 27 21:58:43 dac-pi sudo[1955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:43 dac-pi volumio[1055]: info: MPD Permissions set Mar 27 21:58:43 dac-pi volumio[1055]: info: MPD Permissions set Mar 27 21:58:43 dac-pi volumio[1055]: info: Upmpdcli Daemon Started Mar 27 21:58:43 dac-pi volumio[1055]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:58:43 dac-pi sudo[1957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Mar 27 21:58:43 dac-pi sudo[1957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:43 dac-pi sudo[1955]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:43 dac-pi dbus-daemon[714]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1882 comm="/usr/bin/volumio5-onboarding") Mar 27 21:58:43 dac-pi systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Mar 27 21:58:44 dac-pi volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 27 21:58:44 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:58:44 dac-pi sudo[1957]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:44 dac-pi volumio[1055]: info: gpio-buttons: Socket connected to Volumio Mar 27 21:58:44 dac-pi volumio[1055]: info: Volumio called home Mar 27 21:58:44 dac-pi volumio[1055]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Mar 27 21:58:44 dac-pi volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 27 21:58:44 dac-pi bluetoothd[1970]: Bluetooth daemon 5.83 Mar 27 21:58:44 dac-pi dbus-daemon[714]: [system] Successfully activated service 'org.bluez' Mar 27 21:58:44 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:58:44 dac-pi volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 27 21:58:44 dac-pi bluetoothd[1970]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:58:44 dac-pi bluetoothd[1970]: src/main.c:main() Adapter handling initialization failed Mar 27 21:58:44 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:44 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:58:44 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:44.721-03:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="could not activate BlueZ adapter: Message recipient disconnected from message bus without replying" Mar 27 21:58:44 dac-pi dbus-daemon[714]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1882 comm="/usr/bin/volumio5-onboarding") Mar 27 21:58:44 dac-pi volumio[1055]: info: minidlna: systemctl start minidlna.service succeeded. Mar 27 21:58:44 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:44 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: GPIOButtonLED: dtoverlay already correct Mar 27 21:58:45 dac-pi volumio[1055]: info: GPIOButtonLED: LED+ on GPIO 14 Mar 27 21:58:45 dac-pi volumio[1055]: info: GPIOButtonLED: LED polarity = normal Mar 27 21:58:45 dac-pi volumio[1055]: info: GPIOButtonLED: LED blink slow Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi bluetoothd[1978]: Bluetooth daemon 5.83 Mar 27 21:58:45 dac-pi dbus-daemon[714]: [system] Successfully activated service 'org.bluez' Mar 27 21:58:45 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi bluetoothd[1978]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:58:45 dac-pi bluetoothd[1978]: src/main.c:main() Adapter handling initialization failed Mar 27 21:58:45 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:45 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:58:45 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:45.183-03:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="could not activate BlueZ adapter: Message recipient disconnected from message bus without replying" Mar 27 21:58:45 dac-pi dbus-daemon[714]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1882 comm="/usr/bin/volumio5-onboarding") Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:45 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:45 dac-pi bluetoothd[1987]: Bluetooth daemon 5.83 Mar 27 21:58:45 dac-pi dbus-daemon[714]: [system] Successfully activated service 'org.bluez' Mar 27 21:58:45 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:58:45 dac-pi bluetoothd[1987]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:58:45 dac-pi bluetoothd[1987]: src/main.c:main() Adapter handling initialization failed Mar 27 21:58:45 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:45 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:58:45 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:45.609-03:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="could not activate BlueZ adapter: Message recipient disconnected from message bus without replying" Mar 27 21:58:45 dac-pi dbus-daemon[714]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1882 comm="/usr/bin/volumio5-onboarding") Mar 27 21:58:45 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:58:45 dac-pi bluetoothd[1994]: Bluetooth daemon 5.83 Mar 27 21:58:45 dac-pi dbus-daemon[714]: [system] Successfully activated service 'org.bluez' Mar 27 21:58:45 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:58:46 dac-pi bluetoothd[1994]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:58:46 dac-pi bluetoothd[1994]: src/main.c:main() Adapter handling initialization failed Mar 27 21:58:46 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:46 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:58:46 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:46.049-03:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="could not activate BlueZ adapter: Message recipient disconnected from message bus without replying" Mar 27 21:58:46 dac-pi dbus-daemon[714]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1882 comm="/usr/bin/volumio5-onboarding") Mar 27 21:58:46 dac-pi volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 27 21:58:46 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:58:46 dac-pi mpd[1910]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 21:58:46 dac-pi mpd[1910]: output: No 'audio_output' defined in config file Mar 27 21:58:46 dac-pi bluetoothd[2011]: Bluetooth daemon 5.83 Mar 27 21:58:46 dac-pi mpd[1910]: output: Successfully detected a sndio audio device Mar 27 21:58:46 dac-pi dbus-daemon[714]: [system] Successfully activated service 'org.bluez' Mar 27 21:58:46 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:58:46 dac-pi mpd[1910]: zeroconf: No global port, disabling zeroconf Mar 27 21:58:46 dac-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 21:58:46 dac-pi bluetoothd[2011]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:58:46 dac-pi bluetoothd[2011]: src/main.c:main() Adapter handling initialization failed Mar 27 21:58:46 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:58:46 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:58:46 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:46.474-03:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="could not activate BlueZ adapter: Message recipient disconnected from message bus without replying" Mar 27 21:58:46 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:46.474-03:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Mar 27 21:58:46 dac-pi sudo[1876]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:46 dac-pi sudo[1860]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:46 dac-pi systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:58:46 dac-pi sudo[1944]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:47 dac-pi volumio[1055]: info: Received Get System Info Mar 27 21:58:47 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:47 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:47 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:47 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:47 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:47 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:47 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:47.582-03:00 level=INFO msg="system info for 354feb79f93c035f4ee941e889cbaf03" deviceName=DAC-PI deviceVariant=volumio deviceModel= softwareVersion=4.119 Mar 27 21:58:47 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:47.640-03:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 27 21:58:47 dac-pi volumio[1055]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Mar 27 21:58:47 dac-pi volumio[1055]: info: GPIOButtonLED: Boot complete - LED solid Mar 27 21:58:47 dac-pi volumio[1055]: info: Starting Shairport Sync Mar 27 21:58:47 dac-pi volumio[1055]: info: Starting Shairport Sync Mar 27 21:58:47 dac-pi volumio[1055]: info: Starting Shairport Sync Mar 27 21:58:47 dac-pi sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:58:47 dac-pi sudo[2051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:48 dac-pi sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:58:48 dac-pi sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:48 dac-pi volumio[1055]: info: Received Get System Info Mar 27 21:58:48 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:48 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:48 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:48 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:48 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:48 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:48 dac-pi sudo[2055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:58:48 dac-pi sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:48 dac-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 21:58:48 dac-pi systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 21:58:48 dac-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:58:48 dac-pi systemd[1]: shairport-sync.service: Consumed 2.051s CPU time. Mar 27 21:58:48 dac-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:58:48 dac-pi sudo[2051]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:48 dac-pi volumio-remote-updater[728]: Test mode disabled Mar 27 21:58:48 dac-pi volumio-remote-updater[728]: Alpha mode disabled Mar 27 21:58:48 dac-pi volumio-remote-updater[728]: Alpha legacy test mode disabled Mar 27 21:58:48 dac-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 21:58:48 dac-pi systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 21:58:48 dac-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:58:48 dac-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:58:48 dac-pi sudo[2053]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:48 dac-pi sudo[2055]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:48 dac-pi volumio[1055]: error: MPD error: The expression evaluated to a falsy value: Mar 27 21:58:48 dac-pi volumio[1055]: assert.ok(self.idling) Mar 27 21:58:48 dac-pi volumio[1055]: error: The expression evaluated to a falsy value: Mar 27 21:58:48 dac-pi volumio[1055]: assert.ok(self.idling) Mar 27 21:58:48 dac-pi volumio[1055]: info: MPD running with PID1910 Mar 27 21:58:48 dac-pi volumio[1055]: ,establishing connection Mar 27 21:58:48 dac-pi volumio[1055]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:58:48 dac-pi volumio[1055]: info: Asound.conf file unchanged, so no further update is needed Mar 27 21:58:48 dac-pi volumio[1055]: info: Output device has changed, restarting MPD Mar 27 21:58:49 dac-pi volumio[1055]: info: Output device has changed, restarting Shairport Sync Mar 27 21:58:49 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:49 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:49 dac-pi volumio[1055]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:58:49 dac-pi sudo[2080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:58:49 dac-pi volumio[1055]: error: updateQueue error: null Mar 27 21:58:49 dac-pi sudo[2080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:49 dac-pi sudo[2080]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:49 dac-pi sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:58:49 dac-pi sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:49 dac-pi volumio[1055]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 27 21:58:49 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 21:58:49 dac-pi volumio[1055]: info: [ROTARYENCODER2] Socket connected to Volumio Mar 27 21:58:49 dac-pi systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Mar 27 21:58:49 dac-pi systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Mar 27 21:58:49 dac-pi systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:58:49 dac-pi systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 21:58:49 dac-pi volumio[1055]: info: Shairport-Sync Started Mar 27 21:58:49 dac-pi volumio[1055]: Error adding Membership: Error: addMembership EINVAL Mar 27 21:58:49 dac-pi volumio[1055]: info: Shairport-Sync Started Mar 27 21:58:49 dac-pi volumio[1055]: info: Shairport-Sync Started Mar 27 21:58:49 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:49 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:49 dac-pi volumio[1055]: error: MPD error: Error: write EPIPE Mar 27 21:58:49 dac-pi volumio[1055]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Mar 27 21:58:49 dac-pi systemd[1]: mpd.service: Deactivated successfully. Mar 27 21:58:49 dac-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 21:58:49 dac-pi systemd[1]: mpd.service: Consumed 6.506s CPU time. Mar 27 21:58:49 dac-pi systemd[1]: mpd.socket: Deactivated successfully. Mar 27 21:58:49 dac-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 21:58:49 dac-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 21:58:49 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:58:49 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:58:49 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:58:49 dac-pi volumio[1055]: info: MPD Permissions set Mar 27 21:58:50 dac-pi volumio[1055]: (node:1055) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Mar 27 21:58:50 dac-pi volumio[1055]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:58:50 dac-pi sudo[2097]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:58:50 dac-pi sudo[2097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:58:50 dac-pi sudo[2098]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 27 21:58:50 dac-pi sudo[2097]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:50 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:50 dac-pi sudo[2101]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=27 active_low=true gpio_pull=up keycode=20 Mar 27 21:58:50 dac-pi sudo[2101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:58:50 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:58:51 dac-pi volumio[1055]: info: Received Get System Info Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:58:51 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:51 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:58:51 dac-pi kernel: input: button@1b as /devices/platform/button@1b/input/input3 Mar 27 21:58:51 dac-pi sudo[2101]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 21:58:51 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:51.490-03:00 level=INFO msg="enabling local network discovery" Mar 27 21:58:51 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:51 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:51 dac-pi volumio[1055]: info: Starting Shairport Sync Mar 27 21:58:52 dac-pi sudo[2128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:58:52 dac-pi sudo[2128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:52 dac-pi sudo[2132]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 27 21:58:52 dac-pi sudo[2132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:52 dac-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 21:58:52 dac-pi systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 21:58:52 dac-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:58:52 dac-pi systemd[1]: shairport-sync.service: Consumed 1.423s CPU time. Mar 27 21:58:52 dac-pi sudo[2132]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:52 dac-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:58:52 dac-pi sudo[2128]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:52 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:52.505-03:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 27 21:58:52 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:52.578-03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.199:60827 Mar 27 21:58:52 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:58:52.590-03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.73:46398 Mar 27 21:58:53 dac-pi sudo[2152]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 27 21:58:53 dac-pi sudo[2152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:58:53 dac-pi sudo[2152]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:53 dac-pi volumio[1055]: info: Shairport-Sync Started Mar 27 21:58:54 dac-pi volumio[1055]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:58:54 dac-pi mpd[2099]: 2026-03-27T21:58:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 21:58:54 dac-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 21:58:54 dac-pi sudo[2082]: pam_unix(sudo:session): session closed for user root Mar 27 21:58:54 dac-pi volumio[1055]: info: [yt-cast-receiver] DIAL server listening on port 8098 Mar 27 21:58:54 dac-pi volumio[1055]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:58:54 dac-pi systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:58:54 dac-pi volumio[1055]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:58:55 dac-pi volumio[1055]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:58:55 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:55 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:55 dac-pi volumio[1055]: info: CoreStateMachine::pushState Mar 27 21:58:55 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:55 dac-pi volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 27 21:58:55 dac-pi volumio[1055]: error: updateQueue error: null Mar 27 21:58:55 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:55 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:55 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:55 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:55 dac-pi volumio[1055]: info: Completed starting Core Plugins Mar 27 21:58:55 dac-pi volumio[1055]: info: ------------------------------------------- Mar 27 21:58:55 dac-pi volumio[1055]: info: ----- MyVolumio plugins startup ---- Mar 27 21:58:55 dac-pi volumio[1055]: info: ------------------------------------------- Mar 27 21:58:55 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 27 21:58:55 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:55 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:56 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:56 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:56 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:56 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:56 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:56 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:56 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:56 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:57 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:57 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:57 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:57 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:57 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:57 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:57 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:57 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:57 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:57 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:58 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:58 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:58 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:58 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:58 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:58 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:58 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:58 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:58 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:58 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:58 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:59 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:59 dac-pi volumio[1055]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:58:59 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:59 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:59 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:59 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:59 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:59 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:58:59 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:58:59 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:00 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:00 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:00 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:00 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:00 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:00 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:00 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:00 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:00 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:00 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:00 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:00 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:01 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:01 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:01 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:01 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:01 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:01 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:01 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:01 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:02 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:02 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:02 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:02 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:02 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:02 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:02 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:02 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:02 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:02 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:02 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:02 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:03 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:03 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:03 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:03 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:03 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:03 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:03 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:03 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:04 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:04 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:04 dac-pi volumio[1055]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:59:04 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:04 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:04 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:04 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:04 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:04 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:04 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:04 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:04 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:04 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin bluetooth to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin multiroom to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin metavolumio to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin cd_controller to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 27 21:59:05 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 27 21:59:09 dac-pi systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 27 21:59:09 dac-pi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 27 21:59:09 dac-pi systemd[1]: setdatetime-helper.service: Consumed 1.501s CPU time. Mar 27 21:59:11 dac-pi volumio[1055]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 27 21:59:11 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 27 21:59:11 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:11 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:11 dac-pi volumio[1055]: info: Starting MyVolumio Remote Streaming Endpoints Mar 27 21:59:11 dac-pi volumio[1055]: info: MyVolumio login type: Token Mar 27 21:59:11 dac-pi volumio[1055]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 27 21:59:11 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 27 21:59:13 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:13.010-03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.73:46398 @ 0x2aacab0" latency=-97.090154ms timeout=10s Mar 27 21:59:13 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:13.010-03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" Mar 27 21:59:13 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:13.012-03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.73:46398 @ 0x2aacab0" latency=-93.148475ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 27 21:59:15 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 27 21:59:15 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 27 21:59:15 dac-pi volumio[1055]: info: Streaming services startup Mar 27 21:59:15 dac-pi volumio[1055]: info: Starting Streaming Daemon Mar 27 21:59:15 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 27 21:59:15 dac-pi sudo[2228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 27 21:59:15 dac-pi sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:16 dac-pi sudo[2228]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 21:59:16 dac-pi volumio[1055]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:59:16 dac-pi volumio[1055]: info: Received Get System Info Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:59:16 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:16 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 21:59:16 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:16.452-03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" name=DAC-PI Mar 27 21:59:16 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:16.458-03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" language=pt Mar 27 21:59:16 dac-pi volumio[1055]: error: Cannot start Volumio Streaming Daemon Mar 27 21:59:16 dac-pi volumio[1055]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 27 21:59:16 dac-pi volumio[1055]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 27 21:59:16 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:16.655-03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" timezone=America/Sao_Paulo Mar 27 21:59:16 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:16.657-03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" available=true connected=true macAddress=b8:27:eb:e6:34:1d ip4Address=192.168.1.192/24 ip6Address= Mar 27 21:59:16 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:16.658-03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Mar 27 21:59:16 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:16.659-03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" setupComplete=true Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:16 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 27 21:59:17 dac-pi volumio[1055]: amixer -c 3 info | grep "snd_rpi_hifiberry_dacplus" Mar 27 21:59:17 dac-pi volumio[1055]: Invalid card number '3'. Mar 27 21:59:17 dac-pi volumio[1055]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 27 21:59:17 dac-pi volumio[1055]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 27 21:59:17 dac-pi volumio[1055]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 27 21:59:17 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:17 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:17 dac-pi volumio[1055]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Mar 27 21:59:17 dac-pi volumio[1055]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 27 21:59:17 dac-pi volumio[1055]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 27 21:59:17 dac-pi volumio[1055]: amixer -c 2 info | grep "vc4-hdmi" Mar 27 21:59:18 dac-pi volumio[1055]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.114-03:00 level=WARN msg="active output device not found in available devices" component=volumio/socket activeDeviceId=3 Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.115-03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" selectedOutputId=3 Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:18 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:18 dac-pi volumio[1055]: info: Received Get System Info Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:59:18 dac-pi volumio[1055]: info: Discovery: Getting this device information Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:18 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.481-03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" currentVersion=4.119 latestVersion=4.119 Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.482-03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" status=UPDATE_STATUS_NONE progress=0 Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.483-03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" userId= Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.484-03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" providers=3 Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.514-03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" plugins=0 Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:18 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.546-03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" state=STATUS_STOPPED positionMs=0 volume= Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.548-03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.73:46398 @ 0x2aacab0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99560115" title="Made in 80 HD" Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:18 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:18 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:18 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:18 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:18.622-03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.73:46398 @ 0x2aacab0" latency=-119.779693ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 27 21:59:19 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:19 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:19 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:19 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:19 dac-pi volumio[1055]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 27 21:59:19 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:19 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:19 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:19 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:20 dac-pi volumio-remote-updater[728]: Test mode disabled Mar 27 21:59:20 dac-pi volumio-remote-updater[728]: Alpha mode disabled Mar 27 21:59:20 dac-pi volumio-remote-updater[728]: Alpha legacy test mode disabled Mar 27 21:59:20 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 27 21:59:20 dac-pi volumio[1055]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 27 21:59:20 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 21:59:20 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:20 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:20 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:20 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:21 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:21.079-03:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.73:46398 Mar 27 21:59:21 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:21.079-03:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.73:46398 Mar 27 21:59:21 dac-pi volumio5-onboarding[1882]: time=2026-03-27T21:59:21.085-03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.73:46412 Mar 27 21:59:21 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:21 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:21 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:21 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:21 dac-pi volumio[1055]: info: MyVolumio token set successfully Mar 27 21:59:21 dac-pi volumio[1055]: info: MYVOLUMIO: Adding device Mar 27 21:59:21 dac-pi volumio[1055]: info: MYVOLUMIO: Evaluating Server Mar 27 21:59:21 dac-pi volumio[1055]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:59:22 dac-pi volumio[1055]: info: MyVolumio Plan changed: premium Mar 27 21:59:22 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 27 21:59:22 dac-pi volumio[1055]: info: Removing browser output: myVolumio user plan is not superstar Mar 27 21:59:22 dac-pi volumio[1055]: info: Removing audio output: Mar 27 21:59:22 dac-pi volumio[1055]: info: MYVOLUMIO: Adding device Mar 27 21:59:22 dac-pi volumio[1055]: info: MYVOLUMIO: Evaluating Server Mar 27 21:59:23 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:23 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:23 dac-pi volumio[1055]: info: Setting Geolocation for MyVolumio to us4 Mar 27 21:59:23 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:23 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:23 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:23 dac-pi volumio[1055]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Mar 27 21:59:24 dac-pi volumio[1055]: info: Remote config written successfully Mar 27 21:59:24 dac-pi volumio[1055]: info: Starting Tunnel 1 Mar 27 21:59:24 dac-pi volumio[1055]: info: Starting Tunnel Connection Checker Mar 27 21:59:24 dac-pi volumio[1055]: info: Completed starting MyVolumio Plugin Mar 27 21:59:24 dac-pi volumio[1055]: info: Setting Geolocation for MyVolumio to us1 Mar 27 21:59:24 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:24 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:24 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:24 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:24 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:24 dac-pi volumio[1055]: info: MYVolumio Device enabled Mar 27 21:59:24 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 27 21:59:24 dac-pi volumio[1055]: info: MyVolumio status changed Mar 27 21:59:24 dac-pi volumio[1055]: info: Streaming services startup Mar 27 21:59:24 dac-pi volumio[1055]: info: Starting Streaming Daemon Mar 27 21:59:24 dac-pi volumio[1055]: info: Successfully Added MyVolumio device Mar 27 21:59:24 dac-pi sudo[2349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 27 21:59:24 dac-pi sudo[2349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:24 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:24 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:24 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 27 21:59:24 dac-pi sudo[2349]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:24 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 27 21:59:26 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 27 21:59:26 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 27 21:59:26 dac-pi volumio[1055]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 27 21:59:26 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 27 21:59:34 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 27 21:59:34 dac-pi sudo[2396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 27 21:59:34 dac-pi sudo[2396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:34 dac-pi sudo[2396]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:34 dac-pi volumio[1055]: info: MRS: MultiRoom plugin initialized Mar 27 21:59:35 dac-pi volumio[1055]: info: MRS: STOPPING SNAPCLIENT Mar 27 21:59:35 dac-pi volumio[1055]: info: MRS: Snap server stop Mar 27 21:59:35 dac-pi volumio[1055]: info: MRS: STOPPING volumioStreaming Mar 27 21:59:35 dac-pi sudo[2413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 27 21:59:35 dac-pi sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:35 dac-pi sudo[2415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 27 21:59:35 dac-pi sudo[2415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:35 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 27 21:59:35 dac-pi sudo[2418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 27 21:59:35 dac-pi sudo[2418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:35 dac-pi sudo[2413]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:35 dac-pi sudo[2421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 27 21:59:35 dac-pi sudo[2421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:35 dac-pi sudo[2415]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:35 dac-pi sudo[2421]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:35 dac-pi sudo[2418]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:35 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 27 21:59:35 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 27 21:59:36 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 27 21:59:36 dac-pi volumio[1055]: info: Preparing to generate the ALSA configuration file Mar 27 21:59:36 dac-pi volumio[1055]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:59:36 dac-pi volumio[1055]: info: Updating MyVolumio device info Mar 27 21:59:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:36 dac-pi volumio[1055]: info: Updating MyVolumio device info Mar 27 21:59:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:36 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetBrowseSources Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:59:37 dac-pi volumio[1055]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Mar 27 21:59:37 dac-pi volumio[1055]: info: Reading ALSA contributions from plugins. Mar 27 21:59:37 dac-pi volumio[1055]: info: MRS: Removed streaming files Mar 27 21:59:37 dac-pi volumio[1055]: info: MRS: volumioStreaming STOPPED Mar 27 21:59:37 dac-pi volumio[1055]: info: MRS: SNAPSERVER STOPPED Mar 27 21:59:37 dac-pi volumio[1055]: info: MRS: SNAPCLIENT STOPPED Mar 27 21:59:37 dac-pi volumio[1055]: error: Cannot start Volumio Streaming Daemon Mar 27 21:59:37 dac-pi volumio[1055]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 27 21:59:37 dac-pi volumio[1055]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 27 21:59:37 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:37 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:38 dac-pi volumio[1055]: info: Successfully Added MyVolumio device Mar 27 21:59:38 dac-pi volumio[1055]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 27 21:59:39 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:39 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:39 dac-pi volumio[1055]: info: CoreCommandRouter::volumioGetState Mar 27 21:59:39 dac-pi volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 27 21:59:39 dac-pi volumio[1055]: info: Asound.conf file unchanged, so no further update is needed Mar 27 21:59:39 dac-pi volumio[1055]: info: Output device has changed, restarting MPD Mar 27 21:59:39 dac-pi sudo[2452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 27 21:59:39 dac-pi sudo[2452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:39 dac-pi volumio[1055]: info: Output device has changed, restarting Shairport Sync Mar 27 21:59:39 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:59:39 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:59:39 dac-pi systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 21:59:39 dac-pi systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 21:59:39 dac-pi sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:59:39 dac-pi systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Mar 27 21:59:39 dac-pi sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:39 dac-pi sudo[2452]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:39 dac-pi autossh[2463]: port set to 0, monitoring disabled Mar 27 21:59:39 dac-pi autossh[2463]: starting ssh (count 1) Mar 27 21:59:39 dac-pi autossh[2463]: ssh child pid is 2467 Mar 27 21:59:39 dac-pi sudo[2455]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:39 dac-pi volumio[1055]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:59:39 dac-pi volumio[1055]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 21:59:39 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:59:39 dac-pi sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:59:40 dac-pi sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:40 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 27 21:59:40 dac-pi volumio[1055]: ------------------------------------ BT MESSAGE: [FUNC] onStart Mar 27 21:59:40 dac-pi volumio[1055]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Mar 27 21:59:40 dac-pi systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Mar 27 21:59:40 dac-pi systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Mar 27 21:59:40 dac-pi systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:59:40 dac-pi systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 21:59:40 dac-pi dbus-daemon[714]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.22' (uid=1000 pid=1055 comm="/usr/bin/node /volumio/index.js") Mar 27 21:59:40 dac-pi volumio[1055]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Mar 27 21:59:40 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:59:40 dac-pi volumio[1055]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Mar 27 21:59:40 dac-pi systemd[1]: mpd.service: Deactivated successfully. Mar 27 21:59:40 dac-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 21:59:40 dac-pi systemd[1]: mpd.service: Consumed 4.479s CPU time. Mar 27 21:59:40 dac-pi volumio[1055]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Mar 27 21:59:40 dac-pi systemd[1]: mpd.socket: Deactivated successfully. Mar 27 21:59:40 dac-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 21:59:40 dac-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 21:59:40 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:59:40 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:59:40 dac-pi volumiossh-tunnel[2467]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts. Mar 27 21:59:40 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 27 21:59:40 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 27 21:59:40 dac-pi volumio[1055]: info: MRS: Pushing multiroomSync output for this device Mar 27 21:59:40 dac-pi volumio[1055]: info: MRS: Pushing multiroomSync output Mar 27 21:59:40 dac-pi bluetoothd[2471]: Bluetooth daemon 5.83 Mar 27 21:59:40 dac-pi dbus-daemon[714]: [system] Successfully activated service 'org.bluez' Mar 27 21:59:40 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:59:40 dac-pi bluetoothd[2471]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:59:40 dac-pi bluetoothd[2471]: src/main.c:main() Adapter handling initialization failed Mar 27 21:59:40 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:59:40 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:59:40 dac-pi sudo[2479]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:59:40 dac-pi volumio[1055]: info: Adding audio output: Mar 27 21:59:40 dac-pi sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:59:40 dac-pi sudo[2479]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:40 dac-pi volumio[1055]: info: Adding audio output: Mar 27 21:59:41 dac-pi volumio[1055]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 21:59:41 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:59:41 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding METAVOLUMIO REST API Endpoints Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:59:41 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 27 21:59:41 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 27 21:59:41 dac-pi volumio[1055]: info: Preparing CD Folders Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding CD REST API Endpoints Mar 27 21:59:41 dac-pi volumio[1055]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 27 21:59:41 dac-pi volumio[1055]: info: Starting UDEV Watcher for CD Mar 27 21:59:41 dac-pi volumio[1055]: info: Detecting CD presence with UDEV Mar 27 21:59:41 dac-pi volumio[1055]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 27 21:59:41 dac-pi volumiossh-tunnel[2467]: Warning: remote port forwarding failed for listen port 8091 Mar 27 21:59:45 dac-pi mpd[2486]: 2026-03-27T21:59:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 21:59:45 dac-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 21:59:45 dac-pi sudo[2457]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:45 dac-pi systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:59:46 dac-pi volumio[1055]: warn: [cd-plugin] cdspeedctl: device or media not ready Mar 27 21:59:46 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 27 21:59:46 dac-pi volumio[1055]: info: Adding inputs REST Endpoints Mar 27 21:59:46 dac-pi volumio[1055]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 27 21:59:46 dac-pi volumio[1055]: info: Scanning Audio Inputs Mar 27 21:59:47 dac-pi volumio[1055]: info: Checking against Known Cards name Mar 27 21:59:47 dac-pi volumio[1055]: info: Adding Server instance for streaming Mar 27 21:59:47 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 27 21:59:47 dac-pi volumio[1055]: error: Hi Res Audio Failed Login: Missing Login Data Mar 27 21:59:47 dac-pi volumio[1055]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 27 21:59:47 dac-pi volumio[1055]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 27 21:59:47 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 27 21:59:47 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 27 21:59:47 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 27 21:59:47 dac-pi volumio[1055]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Mar 27 21:59:47 dac-pi volumio[1055]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Mar 27 21:59:47 dac-pi volumio[1055]: info: QobuzConnect: Starting Qobuz Connect socket and service Mar 27 21:59:47 dac-pi sudo[2509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 21:59:47 dac-pi sudo[2509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:47 dac-pi sudo[2509]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:47 dac-pi volumio[1055]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Mar 27 21:59:47 dac-pi volumio[1055]: info: Adding TIDAL REST API Endpoints Mar 27 21:59:47 dac-pi volumio[1055]: info: Stopping AccessToken refresher cron for QOBUZ Mar 27 21:59:47 dac-pi sudo[2535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 27 21:59:47 dac-pi sudo[2535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:48 dac-pi sudo[2535]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:48 dac-pi sudo[2561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 27 21:59:48 dac-pi sudo[2561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:59:48 dac-pi volumio[1055]: info: AccessToken refresher cron started for QOBUZ Mar 27 21:59:48 dac-pi volumio[1055]: info: Adding QOBUZ REST API Endpoints Mar 27 21:59:48 dac-pi systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 27 21:59:48 dac-pi volumio[1055]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:59:48 dac-pi volumio[1055]: info: MRS: Getting audio outputs on start Mar 27 21:59:48 dac-pi volumio[1055]: info: MRS: Requesting all other devices output Mar 27 21:59:48 dac-pi sudo[2561]: pam_unix(sudo:session): session closed for user root Mar 27 21:59:48 dac-pi volumio[1055]: info: Successfully Updated MyVolumio device Mar 27 21:59:48 dac-pi volumio[1055]: info: Successfully Updated MyVolumio device Mar 27 21:59:48 dac-pi volumio[1055]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:59:48 dac-pi volumio[1055]: DBusError: Message recipient disconnected from message bus without replying Mar 27 21:59:48 dac-pi volumio[1055]: at _methodReturnHandlers. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:343:27) Mar 27 21:59:48 dac-pi volumio[1055]: at handleMessage (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:101:11) Mar 27 21:59:48 dac-pi volumio[1055]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:151:9) Mar 27 21:59:48 dac-pi volumio[1055]: at EventEmitter.emit (node:events:514:28) Mar 27 21:59:48 dac-pi volumio[1055]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:132:14 Mar 27 21:59:48 dac-pi volumio[1055]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/message.js:65:9) Mar 27 21:59:48 dac-pi volumio[1055]: at Socket.emit (node:events:514:28) Mar 27 21:59:48 dac-pi volumio[1055]: at emitReadable_ (node:internal/streams/readable:609:12) Mar 27 21:59:48 dac-pi volumio[1055]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { Mar 27 21:59:48 dac-pi volumio[1055]: type: 'org.freedesktop.DBus.Error.NoReply', Mar 27 21:59:48 dac-pi volumio[1055]: text: 'Message recipient disconnected from message bus without replying', Mar 27 21:59:48 dac-pi volumio[1055]: reply: Message { Mar 27 21:59:48 dac-pi volumio[1055]: type: 3, Mar 27 21:59:48 dac-pi volumio[1055]: _sent: false, Mar 27 21:59:48 dac-pi volumio[1055]: _serial: 3, Mar 27 21:59:48 dac-pi volumio[1055]: path: undefined, Mar 27 21:59:48 dac-pi volumio[1055]: interface: undefined, Mar 27 21:59:48 dac-pi volumio[1055]: member: undefined, Mar 27 21:59:48 dac-pi volumio[1055]: errorName: 'org.freedesktop.DBus.Error.NoReply', Mar 27 21:59:48 dac-pi volumio[1055]: replySerial: 2, Mar 27 21:59:48 dac-pi volumio[1055]: destination: ':1.22', Mar 27 21:59:48 dac-pi volumio[1055]: sender: 'org.freedesktop.DBus', Mar 27 21:59:48 dac-pi volumio[1055]: signature: 's', Mar 27 21:59:48 dac-pi volumio[1055]: body: [ Mar 27 21:59:48 dac-pi volumio[1055]: 'Message recipient disconnected from message bus without replying' Mar 27 21:59:48 dac-pi volumio[1055]: ], Mar 27 21:59:48 dac-pi volumio[1055]: flags: 1 Mar 27 21:59:48 dac-pi volumio[1055]: } Mar 27 21:59:48 dac-pi volumio[1055]: } Mar 27 21:59:49 dac-pi volumio[1055]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:59:55 dac-pi sudo[2613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 21:58' Mar 27 21:59:55 dac-pi sudo[2613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"