Feb 21 12:45:00 volumiogarage volumio[1340]: Starting albumart workers Feb 21 12:45:00 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 12:45:00 volumiogarage volumio[1147]: info: CoreCommandRouter::initPlayerControls Feb 21 12:45:00 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:00 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:00 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 12:45:01 volumiogarage volumio[1342]: Starting albumart workers Feb 21 12:45:01 volumiogarage volumio[1147]: Express server listening on port 3000 Feb 21 12:45:01 volumiogarage volumio[1147]: [Metrics] WebUI: 13s 572.44ms Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreStateMachine::resetVolumioState Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreStateMachine::getcurrentVolume Feb 21 12:45:01 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 12:45:01 volumiogarage volumio[1147]: info: Volumio Network Manager: Network status updated: 1 Feb 21 12:45:01 volumiogarage volumio[1147]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 21 12:45:01 volumiogarage volumio[1147]: wlan0 Interface doesn't support scanning : Network is down Feb 21 12:45:01 volumiogarage volumio[1147]: info: Cannot use regular scanning, forcing with ap-force Feb 21 12:45:01 volumiogarage sudo[1382]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 21 12:45:01 volumiogarage sudo[1382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:01 volumiogarage sudo[1382]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:01 volumiogarage volumio[1147]: command failed: Network is down (-100) Feb 21 12:45:01 volumiogarage volumio[1147]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 21 12:45:01 volumiogarage volumio[1147]: command failed: Network is down (-100) Feb 21 12:45:02 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 1 Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreStateMachine::pushState Feb 21 12:45:02 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreStateMachine::updateTrackBlock Feb 21 12:45:02 volumiogarage volumio[1147]: info: CorePlayQueue::getTrackBlock Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 12:45:02 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 2 Feb 21 12:45:02 volumiogarage sudo[1411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 12:45:02 volumiogarage sudo[1411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:02 volumiogarage sudo[1414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 12:45:02 volumiogarage sudo[1414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:02 volumiogarage sudo[1411]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:02 volumiogarage sudo[1414]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreStateMachine::pushState Feb 21 12:45:02 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:02 volumiogarage volumio[1147]: info: Reloading queue from file Feb 21 12:45:02 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:02 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:03 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 3 Feb 21 12:45:03 volumiogarage volumio[1147]: info: Setting Device type: Raspberry PI Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreStateMachine::setRepeat null single undefined Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreStateMachine::pushState Feb 21 12:45:03 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreStateMachine::setRandom null Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreStateMachine::pushState Feb 21 12:45:03 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 21 12:45:03 volumiogarage volumio[1147]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 21 12:45:03 volumiogarage volumio[1147]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a03111 Feb 21 12:45:03 volumiogarage volumio[1147]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 21 12:45:03 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 4 Feb 21 12:45:03 volumiogarage volumio[1147]: info: Completed loading Core Plugins Feb 21 12:45:03 volumiogarage volumio[1147]: info: Preparing to generate the ALSA configuration file Feb 21 12:45:03 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:03 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:03 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 21 12:45:03 volumiogarage volumio[1147]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 12:45:03 volumiogarage volumio[1147]: info: Reading ALSA contributions from plugins. Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: adding f0ae53ee-ecc4-4efe-b56d-7fd563e5bc7b Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: Found device VolumioHouse Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: Connecting to remote: 192.168.40.20 Feb 21 12:45:03 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: adding 40dff118-7dc2-424c-9ef6-cbe2c18c4d98 Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: Found device VolumioGarage Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:03 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:03 volumiogarage sudo[1440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 21 12:45:03 volumiogarage sudo[1440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:03 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: this is already registered, 40dff118-7dc2-424c-9ef6-cbe2c18c4d98 Feb 21 12:45:03 volumiogarage volumio[1147]: info: Discovery: Found device VolumioGarage Feb 21 12:45:03 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:03 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:04 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10:3000 from 192.168.40.20 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 21 12:45:04 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10:3000 from 192.168.40.20 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 21 12:45:04 volumiogarage volumio[1147]: info: Discovery: Connected to remote: 192.168.40.20 Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:04 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:04 volumiogarage volumio[1147]: info: Asound.conf file written Feb 21 12:45:04 volumiogarage sudo[1445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 21 12:45:04 volumiogarage sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:04 volumiogarage sudo[1445]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:04 volumiogarage volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 21 12:45:04 volumiogarage volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 21 12:45:04 volumiogarage volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 21 12:45:04 volumiogarage volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 21 12:45:04 volumiogarage volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Feb 21 12:45:04 volumiogarage volumio[1147]: info: Output device has changed, restarting MPD Feb 21 12:45:04 volumiogarage volumio[1147]: info: Output device has changed, restarting Shairport Sync Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:04 volumiogarage sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 12:45:04 volumiogarage sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:04 volumiogarage sudo[1453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 12:45:04 volumiogarage sudo[1453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:04 volumiogarage sudo[1451]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:04 volumiogarage volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 12:45:04 volumiogarage volumio[1147]: info: ___________ START PLUGINS ___________ Feb 21 12:45:04 volumiogarage volumio[1147]: info: ControllerMpd::onStart: Initializing MPD Feb 21 12:45:04 volumiogarage volumio[1147]: info: Creating MPD Configuration file Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 12:45:04 volumiogarage volumio[1147]: info: [1771695904901] CoreMusicLibrary::Adding element Media Servers Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 12:45:04 volumiogarage sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 21 12:45:04 volumiogarage sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:04 volumiogarage volumio[1147]: info: UPNP Browser: Client initialized successfully Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:04 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:04 volumiogarage sudo[1463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 12:45:04 volumiogarage sudo[1463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:04 volumiogarage sudo[1465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 12:45:04 volumiogarage sudo[1465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:05 volumiogarage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 12:45:05 volumiogarage systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 12:45:05 volumiogarage sudo[1463]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:05 volumiogarage systemd[1]: mpd.service: Deactivated successfully. Feb 21 12:45:05 volumiogarage systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 12:45:05 volumiogarage systemd[1]: mpd.socket: Deactivated successfully. Feb 21 12:45:05 volumiogarage systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 12:45:05 volumiogarage systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 12:45:05 volumiogarage volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:05 volumiogarage systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 12:45:05 volumiogarage systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 12:45:05 volumiogarage volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 12:45:05 volumiogarage volumio[1147]: info: [1771695905100] CoreMusicLibrary::Adding element Last_100 Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 12:45:05 volumiogarage volumio[1147]: info: [1771695905103] CoreMusicLibrary::Adding element Webradio Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 12:45:05 volumiogarage volumio[1147]: info: Initializing BBC Radios Feb 21 12:45:05 volumiogarage sudo[1476]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 12:45:05 volumiogarage sudo[1476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 12:45:05 volumiogarage sudo[1492]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 21 12:45:05 volumiogarage sudo[1476]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 21 12:45:05 volumiogarage volumio[1147]: info: [1771695905264] CoreMusicLibrary::Adding element Radio Paradise Feb 21 12:45:05 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 12:45:05 volumiogarage volumio[1147]: Cannot find translation for source Radio Paradise Feb 21 12:45:05 volumiogarage volumio[1147]: info: [MQTT] Starting MQTT Client plugin Feb 21 12:45:05 volumiogarage volumio[1147]: info: [MQTT] Device ID: volumiogarage Feb 21 12:45:05 volumiogarage volumio[1147]: info: [MQTT] Base topic: volumio Feb 21 12:45:05 volumiogarage volumio[1147]: info: [MQTT] MQTT client is disabled Feb 21 12:45:05 volumiogarage volumio[1147]: info: Volumio Calling Home Feb 21 12:45:05 volumiogarage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 12:45:05 volumiogarage sudo[1461]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:05 volumiogarage volumio5-onboarding[1491]: time=2026-02-21T12:45:05.931-05:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 21 12:45:07 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 10 Feb 21 12:45:07 volumiogarage volumio[1147]: info: MPD Permissions set Feb 21 12:45:07 volumiogarage volumio[1147]: info: MPD Permissions set Feb 21 12:45:07 volumiogarage volumio[1147]: 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: 11 Feb 21 12:45:08 volumiogarage volumio[1147]: 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: 11 Feb 21 12:45:08 volumiogarage volumio[1147]: info: Received Get System Info Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 12:45:08 volumiogarage volumio[1147]: info: Discovery: Getting this device information Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:08 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 12:45:08 volumiogarage volumio5-onboarding[1491]: time=2026-02-21T12:45:08.375-05:00 level=INFO msg="system info for ea8ccddf60a0f8e2dbc7f58a8a768abe" deviceName=VolumioGarage deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 21 12:45:08 volumiogarage volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 21 12:45:08 volumiogarage volumio[1147]: info: Volumio called home Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:08 volumiogarage volumio[1147]: info: Received Get System Info Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 12:45:08 volumiogarage volumio[1147]: info: Discovery: Getting this device information Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:08 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 12:45:09 volumiogarage volumio5-onboarding[1491]: time=2026-02-21T12:45:09.113-05:00 level=INFO msg="enabling local network discovery" Feb 21 12:45:09 volumiogarage volumio[1147]: info: Starting Shairport Sync Feb 21 12:45:09 volumiogarage volumio5-onboarding[1491]: time=2026-02-21T12:45:09.183-05:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 21 12:45:09 volumiogarage volumio[1147]: info: Starting Shairport Sync Feb 21 12:45:09 volumiogarage volumio[1147]: info: Starting Shairport Sync Feb 21 12:45:09 volumiogarage volumio-remote-updater[674]: [2026-02-21 12:45:09] [connect] Successful connection Feb 21 12:45:09 volumiogarage volumio[1147]: info: Received Get System Info Feb 21 12:45:09 volumiogarage sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 12:45:09 volumiogarage sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:09 volumiogarage sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 12:45:09 volumiogarage sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 12:45:09 volumiogarage volumio[1147]: info: Discovery: Getting this device information Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:09 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 12:45:09 volumiogarage sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 12:45:09 volumiogarage sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:09 volumiogarage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 12:45:09 volumiogarage systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 12:45:09 volumiogarage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 12:45:09 volumiogarage systemd[1]: shairport-sync.service: Consumed 1.682s CPU time. Feb 21 12:45:09 volumiogarage volumio-remote-updater[674]: [2026-02-21 12:45:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771695909 101 Feb 21 12:45:09 volumiogarage volumio[1147]: 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: 13 Feb 21 12:45:09 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:09 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:09 volumiogarage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 12:45:09 volumiogarage sudo[1528]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:09 volumiogarage systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 12:45:09 volumiogarage systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 12:45:09 volumiogarage systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 12:45:09 volumiogarage systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 12:45:09 volumiogarage sudo[1526]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:09 volumiogarage sudo[1530]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:09 volumiogarage volumio[1147]: info: Shairport-Sync Started Feb 21 12:45:09 volumiogarage mpd[1498]: 2026-02-21T12:45:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 12:45:09 volumiogarage volumio[1147]: Error adding Membership: Error: addMembership EINVAL Feb 21 12:45:09 volumiogarage volumio[1147]: info: Shairport-Sync Started Feb 21 12:45:09 volumiogarage volumio[1147]: info: Shairport-Sync Started Feb 21 12:45:09 volumiogarage systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 21 12:45:10 volumiogarage systemd[1]: Started mpd.service - Music Player Daemon. Feb 21 12:45:10 volumiogarage sudo[1453]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:10 volumiogarage sudo[1465]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:10 volumiogarage volumio[1147]: info: Completed starting Core Plugins Feb 21 12:45:10 volumiogarage volumio[1147]: info: ------------------------------------------- Feb 21 12:45:10 volumiogarage volumio[1147]: info: ----- MyVolumio plugins startup ---- Feb 21 12:45:10 volumiogarage volumio[1147]: info: ------------------------------------------- Feb 21 12:45:10 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 21 12:45:10 volumiogarage volumio[1147]: error: MPD error: The expression evaluated to a falsy value: Feb 21 12:45:10 volumiogarage volumio[1147]: assert.ok(self.idling) Feb 21 12:45:10 volumiogarage volumio[1147]: error: The expression evaluated to a falsy value: Feb 21 12:45:10 volumiogarage volumio[1147]: assert.ok(self.idling) Feb 21 12:45:10 volumiogarage volumio[1147]: info: MPD running with PID1498 Feb 21 12:45:10 volumiogarage volumio[1147]: ,establishing connection Feb 21 12:45:10 volumiogarage volumio[1147]: error: MPD error: The expression evaluated to a falsy value: Feb 21 12:45:10 volumiogarage volumio[1147]: assert.ok(self.idling) Feb 21 12:45:10 volumiogarage volumio[1147]: error: The expression evaluated to a falsy value: Feb 21 12:45:10 volumiogarage volumio[1147]: assert.ok(self.idling) Feb 21 12:45:10 volumiogarage volumio[1147]: error: updateQueue error: null Feb 21 12:45:10 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10:3000 from 192.168.40.20 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 21 12:45:10 volumiogarage volumio[1147]: verbose: New Socket.io Connection to 192.168.40.10 from 192.168.10.199 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 15 Feb 21 12:45:11 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:11 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:11 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 12:45:11 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 12:45:11 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 12:45:11 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:11 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetQueue Feb 21 12:45:11 volumiogarage volumio[1147]: info: CoreStateMachine::getQueue Feb 21 12:45:11 volumiogarage volumio[1147]: info: CorePlayQueue::getQueue Feb 21 12:45:12 volumiogarage systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 21 12:45:12 volumiogarage systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 12:45:12 volumiogarage systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 21 12:45:12 volumiogarage sudo[1440]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:12 volumiogarage volumio[1147]: info: Upmpdcli Daemon Started Feb 21 12:45:12 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:12 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:12 volumiogarage systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 21 12:45:15 volumiogarage volumio[1147]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 21 12:45:24 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 21 12:45:24 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 21 12:45:26 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:26 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin bluetooth to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin multiroom to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin metavolumio to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin cd_controller to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 21 12:45:27 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 21 12:45:29 volumiogarage systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 21 12:45:31 volumiogarage systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 21 12:45:31 volumiogarage systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 21 12:45:34 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 21 12:45:34 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 21 12:45:34 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:34 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:34 volumiogarage volumio[1147]: info: Starting MyVolumio Remote Streaming Endpoints Feb 21 12:45:35 volumiogarage volumio[1147]: info: MyVolumio login type: Token Feb 21 12:45:35 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 21 12:45:35 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 21 12:45:35 volumiogarage upmpdcli[1645]: writing RSA key Feb 21 12:45:38 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 21 12:45:38 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 21 12:45:38 volumiogarage volumio[1147]: info: Streaming services startup Feb 21 12:45:38 volumiogarage volumio[1147]: info: Starting Streaming Daemon Feb 21 12:45:38 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 21 12:45:38 volumiogarage sudo[1650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 21 12:45:38 volumiogarage sudo[1650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:45:39 volumiogarage sudo[1650]: pam_unix(sudo:session): session closed for user root Feb 21 12:45:39 volumiogarage volumio5-onboarding[1491]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Feb 21 12:45:39 volumiogarage systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 21 12:45:39 volumiogarage systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 21 12:45:39 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 12:45:39 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:39 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 12:45:39 volumiogarage systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Feb 21 12:45:39 volumiogarage systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 12:45:39 volumiogarage systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 21 12:45:39 volumiogarage volumio5-onboarding[1659]: time=2026-02-21T12:45:39.404-05:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 21 12:45:40 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:40 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin bluetooth to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin multiroom to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin metavolumio to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin cd_controller to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 21 12:45:40 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 21 12:45:40 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:40 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:40 volumiogarage volumio[1147]: info: Starting MyVolumio Remote Streaming Endpoints Feb 21 12:45:40 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 21 12:45:40 volumiogarage volumio[1147]: error: Cannot start Volumio Streaming Daemon Feb 21 12:45:40 volumiogarage volumio[1147]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 21 12:45:40 volumiogarage volumio[1147]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 21 12:45:40 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:45:40 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:45:41 volumiogarage volumio[1147]: 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: 15 Feb 21 12:45:43 volumiogarage volumio[1147]: 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: 15 Feb 21 12:45:43 volumiogarage volumio[1147]: info: Received Get System Info Feb 21 12:45:43 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 12:45:43 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 12:45:43 volumiogarage volumio[1147]: info: Discovery: Getting this device information Feb 21 12:45:43 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:43 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:43 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 12:45:43 volumiogarage volumio5-onboarding[1659]: time=2026-02-21T12:45:43.803-05:00 level=INFO msg="system info for ea8ccddf60a0f8e2dbc7f58a8a768abe" deviceName=VolumioGarage deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 21 12:45:43 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:45:44 volumiogarage volumio[1147]: info: Received Get System Info Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 12:45:44 volumiogarage volumio[1147]: info: Discovery: Getting this device information Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 12:45:44 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::ClearQueue Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::stop Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::clearPlayQueue Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::saveQueue Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushQueue Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::addQueueItems Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::addQueueItems Feb 21 12:45:44 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:45:44 volumiogarage volumio[1147]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99575334 Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushQueue Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::saveQueue Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::updateTrackBlock Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::getTrackBlock Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPlay Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::play index 0 Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::stop Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::play index undefined Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:44 volumiogarage volumio[1147]: info: CoreStateMachine::startPlaybackTimer Feb 21 12:45:44 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:44 volumiogarage volumio[1147]: info: [1771695944930] ControllerWebradio::clearAddPlayTrack Feb 21 12:45:44 volumiogarage volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 12:45:45 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:45 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:45 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 12:45:45 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 12:45:45 volumiogarage volumio5-onboarding[1659]: time=2026-02-21T12:45:45.583-05:00 level=INFO msg="enabling local network discovery" Feb 21 12:45:45 volumiogarage volumio5-onboarding[1659]: time=2026-02-21T12:45:45.618-05:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 21 12:45:46 volumiogarage volumio[1147]: info: Received Get System Info Feb 21 12:45:46 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 12:45:46 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 12:45:46 volumiogarage volumio[1147]: info: Discovery: Getting this device information Feb 21 12:45:46 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:46 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:46 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 12:45:46 volumiogarage volumio-remote-updater[674]: Test mode disabled Feb 21 12:45:46 volumiogarage volumio-remote-updater[674]: Alpha mode disabled Feb 21 12:45:46 volumiogarage volumio-remote-updater[674]: Alpha legacy test mode disabled Feb 21 12:45:46 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 21 12:45:47 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:47 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:45:47 volumiogarage volumio-remote-updater[674]: Test mode disabled Feb 21 12:45:47 volumiogarage volumio-remote-updater[674]: Alpha mode disabled Feb 21 12:45:47 volumiogarage volumio-remote-updater[674]: Alpha legacy test mode disabled Feb 21 12:45:48 volumiogarage volumio[1147]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 21 12:45:48 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 21 12:45:48 volumiogarage volumio[1147]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 21 12:45:48 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 21 12:45:49 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 12:45:50 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 12:45:50 volumiogarage volumio[1147]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 21 12:45:50 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 12:45:51 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 12:45:52 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 21 12:45:53 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Feb 21 12:45:53 volumiogarage volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Feb 21 12:45:54 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetBrowseSources Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 12:45:55 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 12:45:56 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:45:56 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:56 volumiogarage volumio[1147]: info: MyVolumio token set successfully Feb 21 12:45:56 volumiogarage volumio[1147]: info: MYVOLUMIO: Adding device Feb 21 12:45:56 volumiogarage volumio[1147]: info: MYVOLUMIO: Evaluating Server Feb 21 12:45:58 volumiogarage volumio5-onboarding[1659]: time=2026-02-21T12:45:58.361-05:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=Me1gLyYSh6aIc7VjqvXzcYbp9z53 tokenExpiry=2026-02-21T13:45:58.361-05:00 Feb 21 12:45:59 volumiogarage volumio[1147]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 21 12:45:59 volumiogarage volumio[1147]: info: Completed starting MyVolumio Plugin Feb 21 12:45:59 volumiogarage volumio[1147]: [Metrics] CommandRouter: 71s 410.70ms Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::volumiosetStartupVolume Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:45:59 volumiogarage volumio[1147]: info: VolumeController:: Setting startup Volume 15 Feb 21 12:45:59 volumiogarage volumio[1147]: info: VolumeController::SetAlsaVolume15 Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::Close All Modals sent Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::Close All Modals sent Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreStateMachine::pushState Feb 21 12:45:59 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 12:45:59 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 21 12:46:00 volumiogarage volumio[1147]: info: MyVolumio Plan changed: premium Feb 21 12:46:00 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 21 12:46:00 volumiogarage volumio[1147]: info: Removing browser output: myVolumio user plan is not superstar Feb 21 12:46:01 volumiogarage volumio[1147]: info: Removing audio output: Feb 21 12:46:01 volumiogarage volumio[1147]: info: MYVOLUMIO: Adding device Feb 21 12:46:01 volumiogarage volumio[1147]: info: MYVOLUMIO: Evaluating Server Feb 21 12:46:01 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::ClearQueue Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::stop Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::clearPlayQueue Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::saveQueue Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushQueue Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::addQueueItems Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::addQueueItems Feb 21 12:46:01 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:46:01 volumiogarage volumio[1147]: info: Adding Item to queue: webrp/8 Feb 21 12:46:01 volumiogarage volumio[1147]: info: Exploding uri webrp/8 in service radio_paradise Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPushQueue Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::saveQueue Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::updateTrackBlock Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::getTrackBlock Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioPlay Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::play index 0 Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::stop Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::play index undefined Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreStateMachine::startPlaybackTimer Feb 21 12:46:01 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:46:01 volumiogarage volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 12:46:01 volumiogarage volumio[1147]: info: Setting Geolocation for MyVolumio to us3 Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetBrowseSources Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 12:46:01 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 21 12:46:03 volumiogarage volumio[1147]: info: Updating MyVolumio device info Feb 21 12:46:03 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:03 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:03 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:04 volumiogarage volumio[1147]: info: Remote config written successfully Feb 21 12:46:04 volumiogarage volumio[1147]: info: Starting Tunnel 1 Feb 21 12:46:04 volumiogarage volumio[1147]: info: Starting Tunnel Connection Checker Feb 21 12:46:04 volumiogarage volumio[1147]: info: Successfully Added MyVolumio device Feb 21 12:46:05 volumiogarage volumio[1147]: info: MYVolumio Device enabled Feb 21 12:46:05 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 21 12:46:05 volumiogarage volumio[1147]: info: MyVolumio status changed Feb 21 12:46:05 volumiogarage volumio[1147]: info: Streaming services startup Feb 21 12:46:05 volumiogarage volumio[1147]: info: Starting Streaming Daemon Feb 21 12:46:05 volumiogarage sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 21 12:46:05 volumiogarage sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:46:05 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:46:05 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:46:05 volumiogarage sudo[1759]: pam_unix(sudo:session): session closed for user root Feb 21 12:46:07 volumiogarage volumio[1147]: info: Successfully Updated MyVolumio device Feb 21 12:46:07 volumiogarage volumio[1147]: info: Setting Geolocation for MyVolumio to us3 Feb 21 12:46:07 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:07 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:07 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:07 volumiogarage volumio[1147]: info: BOOT COMPLETED Feb 21 12:46:08 volumiogarage volumio[1147]: error: Cannot start Volumio Streaming Daemon Feb 21 12:46:08 volumiogarage volumio[1147]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 21 12:46:08 volumiogarage volumio[1147]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 21 12:46:08 volumiogarage volumio[1147]: info: Updating MyVolumio device info Feb 21 12:46:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:08 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 12:46:09 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 21 12:46:09 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 21 12:46:10 volumiogarage volumio[1147]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 21 12:46:10 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 21 12:46:14 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 21 12:46:14 volumiogarage sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 21 12:46:14 volumiogarage sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:46:14 volumiogarage sudo[1782]: pam_unix(sudo:session): session closed for user root Feb 21 12:46:14 volumiogarage volumio[1147]: info: MRS: MultiRoom plugin initialized Feb 21 12:46:14 volumiogarage volumio[1147]: info: MRS: STOPPING SNAPCLIENT Feb 21 12:46:14 volumiogarage volumio[1147]: info: MRS: Snap server stop Feb 21 12:46:14 volumiogarage volumio[1147]: info: MRS: STOPPING volumioStreaming Feb 21 12:46:14 volumiogarage sudo[1799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 21 12:46:14 volumiogarage sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:46:14 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 21 12:46:14 volumiogarage sudo[1801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 21 12:46:14 volumiogarage sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:46:14 volumiogarage sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 21 12:46:14 volumiogarage sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:46:15 volumiogarage sudo[1806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 21 12:46:15 volumiogarage sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 12:46:15 volumiogarage sudo[1806]: pam_unix(sudo:session): session closed for user root Feb 21 12:46:15 volumiogarage sudo[1801]: pam_unix(sudo:session): session closed for user root Feb 21 12:46:15 volumiogarage sudo[1805]: pam_unix(sudo:session): session closed for user root Feb 21 12:46:15 volumiogarage sudo[1799]: pam_unix(sudo:session): session closed for user root Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 21 12:46:15 volumiogarage volumio[1147]: info: Preparing to generate the ALSA configuration file Feb 21 12:46:15 volumiogarage volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 21 12:46:15 volumiogarage volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 21 12:46:15 volumiogarage volumio[1147]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 12:46:15 volumiogarage volumio[1147]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 12:46:15 volumiogarage volumio[1147]: info: Reading ALSA contributions from plugins. Feb 21 12:46:15 volumiogarage volumio[1147]: info: Successfully Added MyVolumio device Feb 21 12:46:15 volumiogarage volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 21 12:46:16 volumiogarage volumio[1147]: info: Preload queue cleared Feb 21 12:46:29 volumiogarage volumio[1147]: info: MRS: Removed streaming files Feb 21 12:46:29 volumiogarage volumio[1147]: info: MRS: volumioStreaming STOPPED Feb 21 12:46:29 volumiogarage volumio[1147]: info: MRS: SNAPSERVER STOPPED Feb 21 12:46:29 volumiogarage volumio[1147]: info: MRS: SNAPCLIENT STOPPED Feb 21 12:46:40 volumiogarage dbus-daemon[644]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30007ms) Feb 21 12:46:43 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:46:43 volumiogarage volumio[1147]: info: Listing playlists Feb 21 12:46:43 volumiogarage volumio[1147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 12:46:43 volumiogarage volumio[1147]: Error: write EPIPE Feb 21 12:46:43 volumiogarage volumio[1147]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Feb 21 12:46:43 volumiogarage volumio[1147]: at writeGeneric (node:internal/stream_base_commons:151:3) Feb 21 12:46:43 volumiogarage volumio[1147]: at Socket._writeGeneric (node:net:952:11) Feb 21 12:46:43 volumiogarage volumio[1147]: at Socket._write (node:net:964:8) Feb 21 12:46:43 volumiogarage volumio[1147]: at writeOrBuffer (node:internal/streams/writable:399:12) Feb 21 12:46:43 volumiogarage volumio[1147]: at _write (node:internal/streams/writable:340:10) Feb 21 12:46:43 volumiogarage volumio[1147]: at Writable.write (node:internal/streams/writable:344:10) Feb 21 12:46:43 volumiogarage volumio[1147]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:89:18 Feb 21 12:46:43 volumiogarage volumio[1147]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/readline.js:12:11) Feb 21 12:46:43 volumiogarage volumio[1147]: at Socket.emit (node:events:514:28) Feb 21 12:46:43 volumiogarage volumio[1147]: at emitReadable_ (node:internal/streams/readable:609:12) Feb 21 12:46:43 volumiogarage volumio[1147]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { Feb 21 12:46:43 volumiogarage volumio[1147]: errno: -32, Feb 21 12:46:43 volumiogarage volumio[1147]: code: 'EPIPE', Feb 21 12:46:43 volumiogarage volumio[1147]: syscall: 'write' Feb 21 12:46:43 volumiogarage volumio[1147]: } Feb 21 12:46:43 volumiogarage volumio[1147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 12:46:47 volumiogarage sudo[1868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 12:45' Feb 21 12:46:47 volumiogarage sudo[1868]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"