Mar 27 21:50:01 dac-pi volumio[1203]: info: Loading plugin "ytmusic"... Mar 27 21:50:04 dac-pi systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 27 21:50:04 dac-pi volumio[1203]: info: Loading plugin "autostart"... Mar 27 21:50:05 dac-pi volumio[1203]: info: Applying required configuration parameters for plugin autostart Mar 27 21:50:05 dac-pi volumio[1203]: info: AutoStart - onVolumioStart - read config.json Mar 27 21:50:05 dac-pi volumio[1203]: info: Loading plugin "gpio-buttons"... Mar 27 21:50:06 dac-pi volumio[1203]: gpiox_arm32 V2.2.1 Mar 27 21:50:06 dac-pi volumio-remote-updater[761]: [2026-03-27 21:50:06] [connect] Successful connection Mar 27 21:50:07 dac-pi volumio[1203]: info: gpio-buttons: Initialized Mar 27 21:50:07 dac-pi volumio[1203]: info: Loading plugin "gpio_button_led"... Mar 27 21:50:08 dac-pi volumio[1203]: gpiox_arm32 V2.2.1 Mar 27 21:50:09 dac-pi volumio[1203]: info: Plugin now_playing is not enabled Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "outputs"... Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "albumart"... Mar 27 21:50:09 dac-pi volumio[1203]: info: Plugin example_plugin is not enabled Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "inputs"... Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "updater_comm"... Mar 27 21:50:09 dac-pi volumio[1203]: info: Plugin mpdemulation is not enabled Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "rest_api"... Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "websocket"... Mar 27 21:50:09 dac-pi volumio[1203]: info: Starting Socket.io Server version 1.7.4 Mar 27 21:50:09 dac-pi volumio[1203]: info: Loading plugin "minidlna"... Mar 27 21:50:09 dac-pi volumio[1502]: Forking 3 albumart workers Mar 27 21:50:10 dac-pi volumio[1203]: info: Applying required configuration parameters for plugin minidlna Mar 27 21:50:10 dac-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Mar 27 21:50:10 dac-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:50:10 dac-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:50:10 dac-pi upmpdcli[1541]: Could not open config: /tmp/upmpdcli.conf Mar 27 21:50:10 dac-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:50:10 dac-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 27 21:50:10 dac-pi volumio[1203]: info: Loading plugin "radio_paradise"... Mar 27 21:50:12 dac-pi volumio[1203]: info: Applying required configuration parameters for plugin radio_paradise Mar 27 21:50:12 dac-pi volumio[1203]: info: [1774659012917] [RadioParadise] API delay: 5 Mar 27 21:50:12 dac-pi volumio[1203]: info: Loading plugin "backup_restore"... Mar 27 21:50:14 dac-pi volumio[1203]: info: Applying required configuration parameters for plugin backup_restore Mar 27 21:50:14 dac-pi volumio[1203]: info: Loading plugin "mpd_oled"... Mar 27 21:50:15 dac-pi volumio[1203]: info: Applying required configuration parameters for plugin mpd_oled Mar 27 21:50:15 dac-pi volumio[1203]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Mar 27 21:50:16 dac-pi volumio[1203]: info: Loading plugin "rotaryencoder2"... Mar 27 21:50:16 dac-pi volumio[1513]: Starting albumart workers Mar 27 21:50:16 dac-pi volumio[1514]: Starting albumart workers Mar 27 21:50:16 dac-pi volumio[1515]: Starting albumart workers Mar 27 21:50:18 dac-pi volumio[1203]: info: Loading plugin "Systeminfo"... Mar 27 21:50:20 dac-pi volumio[1203]: info: Plugin randomizer is not enabled Mar 27 21:50:20 dac-pi volumio[1203]: info: Loading i18n strings for locale pt Mar 27 21:50:20 dac-pi volumio[1203]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 27 21:50:20 dac-pi volumio[1203]: Updating browse sources language Mar 27 21:50:20 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:50:20 dac-pi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::initPlayerControls Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 21:50:21 dac-pi volumio[1203]: Express server listening on port 3000 Mar 27 21:50:21 dac-pi volumio[1203]: [Metrics] WebUI: 31s 4.05ms Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreStateMachine::resetVolumioState Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreStateMachine::getcurrentVolume Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreStateMachine::getcurrentVolume Mar 27 21:50:21 dac-pi volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:50:21 dac-pi sudo[1599]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 21:50:21 dac-pi sudo[1599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:21 dac-pi sudo[1599]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:21 dac-pi volumio[1203]: info: Volumio Network Manager: Network status updated: 1 Mar 27 21:50:21 dac-pi sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 21:50:21 dac-pi sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:21 dac-pi volumio-remote-updater[761]: [2026-03-27 21:50:21] [connect] Successful connection Mar 27 21:50:21 dac-pi sudo[1601]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:21 dac-pi volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:50:21 dac-pi volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:50:21 dac-pi volumio[1203]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 27 21:50:21 dac-pi volumio[1203]: wlan0 Interface doesn't support scanning. Mar 27 21:50:21 dac-pi volumio[1203]: info: Cannot use regular scanning, forcing with ap-force Mar 27 21:50:22 dac-pi sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 27 21:50:22 dac-pi sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:22 dac-pi sudo[1610]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:22 dac-pi volumio[1203]: nl80211 not found. Mar 27 21:50:22 dac-pi volumio[1203]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 27 21:50:22 dac-pi volumio[1203]: nl80211 not found. Mar 27 21:50:22 dac-pi volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:22 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreStateMachine::updateTrackBlock Mar 27 21:50:22 dac-pi volumio[1203]: info: CorePlayQueue::getTrackBlock Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:50:22 dac-pi volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:22 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreStateMachine::updateTrackBlock Mar 27 21:50:22 dac-pi volumio[1203]: info: CorePlayQueue::getTrackBlock Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:50:22 dac-pi volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:50:22 dac-pi volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:50:22 dac-pi volumio[1203]: info: Reloading queue from file Mar 27 21:50:22 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:23 dac-pi volumio[1203]: 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:50:23 dac-pi volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:23 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreStateMachine::setRepeat false single undefined Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:23 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreStateMachine::setRandom true Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:23 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:23 dac-pi volumio[1203]: info: Setting Device type: Raspberry PI Mar 27 21:50:23 dac-pi volumio-remote-updater[761]: [2026-03-27 21:50:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774659021 101 Mar 27 21:50:23 dac-pi volumio[1203]: 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:50:23 dac-pi volumio[1203]: info: Completed loading Core Plugins Mar 27 21:50:23 dac-pi volumio[1203]: info: Preparing to generate the ALSA configuration file Mar 27 21:50:23 dac-pi volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:23 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:23 dac-pi volumio[1203]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 27 21:50:23 dac-pi volumio[1203]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a52082 Mar 27 21:50:23 dac-pi volumio[1203]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 27 21:50:23 dac-pi volumio[1203]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Mar 27 21:50:23 dac-pi volumio[1203]: info: Reading ALSA contributions from plugins. Mar 27 21:50:23 dac-pi sudo[1626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 27 21:50:23 dac-pi sudo[1626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:23 dac-pi volumio[1203]: info: Discovery: adding 610b1086-e212-4b9a-9659-b89851773d7e Mar 27 21:50:23 dac-pi volumio[1203]: info: Discovery: Found device DAC-PI Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:23 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:23 dac-pi volumio[1203]: info: Discovery: this is already registered, 610b1086-e212-4b9a-9659-b89851773d7e Mar 27 21:50:23 dac-pi volumio[1203]: info: Discovery: Found device DAC-PI Mar 27 21:50:23 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:23 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:23 dac-pi volumio[1203]: info: gpio-buttons: Socket connected to Volumio Mar 27 21:50:23 dac-pi volumio[1203]: info: Asound.conf file written Mar 27 21:50:23 dac-pi sudo[1631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 27 21:50:23 dac-pi sudo[1631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:24 dac-pi sudo[1631]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:24 dac-pi volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 27 21:50:24 dac-pi volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 27 21:50:24 dac-pi volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 27 21:50:24 dac-pi volumio[1203]: info: Output device has changed, restarting MPD Mar 27 21:50:24 dac-pi volumio[1203]: info: Output device has changed, restarting Shairport Sync Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:24 dac-pi sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:50:24 dac-pi sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:24 dac-pi sudo[1637]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:24 dac-pi sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:50:24 dac-pi sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:24 dac-pi volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:50:24 dac-pi volumio[1203]: info: ___________ START PLUGINS ___________ Mar 27 21:50:24 dac-pi volumio[1203]: info: ControllerMpd::onStart: Initializing MPD Mar 27 21:50:24 dac-pi volumio[1203]: info: Creating MPD Configuration file Mar 27 21:50:24 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:50:24 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:50:24 dac-pi volumio[1203]: info: [1774659024403] CoreMusicLibrary::Adding element Servidores multimédia Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:50:24 dac-pi sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 27 21:50:24 dac-pi sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:24 dac-pi volumio[1203]: info: UPNP Browser: Client initialized successfully Mar 27 21:50:24 dac-pi sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:50:24 dac-pi sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:24 dac-pi sudo[1659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:50:24 dac-pi sudo[1659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:24 dac-pi sudo[1654]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:24 dac-pi sudo[1658]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:50:24 dac-pi sudo[1658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:50:24 dac-pi sudo[1668]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 27 21:50:24 dac-pi sudo[1658]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:24 dac-pi systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 21:50:24 dac-pi sudo[1647]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:24 dac-pi systemd[1]: mpd.service: Deactivated successfully. Mar 27 21:50:24 dac-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 21:50:24 dac-pi systemd[1]: mpd.socket: Deactivated successfully. Mar 27 21:50:24 dac-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 21:50:24 dac-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 21:50:24 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:50:24 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:50:24 dac-pi volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:25 dac-pi volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:50:25 dac-pi volumio[1203]: info: [1774659025068] CoreMusicLibrary::Adding element Last_100 Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:50:25 dac-pi sudo[1671]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:50:25 dac-pi sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:50:25 dac-pi sudo[1682]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 27 21:50:25 dac-pi sudo[1671]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:50:25 dac-pi volumio[1203]: info: [1774659025140] CoreMusicLibrary::Adding element Webradio Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:50:25 dac-pi systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 27 21:50:25 dac-pi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 27 21:50:25 dac-pi systemd[1]: setdatetime-helper.service: Consumed 1.421s CPU time. Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:50:25 dac-pi volumio[1203]: info: Initializing BBC Radios Mar 27 21:50:25 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:25.414-03:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:50:25 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:25 dac-pi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Mar 27 21:50:25 dac-pi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:50:25 dac-pi volumio[1203]: info: [ytcr] Data store TTL expired - clearing it... Mar 27 21:50:25 dac-pi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 27 21:50:25 dac-pi sudo[1626]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:26 dac-pi volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:50:26 dac-pi volumio[1203]: info: [1774659026155] CoreMusicLibrary::Adding element YouTube Music Mar 27 21:50:26 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:50:26 dac-pi volumio[1203]: Cannot find translation for source YouTube Music Mar 27 21:50:26 dac-pi volumio[1203]: info: AutoStart - onStart - waiting for system ready state Mar 27 21:50:26 dac-pi volumio[1203]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Mar 27 21:50:26 dac-pi volumio[1203]: info: AutoStart - Maximum wait time: 300 seconds Mar 27 21:50:26 dac-pi volumio[1203]: info: AutoStart - Startup volume disabled Mar 27 21:50:26 dac-pi volumio[1203]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:50:26 dac-pi volumio[1203]: info: gpio-buttons: Creating GPIO triggers... Mar 27 21:50:26 dac-pi volumio[1203]: info: gpio-buttons: GPIO16 configured: button1 -> next Mar 27 21:50:26 dac-pi volumio[1203]: info: gpio-buttons: GPIO26 configured: button2 -> previous Mar 27 21:50:26 dac-pi volumio[1203]: info: gpio-buttons: GPIO22 configured: button3 -> playPause Mar 27 21:50:26 dac-pi volumio[1203]: info: GPIOButtonLED: Starting plugin Mar 27 21:50:26 dac-pi volumio[1203]: info: Loading i18n strings for locale pt Mar 27 21:50:26 dac-pi volumio[1203]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 27 21:50:26 dac-pi volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 21:50:26 dac-pi volumio[1203]: info: [1774659026407] CoreMusicLibrary::Adding element Radio Paradise Mar 27 21:50:26 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:50:26 dac-pi volumio[1203]: Cannot find translation for source YouTube Music Mar 27 21:50:26 dac-pi volumio[1203]: Cannot find translation for source Radio Paradise Mar 27 21:50:26 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 27 21:50:26 dac-pi sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Mar 27 21:50:26 dac-pi sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:26 dac-pi sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Mar 27 21:50:26 dac-pi sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:26 dac-pi sudo[1736]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:27 dac-pi volumio[1203]: info: Volumio Calling Home Mar 27 21:50:27 dac-pi sudo[1742]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 27 21:50:27 dac-pi sudo[1742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:27 dac-pi volumio[1203]: info: gpio-buttons: Started successfully Mar 27 21:50:27 dac-pi sudo[1742]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:27 dac-pi volumio[1203]: info: Preparing to generate the ALSA configuration file Mar 27 21:50:29 dac-pi volumio[1203]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Mar 27 21:50:29 dac-pi volumio[1203]: info: Reading ALSA contributions from plugins. Mar 27 21:50:30 dac-pi volumio[1203]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Mar 27 21:50:30 dac-pi volumio[1203]: info: minidlna: Found miniDLNA version 1.3.0 Mar 27 21:50:30 dac-pi volumio[1203]: info: minidlna: Starting minidlna.service Mar 27 21:50:30 dac-pi sudo[1749]: 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:50:30 dac-pi sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:30 dac-pi sudo[1749]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:30 dac-pi volumio[1203]: info: MPD Permissions set Mar 27 21:50:30 dac-pi volumio[1203]: info: MPD Permissions set Mar 27 21:50:30 dac-pi volumio[1203]: info: Upmpdcli Daemon Started Mar 27 21:50:30 dac-pi sudo[1758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Mar 27 21:50:30 dac-pi sudo[1758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:30 dac-pi dbus-daemon[750]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1664 comm="/usr/bin/volumio5-onboarding") Mar 27 21:50:30 dac-pi systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Mar 27 21:50:30 dac-pi volumio[1203]: 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:50:30 dac-pi sudo[1758]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:30 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:50:30 dac-pi volumio[1203]: info: Volumio called home Mar 27 21:50:30 dac-pi volumio[1203]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Mar 27 21:50:30 dac-pi volumio[1203]: 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:50:30 dac-pi bluetoothd[1764]: Bluetooth daemon 5.83 Mar 27 21:50:30 dac-pi dbus-daemon[750]: [system] Successfully activated service 'org.bluez' Mar 27 21:50:30 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:50:30 dac-pi bluetoothd[1764]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:50:30 dac-pi bluetoothd[1764]: src/main.c:main() Adapter handling initialization failed Mar 27 21:50:31 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:50:31 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:50:31 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:31.009-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:50:31 dac-pi dbus-daemon[750]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1664 comm="/usr/bin/volumio5-onboarding") Mar 27 21:50:31 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:50:31 dac-pi bluetoothd[1772]: Bluetooth daemon 5.83 Mar 27 21:50:31 dac-pi dbus-daemon[750]: [system] Successfully activated service 'org.bluez' Mar 27 21:50:31 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:50:31 dac-pi bluetoothd[1772]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:50:31 dac-pi bluetoothd[1772]: src/main.c:main() Adapter handling initialization failed Mar 27 21:50:31 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:50:31 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:50:31 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:31.371-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:50:31 dac-pi dbus-daemon[750]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1664 comm="/usr/bin/volumio5-onboarding") Mar 27 21:50:31 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:50:31 dac-pi volumio[1203]: 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:50:31 dac-pi volumio[1203]: info: minidlna: systemctl start minidlna.service succeeded. Mar 27 21:50:31 dac-pi volumio[1203]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:50:31 dac-pi bluetoothd[1775]: Bluetooth daemon 5.83 Mar 27 21:50:31 dac-pi dbus-daemon[750]: [system] Successfully activated service 'org.bluez' Mar 27 21:50:31 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:50:31 dac-pi volumio[1203]: info: GPIOButtonLED: dtoverlay already correct Mar 27 21:50:31 dac-pi bluetoothd[1775]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:50:31 dac-pi bluetoothd[1775]: src/main.c:main() Adapter handling initialization failed Mar 27 21:50:31 dac-pi volumio[1203]: info: GPIOButtonLED: LED+ on GPIO 14 Mar 27 21:50:31 dac-pi volumio[1203]: info: GPIOButtonLED: LED polarity = normal Mar 27 21:50:31 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:50:31 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:50:31 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:31.713-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:50:31 dac-pi volumio[1203]: info: GPIOButtonLED: LED blink slow Mar 27 21:50:31 dac-pi dbus-daemon[750]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1664 comm="/usr/bin/volumio5-onboarding") Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:31 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:31 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi bluetoothd[1778]: Bluetooth daemon 5.83 Mar 27 21:50:32 dac-pi dbus-daemon[750]: [system] Successfully activated service 'org.bluez' Mar 27 21:50:32 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi bluetoothd[1778]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:50:32 dac-pi bluetoothd[1778]: src/main.c:main() Adapter handling initialization failed Mar 27 21:50:32 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:50:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:32.074-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:50:32 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:50:32 dac-pi dbus-daemon[750]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1664 comm="/usr/bin/volumio5-onboarding") Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:32 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:50:32 dac-pi bluetoothd[1791]: Bluetooth daemon 5.83 Mar 27 21:50:32 dac-pi dbus-daemon[750]: [system] Successfully activated service 'org.bluez' Mar 27 21:50:32 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:50:32 dac-pi bluetoothd[1791]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:50:32 dac-pi bluetoothd[1791]: src/main.c:main() Adapter handling initialization failed Mar 27 21:50:32 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:50:32 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:50:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:32.449-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:50:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:32.449-03:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Mar 27 21:50:32 dac-pi volumio[1203]: 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:50:32 dac-pi volumio[1203]: info: Received Get System Info Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:50:32 dac-pi volumio[1203]: info: Discovery: Getting this device information Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:32 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:50:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:32.675-03:00 level=INFO msg="system info for 354feb79f93c035f4ee941e889cbaf03" deviceName=DAC-PI deviceVariant=volumio deviceModel= softwareVersion=4.119 Mar 27 21:50:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:32.782-03:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 27 21:50:32 dac-pi volumio[1203]: info: Received Get System Info Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:50:32 dac-pi volumio[1203]: info: Discovery: Getting this device information Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:32 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:32 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:50:32 dac-pi volumio[1203]: info: Starting Shairport Sync Mar 27 21:50:32 dac-pi volumio[1203]: info: Starting Shairport Sync Mar 27 21:50:33 dac-pi volumio[1203]: info: Starting Shairport Sync Mar 27 21:50:33 dac-pi sudo[1796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:50:33 dac-pi sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:50:33 dac-pi volumio-remote-updater[761]: Test mode disabled Mar 27 21:50:33 dac-pi volumio-remote-updater[761]: Alpha mode disabled Mar 27 21:50:33 dac-pi volumio-remote-updater[761]: Alpha legacy test mode disabled Mar 27 21:50:33 dac-pi sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:33 dac-pi sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:33 dac-pi sudo[1799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:50:33 dac-pi sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:33 dac-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 21:50:33 dac-pi systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 21:50:33 dac-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:50:33 dac-pi systemd[1]: shairport-sync.service: Consumed 2.096s CPU time. Mar 27 21:50:33 dac-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:50:33 dac-pi sudo[1800]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:33 dac-pi sudo[1796]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:33 dac-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 21:50:33 dac-pi systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 21:50:33 dac-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:50:33 dac-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:50:33 dac-pi sudo[1799]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:33 dac-pi volumio[1203]: info: Asound.conf file unchanged, so no further update is needed Mar 27 21:50:33 dac-pi volumio[1203]: info: Output device has changed, restarting MPD Mar 27 21:50:33 dac-pi mpd[1683]: 2026-03-27T21:50:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 21:50:33 dac-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 21:50:33 dac-pi sudo[1659]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:33 dac-pi sudo[1639]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:33 dac-pi volumio[1203]: info: Output device has changed, restarting Shairport Sync Mar 27 21:50:33 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:33 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:33 dac-pi sudo[1829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:50:33 dac-pi sudo[1829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:33 dac-pi systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:50:33 dac-pi sudo[1738]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:34 dac-pi systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Mar 27 21:50:34 dac-pi sudo[1825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:50:34 dac-pi systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Mar 27 21:50:34 dac-pi systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:50:34 dac-pi systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 21:50:34 dac-pi sudo[1825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:34 dac-pi volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:50:34 dac-pi sudo[1825]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:34 dac-pi systemd[1]: mpd.service: Deactivated successfully. Mar 27 21:50:34 dac-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 21:50:34 dac-pi systemd[1]: mpd.service: Consumed 6.036s CPU time. Mar 27 21:50:34 dac-pi systemd[1]: mpd.socket: Deactivated successfully. Mar 27 21:50:34 dac-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 21:50:34 dac-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 21:50:34 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:50:34 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:50:34 dac-pi sudo[1839]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:50:34 dac-pi sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:50:34 dac-pi sudo[1839]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:35 dac-pi volumio[1203]: info: [ROTARYENCODER2] Socket connected to Volumio Mar 27 21:50:35 dac-pi volumio[1203]: info: Shairport-Sync Started Mar 27 21:50:35 dac-pi volumio[1203]: Error adding Membership: Error: addMembership EINVAL Mar 27 21:50:35 dac-pi volumio[1203]: info: Shairport-Sync Started Mar 27 21:50:35 dac-pi volumio[1203]: info: Shairport-Sync Started Mar 27 21:50:35 dac-pi volumio[1203]: info: GPIOButtonLED: Boot complete - LED solid Mar 27 21:50:35 dac-pi volumio[1203]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 27 21:50:35 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 21:50:35 dac-pi volumio[1203]: info: MPD Permissions set Mar 27 21:50:35 dac-pi volumio[1203]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Mar 27 21:50:35 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:50:36 dac-pi sudo[1859]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=27 active_low=true gpio_pull=up keycode=20 Mar 27 21:50:36 dac-pi sudo[1859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:36 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:36 dac-pi volumio[1203]: info: MPD running with PID1841 Mar 27 21:50:36 dac-pi volumio[1203]: ,establishing connection Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi kernel: input: button@1b as /devices/platform/button@1b/input/input3 Mar 27 21:50:36 dac-pi sudo[1859]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 21:50:36 dac-pi volumio[1203]: info: Received Get System Info Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 21:50:36 dac-pi volumio[1203]: info: Discovery: Getting this device information Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:36 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:36 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 21:50:36 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 21:50:36 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:36.342-03:00 level=INFO msg="enabling local network discovery" Mar 27 21:50:36 dac-pi volumio[1203]: info: Starting Shairport Sync Mar 27 21:50:36 dac-pi sudo[1878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 21:50:36 dac-pi sudo[1878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:36 dac-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 21:50:36 dac-pi systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 21:50:36 dac-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:50:36 dac-pi systemd[1]: shairport-sync.service: Consumed 1.371s CPU time. Mar 27 21:50:36 dac-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 21:50:36 dac-pi sudo[1878]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:36 dac-pi volumio[1203]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:50:36 dac-pi sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 27 21:50:36 dac-pi sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:37 dac-pi sudo[1883]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:37 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:50:37.384-03:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 27 21:50:37 dac-pi volumio[1203]: info: Shairport-Sync Started Mar 27 21:50:38 dac-pi sudo[1902]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 27 21:50:38 dac-pi sudo[1902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:38 dac-pi sudo[1902]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:38 dac-pi mpd[1841]: 2026-03-27T21:50:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 21:50:38 dac-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 21:50:38 dac-pi sudo[1829]: pam_unix(sudo:session): session closed for user root Mar 27 21:50:38 dac-pi systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:50:39 dac-pi volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Mar 27 21:50:39 dac-pi volumio[1203]: assert.ok(self.idling) Mar 27 21:50:39 dac-pi volumio[1203]: error: The expression evaluated to a falsy value: Mar 27 21:50:39 dac-pi volumio[1203]: assert.ok(self.idling) Mar 27 21:50:39 dac-pi volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Mar 27 21:50:39 dac-pi volumio[1203]: assert.ok(self.idling) Mar 27 21:50:39 dac-pi volumio[1203]: error: The expression evaluated to a falsy value: Mar 27 21:50:39 dac-pi volumio[1203]: assert.ok(self.idling) Mar 27 21:50:39 dac-pi volumio[1203]: error: updateQueue error: null Mar 27 21:50:39 dac-pi volumio[1203]: error: updateQueue error: null Mar 27 21:50:39 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:39 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:39 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:39 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:39 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:39 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:39 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:39 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:39 dac-pi volumio[1203]: info: [yt-cast-receiver] DIAL server listening on port 8098 Mar 27 21:50:39 dac-pi volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 21:50:39 dac-pi volumio[1203]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '3'. Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:40 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:40 dac-pi volumio[1203]: info: VolumeController:: Volume=undefined Mute =false Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:40 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreStateMachine::pushState Mar 27 21:50:40 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreCommandRouter::volumioPushState Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:40 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:40 dac-pi volumio[1203]: info: Completed starting Core Plugins Mar 27 21:50:40 dac-pi volumio[1203]: info: ------------------------------------------- Mar 27 21:50:40 dac-pi volumio[1203]: info: ----- MyVolumio plugins startup ---- Mar 27 21:50:40 dac-pi volumio[1203]: info: ------------------------------------------- Mar 27 21:50:40 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:40 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:40 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:40 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:41 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:41 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:41 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:41 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:41 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:41 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:41 dac-pi volumio[1203]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:50:42 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:42 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:42 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:42 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:42 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:42 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:42 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:42 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:42 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:42 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:42 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:43 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:43 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:43 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:43 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:43 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:43 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:43 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:43 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:43 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:44 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:44 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:44 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:44 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:44 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:44 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:44 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:44 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:44 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:44 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:44 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:44 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:45 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:45 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:45 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:45 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:45 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:45 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:45 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:45 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:45 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:45 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:45 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:45 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:46 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:46 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:46 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:46 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:46 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:46 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:46 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:46 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:46 dac-pi volumio[1203]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:50:47 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:47 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:47 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:47 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:47 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:47 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:47 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:47 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:47 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:47 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:47 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:47 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:48 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:48 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:48 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:48 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:48 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:48 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:48 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:48 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:49 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:49 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:49 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:49 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:49 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:49 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:49 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:49 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:49 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:49 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:50 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:50:50 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin bluetooth to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin multiroom to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin metavolumio to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin cd_controller to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 27 21:50:50 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 27 21:50:54 dac-pi ntpd[985]: CLOCK: time stepped by 0.448682 Mar 27 21:50:54 dac-pi ntpd[985]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 27 21:50:55 dac-pi volumio[1203]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 27 21:50:55 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 27 21:50:55 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:55 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:50:55 dac-pi volumio[1203]: info: Starting MyVolumio Remote Streaming Endpoints Mar 27 21:50:56 dac-pi volumio[1203]: info: MyVolumio login type: Token Mar 27 21:50:56 dac-pi volumio[1203]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 27 21:50:56 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 27 21:50:59 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 27 21:50:59 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 27 21:50:59 dac-pi volumio[1203]: info: Streaming services startup Mar 27 21:50:59 dac-pi volumio[1203]: info: Starting Streaming Daemon Mar 27 21:50:59 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 27 21:50:59 dac-pi sudo[2143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 27 21:50:59 dac-pi sudo[2143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:50:59 dac-pi sudo[2143]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:00 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:51:00 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:00 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 21:51:00 dac-pi volumio[1203]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:51:01 dac-pi volumio[1203]: error: Cannot start Volumio Streaming Daemon Mar 27 21:51:01 dac-pi volumio[1203]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 27 21:51:01 dac-pi volumio[1203]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 27 21:51:01 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:01 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:01 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:01 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:01 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:01 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:01 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:01 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:02 dac-pi volumio[1203]: error: MyVolumio Custom Token format not valid, refreshing it Mar 27 21:51:02 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:02 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:02 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:02 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:02 dac-pi volumio-remote-updater[761]: Test mode disabled Mar 27 21:51:02 dac-pi volumio-remote-updater[761]: Alpha mode disabled Mar 27 21:51:02 dac-pi volumio-remote-updater[761]: Alpha legacy test mode disabled Mar 27 21:51:02 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 27 21:51:03 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:03 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:03 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:03 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:03 dac-pi volumio[1203]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 27 21:51:03 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 21:51:03 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:03 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:03 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:03 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:04 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:04 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:04 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:04 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:04 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:04 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:04 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:04 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:04 dac-pi volumio[1203]: info: MyVolumio login type: Token Mar 27 21:51:04 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:04 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:05 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:05 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:05 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:05 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:05 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:05 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:06 dac-pi volumio[1203]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:51:06 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:06 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:06 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:06 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:06 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:06 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:06 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:06 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:07 dac-pi volumio[1203]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 27 21:51:07 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:07 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:07 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:07 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:07 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:07 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:07 dac-pi volumio[1203]: info: MyVolumio token set successfully Mar 27 21:51:07 dac-pi volumio[1203]: info: MYVOLUMIO: Adding device Mar 27 21:51:07 dac-pi volumio[1203]: info: MYVOLUMIO: Evaluating Server Mar 27 21:51:07 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:07 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:08 dac-pi volumio[1203]: info: MyVolumio Plan changed: premium Mar 27 21:51:08 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 27 21:51:08 dac-pi volumio[1203]: info: Removing browser output: myVolumio user plan is not superstar Mar 27 21:51:08 dac-pi volumio[1203]: info: Removing audio output: Mar 27 21:51:08 dac-pi volumio[1203]: info: MYVOLUMIO: Adding device Mar 27 21:51:08 dac-pi volumio[1203]: info: MYVOLUMIO: Evaluating Server Mar 27 21:51:09 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:09 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:09 dac-pi volumio[1203]: info: Setting Geolocation for MyVolumio to us4 Mar 27 21:51:09 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:09 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:09 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:09 dac-pi volumio[1203]: info: Remote config written successfully Mar 27 21:51:09 dac-pi volumio[1203]: info: Starting Tunnel 1 Mar 27 21:51:09 dac-pi volumio[1203]: info: Starting Tunnel Connection Checker Mar 27 21:51:09 dac-pi volumio[1203]: info: Completed starting MyVolumio Plugin Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:10 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:10 dac-pi volumio[1203]: info: MYVolumio Device enabled Mar 27 21:51:10 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 27 21:51:10 dac-pi volumio[1203]: info: MyVolumio status changed Mar 27 21:51:10 dac-pi volumio[1203]: info: Streaming services startup Mar 27 21:51:10 dac-pi volumio[1203]: info: Starting Streaming Daemon Mar 27 21:51:10 dac-pi volumio[1203]: info: Updating MyVolumio device info Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:10 dac-pi sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 27 21:51:10 dac-pi sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:10 dac-pi volumio[1203]: info: Successfully Added MyVolumio device Mar 27 21:51:10 dac-pi volumio[1203]: info: Setting Geolocation for MyVolumio to us1 Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:10 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:10 dac-pi sudo[2387]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:11 dac-pi volumio[1203]: error: Cannot start Volumio Streaming Daemon Mar 27 21:51:11 dac-pi volumio[1203]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 27 21:51:11 dac-pi volumio[1203]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:11 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:11 dac-pi volumio[1203]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 27 21:51:11 dac-pi volumio[1203]: error: [MyVolumio PluginManager] Cache data is invalid! Mar 27 21:51:11 dac-pi volumio[1203]: info: Successfully Updated MyVolumio device Mar 27 21:51:11 dac-pi volumio[1203]: info: Updating MyVolumio device info Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:11 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:11 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:11 dac-pi volumio[1203]: 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:51:12 dac-pi volumio[1203]: info: Successfully Added MyVolumio device Mar 27 21:51:12 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:12 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:12 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:12 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:12 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:12 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:12 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 27 21:51:14 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 27 21:51:14 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 27 21:51:14 dac-pi volumio[1203]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 27 21:51:14 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 27 21:51:21 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 27 21:51:21 dac-pi sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 27 21:51:21 dac-pi sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:21 dac-pi sudo[2422]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:22 dac-pi volumio[1203]: info: MRS: MultiRoom plugin initialized Mar 27 21:51:22 dac-pi volumio[1203]: info: MRS: STOPPING SNAPCLIENT Mar 27 21:51:22 dac-pi volumio[1203]: info: MRS: Snap server stop Mar 27 21:51:22 dac-pi volumio[1203]: info: MRS: STOPPING volumioStreaming Mar 27 21:51:22 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 27 21:51:22 dac-pi sudo[2445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 27 21:51:22 dac-pi sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 27 21:51:22 dac-pi sudo[2445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:22 dac-pi sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:22 dac-pi sudo[2439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 27 21:51:22 dac-pi sudo[2441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 27 21:51:22 dac-pi sudo[2441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:22 dac-pi sudo[2439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:22 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 27 21:51:22 dac-pi sudo[2445]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:22 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 27 21:51:22 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 27 21:51:23 dac-pi sudo[2443]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:23 dac-pi sudo[2439]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:23 dac-pi sudo[2441]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:23 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 27 21:51:23 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 27 21:51:23 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 27 21:51:23 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 27 21:51:23 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 27 21:51:23 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 27 21:51:24 dac-pi volumio[1203]: info: Preparing to generate the ALSA configuration file Mar 27 21:51:24 dac-pi volumio[1203]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetBrowseSources Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 27 21:51:24 dac-pi volumio[1203]: info: Successfully Updated MyVolumio device Mar 27 21:51:24 dac-pi volumio[1203]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 27 21:51:24 dac-pi volumio[1203]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Mar 27 21:51:24 dac-pi volumio[1203]: info: Reading ALSA contributions from plugins. Mar 27 21:51:24 dac-pi volumio[1203]: info: MRS: Removed streaming files Mar 27 21:51:24 dac-pi volumio[1203]: info: MRS: volumioStreaming STOPPED Mar 27 21:51:24 dac-pi volumio[1203]: info: MRS: SNAPSERVER STOPPED Mar 27 21:51:24 dac-pi volumio[1203]: info: MRS: SNAPCLIENT STOPPED Mar 27 21:51:24 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:24 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:25 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:25 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:25 dac-pi sudo[2458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 27 21:51:25 dac-pi sudo[2458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:25 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:51:25 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:51:25 dac-pi systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Mar 27 21:51:25 dac-pi sudo[2458]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:25 dac-pi autossh[2461]: port set to 0, monitoring disabled Mar 27 21:51:25 dac-pi autossh[2461]: starting ssh (count 1) Mar 27 21:51:25 dac-pi autossh[2461]: ssh child pid is 2464 Mar 27 21:51:26 dac-pi volumio[1203]: info: Remote SSH Started Mar 27 21:51:26 dac-pi volumiossh-tunnel[2464]: Warning: Permanently added '[us1.myvolumio.org]:2222' (RSA) to the list of known hosts. Mar 27 21:51:27 dac-pi volumio[1203]: info: CoreCommandRouter::volumioGetState Mar 27 21:51:27 dac-pi volumio[1203]: info: CorePlayQueue::getTrack 0 Mar 27 21:51:27 dac-pi volumio[1203]: info: Asound.conf file written Mar 27 21:51:27 dac-pi sudo[2481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 27 21:51:27 dac-pi sudo[2481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:28 dac-pi sudo[2481]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:28 dac-pi volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 27 21:51:28 dac-pi volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 27 21:51:28 dac-pi volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 27 21:51:28 dac-pi volumio[1203]: info: Output device has changed, restarting MPD Mar 27 21:51:28 dac-pi volumio[1203]: info: Output device has changed, restarting Shairport Sync Mar 27 21:51:28 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:51:28 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 21:51:28 dac-pi sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 21:51:28 dac-pi sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:28 dac-pi sudo[2487]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:28 dac-pi sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 21:51:28 dac-pi sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:28 dac-pi volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 21:51:28 dac-pi volumio[1203]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 21:51:28 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:51:28 dac-pi systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Mar 27 21:51:28 dac-pi systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Mar 27 21:51:28 dac-pi systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:51:28 dac-pi systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 21:51:28 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 27 21:51:28 dac-pi volumio[1203]: ------------------------------------ BT MESSAGE: [FUNC] onStart Mar 27 21:51:28 dac-pi volumio[1203]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Mar 27 21:51:28 dac-pi systemd[1]: mpd.service: Deactivated successfully. Mar 27 21:51:28 dac-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 21:51:28 dac-pi systemd[1]: mpd.service: Consumed 4.124s CPU time. Mar 27 21:51:28 dac-pi systemd[1]: mpd.socket: Deactivated successfully. Mar 27 21:51:28 dac-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 21:51:28 dac-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 21:51:28 dac-pi dbus-daemon[750]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.22' (uid=1000 pid=1203 comm="/usr/bin/node /volumio/index.js") Mar 27 21:51:28 dac-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 21:51:28 dac-pi volumio[1203]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Mar 27 21:51:28 dac-pi volumio[1203]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Mar 27 21:51:28 dac-pi volumio[1203]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Mar 27 21:51:28 dac-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 21:51:28 dac-pi systemd[1]: Starting bluetooth.service - Bluetooth service... Mar 27 21:51:29 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 27 21:51:29 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 27 21:51:29 dac-pi volumio[1203]: info: MRS: Pushing multiroomSync output for this device Mar 27 21:51:29 dac-pi volumio[1203]: info: MRS: Pushing multiroomSync output Mar 27 21:51:29 dac-pi bluetoothd[2506]: Bluetooth daemon 5.83 Mar 27 21:51:29 dac-pi sudo[2504]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 21:51:29 dac-pi sudo[2504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 21:51:29 dac-pi dbus-daemon[750]: [system] Successfully activated service 'org.bluez' Mar 27 21:51:29 dac-pi systemd[1]: Started bluetooth.service - Bluetooth service. Mar 27 21:51:29 dac-pi sudo[2504]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:29 dac-pi bluetoothd[2506]: src/adapter.c:adapter_init() Failed to access management interface Mar 27 21:51:29 dac-pi bluetoothd[2506]: src/main.c:main() Adapter handling initialization failed Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding audio output: Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding audio output: Mar 27 21:51:29 dac-pi systemd[1]: bluetooth.service: Main process exited, code=exited, status=1/FAILURE Mar 27 21:51:29 dac-pi systemd[1]: bluetooth.service: Failed with result 'exit-code'. Mar 27 21:51:29 dac-pi volumio[1203]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 27 21:51:29 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 21:51:29 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding METAVOLUMIO REST API Endpoints Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 27 21:51:29 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 27 21:51:29 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 27 21:51:29 dac-pi volumio[1203]: info: Preparing CD Folders Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding CD REST API Endpoints Mar 27 21:51:29 dac-pi volumio[1203]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 27 21:51:29 dac-pi volumio[1203]: info: Starting UDEV Watcher for CD Mar 27 21:51:29 dac-pi volumio[1203]: info: Detecting CD presence with UDEV Mar 27 21:51:29 dac-pi volumio[1203]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 27 21:51:31 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:51:31.520-03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.73:45988 Mar 27 21:51:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:51:32.855-03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.73:45988 @ 0x18a0e70" latency=-119.246537ms timeout=10s Mar 27 21:51:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:51:32.855-03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.73:45988 @ 0x18a0e70" Mar 27 21:51:32 dac-pi volumio5-onboarding[1664]: time=2026-03-27T21:51:32.857-03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.73:45988 @ 0x18a0e70" latency=-118.441551ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 27 21:51:33 dac-pi mpd[2512]: 2026-03-27T21:51:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 21:51:33 dac-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 21:51:33 dac-pi sudo[2489]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:33 dac-pi systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Mar 27 21:51:35 dac-pi volumio[1203]: warn: [cd-plugin] cdspeedctl: device or media not ready Mar 27 21:51:35 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 27 21:51:35 dac-pi volumio[1203]: info: Adding inputs REST Endpoints Mar 27 21:51:35 dac-pi volumio[1203]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 27 21:51:35 dac-pi volumio[1203]: info: Scanning Audio Inputs Mar 27 21:51:35 dac-pi volumio[1203]: info: Checking against Known Cards name Mar 27 21:51:35 dac-pi volumio[1203]: info: Adding Server instance for streaming Mar 27 21:51:35 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 27 21:51:35 dac-pi volumio[1203]: error: Hi Res Audio Failed Login: Missing Login Data Mar 27 21:51:35 dac-pi volumio[1203]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 27 21:51:35 dac-pi volumio[1203]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 27 21:51:35 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 27 21:51:35 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 27 21:51:35 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 27 21:51:35 dac-pi volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Mar 27 21:51:35 dac-pi volumio[1203]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Mar 27 21:51:35 dac-pi sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 27 21:51:35 dac-pi volumio[1203]: info: QobuzConnect: Starting Qobuz Connect socket and service Mar 27 21:51:35 dac-pi sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:36 dac-pi sudo[2527]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:36 dac-pi volumio[1203]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Mar 27 21:51:36 dac-pi volumio[1203]: info: Adding TIDAL REST API Endpoints Mar 27 21:51:36 dac-pi volumio[1203]: info: Stopping AccessToken refresher cron for QOBUZ Mar 27 21:51:36 dac-pi sudo[2534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 27 21:51:36 dac-pi sudo[2534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:36 dac-pi sudo[2534]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:36 dac-pi volumio[1203]: info: AccessToken refresher cron started for QOBUZ Mar 27 21:51:36 dac-pi volumio[1203]: info: Adding QOBUZ REST API Endpoints Mar 27 21:51:36 dac-pi volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 27 21:51:36 dac-pi volumio[1203]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Mar 27 21:51:36 dac-pi volumio[1203]: info: MRS: Getting audio outputs on start Mar 27 21:51:36 dac-pi volumio[1203]: info: MRS: Requesting all other devices output Mar 27 21:51:36 dac-pi sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 27 21:51:36 dac-pi sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 21:51:36 dac-pi systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 27 21:51:36 dac-pi sudo[2537]: pam_unix(sudo:session): session closed for user root Mar 27 21:51:36 dac-pi volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:51:36 dac-pi volumio[1203]: DBusError: Message recipient disconnected from message bus without replying Mar 27 21:51:36 dac-pi volumio[1203]: at _methodReturnHandlers. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:343:27) Mar 27 21:51:36 dac-pi volumio[1203]: at handleMessage (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:101:11) Mar 27 21:51:36 dac-pi volumio[1203]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:151:9) Mar 27 21:51:36 dac-pi volumio[1203]: at EventEmitter.emit (node:events:514:28) Mar 27 21:51:36 dac-pi volumio[1203]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:132:14 Mar 27 21:51:36 dac-pi volumio[1203]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/message.js:65:9) Mar 27 21:51:36 dac-pi volumio[1203]: at Socket.emit (node:events:514:28) Mar 27 21:51:36 dac-pi volumio[1203]: at emitReadable_ (node:internal/streams/readable:609:12) Mar 27 21:51:36 dac-pi volumio[1203]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { Mar 27 21:51:36 dac-pi volumio[1203]: type: 'org.freedesktop.DBus.Error.NoReply', Mar 27 21:51:36 dac-pi volumio[1203]: text: 'Message recipient disconnected from message bus without replying', Mar 27 21:51:36 dac-pi volumio[1203]: reply: Message { Mar 27 21:51:36 dac-pi volumio[1203]: type: 3, Mar 27 21:51:36 dac-pi volumio[1203]: _sent: false, Mar 27 21:51:36 dac-pi volumio[1203]: _serial: 3, Mar 27 21:51:36 dac-pi volumio[1203]: path: undefined, Mar 27 21:51:36 dac-pi volumio[1203]: interface: undefined, Mar 27 21:51:36 dac-pi volumio[1203]: member: undefined, Mar 27 21:51:36 dac-pi volumio[1203]: errorName: 'org.freedesktop.DBus.Error.NoReply', Mar 27 21:51:36 dac-pi volumio[1203]: replySerial: 2, Mar 27 21:51:36 dac-pi volumio[1203]: destination: ':1.22', Mar 27 21:51:36 dac-pi volumio[1203]: sender: 'org.freedesktop.DBus', Mar 27 21:51:36 dac-pi volumio[1203]: signature: 's', Mar 27 21:51:36 dac-pi volumio[1203]: body: [ Mar 27 21:51:36 dac-pi volumio[1203]: 'Message recipient disconnected from message bus without replying' Mar 27 21:51:36 dac-pi volumio[1203]: ], Mar 27 21:51:36 dac-pi volumio[1203]: flags: 1 Mar 27 21:51:36 dac-pi volumio[1203]: } Mar 27 21:51:36 dac-pi volumio[1203]: } Mar 27 21:51:37 dac-pi volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:51:37 dac-pi upmpdcli[2540]: writing RSA key Mar 27 21:51:41 dac-pi sudo[2586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 21:50' Mar 27 21:51:41 dac-pi sudo[2586]: 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"