May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Loading plugin "network"... May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Refreshing Cached IP Addresses May 31 10:18:00 volumiopi3b-ss sudo[4087]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 10:18:00 volumiopi3b-ss sudo[4087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:00 volumiopi3b-ss sudo[4087]: pam_unix(sudo:session): session closed for user root May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Loading plugin "services"... May 31 10:18:00 volumiopi3b-ss sudo[4089]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 10:18:00 volumiopi3b-ss sudo[4089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Loading plugin "volumio5onboarding"... May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Loading plugin "alsa_controller"... May 31 10:18:00 volumiopi3b-ss sudo[4089]: pam_unix(sudo:session): session closed for user root May 31 10:18:00 volumiopi3b-ss sudo[4097]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 10:18:00 volumiopi3b-ss sudo[4097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:00 volumiopi3b-ss sudo[4097]: pam_unix(sudo:session): session closed for user root May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Loading plugin "wizard"... May 31 10:18:00 volumiopi3b-ss volumio[4059]: info: Loading plugin "networkfs"... May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Starting Udev Watcher for removable devices May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Ignoring mount for partition: boot May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Ignoring mount for partition: volumio May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Ignoring mount for partition: volumio_data May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Loading plugin "volumio_command_line_client"... May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Loading plugin "upnp"... May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: [1780237081115] Starting Upmpd Daemon May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Loading plugin "my_music"... May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Loading plugin "mpd"... May 31 10:18:01 volumiopi3b-ss volumio[4059]: info: Loading plugin "upnp_browser"... May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Starting UPNP Browser May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Loading plugin "alarm-clock"... May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Loading plugin "airplay_emulation"... May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Starting Shairport Sync May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Loading plugin "last_100"... May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Loading plugin "webradio"... May 31 10:18:04 volumiopi3b-ss volumio[4059]: info: Loading plugin "i2s_dacs"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "volumiodiscovery"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 10:18:05 volumiopi3b-ss volumio[4059]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 10:18:05 volumiopi3b-ss volumio[4059]: *** WARNING *** For more information see May 31 10:18:05 volumiopi3b-ss volumio[4059]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 10:18:05 volumiopi3b-ss volumio[4059]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 10:18:05 volumiopi3b-ss volumio[4059]: *** WARNING *** For more information see May 31 10:18:05 volumiopi3b-ss node[4059]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 10:18:05 volumiopi3b-ss node[4059]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 10:18:05 volumiopi3b-ss node[4059]: *** WARNING *** For more information see May 31 10:18:05 volumiopi3b-ss node[4059]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 10:18:05 volumiopi3b-ss node[4059]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 10:18:05 volumiopi3b-ss node[4059]: *** WARNING *** For more information see May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Discovery: Started advertising with name: VolumioPi3b SS May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "outputs"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "albumart"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Plugin example_plugin is not enabled May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "inputs"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "updater_comm"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Plugin mpdemulation is not enabled May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "rest_api"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading plugin "websocket"... May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Starting Socket.io Server version 1.7.4 May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: Loading i18n strings for locale en May 31 10:18:05 volumiopi3b-ss volumio[4059]: Updating browse sources language May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::initPlayerControls May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:05 volumiopi3b-ss volumio[4059]: Express server listening on port 3000 May 31 10:18:05 volumiopi3b-ss volumio[4059]: [Metrics] WebUI: 9s 862.83ms May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::resetVolumioState May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::getcurrentVolume May 31 10:18:05 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioRetrievevolume May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Volumio Network Manager: Network status updated: 1 May 31 10:18:06 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:06.095-04:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:45512->127.0.0.1:3000: i/o timeout" May 31 10:18:06 volumiopi3b-ss volumio[4059]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 31 10:18:06 volumiopi3b-ss volumio[4059]: wlan0 Interface doesn't support scanning. May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Cannot use regular scanning, forcing with ap-force May 31 10:18:06 volumiopi3b-ss sudo[4143]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 31 10:18:06 volumiopi3b-ss sudo[4143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:06 volumiopi3b-ss sudo[4143]: pam_unix(sudo:session): session closed for user root May 31 10:18:06 volumiopi3b-ss volumio[4059]: command failed: No such device (-19) May 31 10:18:06 volumiopi3b-ss volumio[4059]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 31 10:18:06 volumiopi3b-ss volumio[4059]: command failed: No such device (-19) May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Cannot mount NAS USB at system boot, trial number 2 ,retrying in 5 seconds May 31 10:18:06 volumiopi3b-ss volumio[4126]: Forking 3 albumart workers May 31 10:18:06 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11 from 10.0.0.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 1 May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Cannot read play queue from file May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: VolumeController:: Volume=84 Mute =false May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::updateTrackBlock May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrackBlock May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioRetrievevolume May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::setRepeat null single undefined May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::setRandom null May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Setting Device type: Raspberry PI May 31 10:18:06 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11 from 10.0.0.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 2 May 31 10:18:06 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Completed loading Core Plugins May 31 10:18:06 volumiopi3b-ss volumio[4059]: info: Preparing to generate the ALSA configuration file May 31 10:18:07 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: adding 1521a0d1-ee4d-458c-907b-00a498549065 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: Found device Volumio May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: Connecting to remote: 10.0.0.202 May 31 10:18:07 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11 from 10.0.0.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetVisibleSources May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetQueue May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::getQueue May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getQueue May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Listing playlists May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Received Get System Info May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: Getting this device information May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: adding 0b70f5e2-c8be-4f1e-a75b-e5891ab585e9 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: Found device Volumio salon May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Discovery: Connecting to remote: 10.0.0.165 May 31 10:18:07 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: VolumeController:: Volume=84 Mute =false May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Asound.conf file unchanged, so no further update is needed May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Output device has changed, restarting MPD May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Output device has changed, restarting Shairport Sync May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:07 volumiopi3b-ss sudo[4190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 10:18:07 volumiopi3b-ss sudo[4190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: ___________ START PLUGINS ___________ May 31 10:18:07 volumiopi3b-ss sudo[4190]: pam_unix(sudo:session): session closed for user root May 31 10:18:07 volumiopi3b-ss sudo[4192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 10:18:07 volumiopi3b-ss sudo[4192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: ControllerMpd::onStart: Initializing MPD May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: Creating MPD Configuration file May 31 10:18:07 volumiopi3b-ss systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:07 volumiopi3b-ss upmpdcli[1337]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: [1780237087976] CoreMusicLibrary::Adding element Media Servers May 31 10:18:07 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 10:18:08 volumiopi3b-ss sudo[4202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 10:18:08 volumiopi3b-ss sudo[4200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 31 10:18:08 volumiopi3b-ss sudo[4200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: UPNP Browser: Client initialized successfully May 31 10:18:08 volumiopi3b-ss sudo[4202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:08 volumiopi3b-ss sudo[4202]: pam_unix(sudo:session): session closed for user root May 31 10:18:08 volumiopi3b-ss systemd[1]: mpd.service: Deactivated successfully. May 31 10:18:08 volumiopi3b-ss systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 10:18:08 volumiopi3b-ss systemd[1]: mpd.service: Consumed 7.324s CPU time. May 31 10:18:08 volumiopi3b-ss systemd[1]: mpd.socket: Deactivated successfully. May 31 10:18:08 volumiopi3b-ss systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 10:18:08 volumiopi3b-ss systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 10:18:08 volumiopi3b-ss sudo[4204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 10:18:08 volumiopi3b-ss sudo[4204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:08 volumiopi3b-ss systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:08 volumiopi3b-ss systemd[1]: Starting mpd.service - Music Player Daemon... May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:08 volumiopi3b-ss systemd[1]: mpd.service: Deactivated successfully. May 31 10:18:08 volumiopi3b-ss systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 10:18:08 volumiopi3b-ss systemd[1]: mpd.socket: Deactivated successfully. May 31 10:18:08 volumiopi3b-ss sudo[4200]: pam_unix(sudo:session): session closed for user root May 31 10:18:08 volumiopi3b-ss systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 10:18:08 volumiopi3b-ss systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: [1780237088377] CoreMusicLibrary::Adding element Last_100 May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: [1780237088387] CoreMusicLibrary::Adding element Webradio May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 10:18:08 volumiopi3b-ss systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 10:18:08 volumiopi3b-ss systemd[1]: Starting mpd.service - Music Player Daemon... May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: Initializing BBC Radios May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:08 volumiopi3b-ss volumio[4059]: info: Volumio Calling Home May 31 10:18:08 volumiopi3b-ss sudo[4219]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 10:18:08 volumiopi3b-ss sudo[4219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 10:18:09 volumiopi3b-ss sudo[4219]: pam_unix(sudo:session): session closed for user root May 31 10:18:10 volumiopi3b-ss volumio[4148]: Starting albumart workers May 31 10:18:10 volumiopi3b-ss volumio[4147]: Starting albumart workers May 31 10:18:10 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: Discovery: Connected to remote: 10.0.0.202 May 31 10:18:10 volumiopi3b-ss volumio[4149]: Starting albumart workers May 31 10:18:10 volumiopi3b-ss volumio-remote-updater[639]: [2026-05-31 10:18:10] [connect] Successful connection May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: Discovery: adding a4cda221-48cc-4cf2-9500-a0c9657af411 May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: Discovery: Found device VolumioPi3b SS May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:10 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: MPD Permissions set May 31 10:18:10 volumiopi3b-ss volumio[4059]: info: MPD Permissions set May 31 10:18:10 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: Volumio called home May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: Discovery: this is already registered, a4cda221-48cc-4cf2-9500-a0c9657af411 May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: Discovery: Found device VolumioPi3b SS May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:11 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: Discovery: Connected to remote: 10.0.0.165 May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss sudo[4256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 10:18:11 volumiopi3b-ss sudo[4256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:11 volumiopi3b-ss sudo[4257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss sudo[4257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:11 volumiopi3b-ss sudo[4256]: pam_unix(sudo:session): session closed for user root May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:11 volumiopi3b-ss sudo[4257]: pam_unix(sudo:session): session closed for user root May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:12 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 31 10:18:12 volumiopi3b-ss volumio[4059]: info: Starting Shairport Sync May 31 10:18:12 volumiopi3b-ss volumio[4059]: info: Starting Shairport Sync May 31 10:18:12 volumiopi3b-ss volumio[4059]: info: Starting Shairport Sync May 31 10:18:12 volumiopi3b-ss sudo[4292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 10:18:12 volumiopi3b-ss sudo[4292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:12 volumiopi3b-ss sudo[4294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 10:18:12 volumiopi3b-ss sudo[4294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:12 volumiopi3b-ss volumio[4059]: 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: 12 May 31 10:18:12 volumiopi3b-ss sudo[4296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 10:18:12 volumiopi3b-ss sudo[4296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:12 volumiopi3b-ss volumio-remote-updater[639]: [2026-05-31 10:18:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1780237090 101 May 31 10:18:12 volumiopi3b-ss volumio[4059]: 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: 12 May 31 10:18:12 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 31 10:18:13 volumiopi3b-ss systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 10:18:13 volumiopi3b-ss systemd[1]: shairport-sync.service: Deactivated successfully. May 31 10:18:13 volumiopi3b-ss systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 10:18:13 volumiopi3b-ss systemd[1]: shairport-sync.service: Consumed 2.365s CPU time. May 31 10:18:13 volumiopi3b-ss sudo[4301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 10:18:13 volumiopi3b-ss sudo[4301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:13 volumiopi3b-ss systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 10:18:13 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to 10.0.0.11:3000 from 10.0.0.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 May 31 10:18:13 volumiopi3b-ss sudo[4292]: pam_unix(sudo:session): session closed for user root May 31 10:18:13 volumiopi3b-ss sudo[4296]: pam_unix(sudo:session): session closed for user root May 31 10:18:13 volumiopi3b-ss sudo[4294]: pam_unix(sudo:session): session closed for user root May 31 10:18:13 volumiopi3b-ss volumio[4059]: 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 May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: Shairport-Sync Started May 31 10:18:13 volumiopi3b-ss volumio[4059]: Error adding Membership: Error: addMembership EINVAL May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: Shairport-Sync Started May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: Shairport-Sync Started May 31 10:18:13 volumiopi3b-ss sudo[4301]: pam_unix(sudo:session): session closed for user root May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: Upmpdcli Daemon Started May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:13 volumiopi3b-ss volumio[4059]: 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 May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:13 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:13 volumiopi3b-ss volumio[4059]: 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 May 31 10:18:13 volumiopi3b-ss volumio[4059]: 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 May 31 10:18:13 volumiopi3b-ss volumio[4059]: 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 May 31 10:18:13 volumiopi3b-ss volumio[4059]: 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 May 31 10:18:13 volumiopi3b-ss upmpdcli[1337]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout May 31 10:18:13 volumiopi3b-ss upmpdcli[1337]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed May 31 10:18:13 volumiopi3b-ss upmpdcli[1337]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 May 31 10:18:15 volumiopi3b-ss mpd[4237]: 2026-05-31T10:18:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 10:18:15 volumiopi3b-ss systemd[1]: Started mpd.service - Music Player Daemon. May 31 10:18:15 volumiopi3b-ss sudo[4204]: pam_unix(sudo:session): session closed for user root May 31 10:18:15 volumiopi3b-ss sudo[4192]: pam_unix(sudo:session): session closed for user root May 31 10:18:15 volumiopi3b-ss volumio[4059]: info: Completed starting Core Plugins May 31 10:18:15 volumiopi3b-ss volumio[4059]: info: ------------------------------------------- May 31 10:18:15 volumiopi3b-ss volumio[4059]: info: ----- MyVolumio plugins startup ---- May 31 10:18:15 volumiopi3b-ss volumio[4059]: info: ------------------------------------------- May 31 10:18:15 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Fetching plans data.... May 31 10:18:15 volumiopi3b-ss upmpdcli[1337]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started May 31 10:18:16 volumiopi3b-ss volumio[4059]: error: MPD error: The expression evaluated to a falsy value: May 31 10:18:16 volumiopi3b-ss volumio[4059]: assert.ok(self.idling) May 31 10:18:16 volumiopi3b-ss volumio[4059]: error: The expression evaluated to a falsy value: May 31 10:18:16 volumiopi3b-ss volumio[4059]: assert.ok(self.idling) May 31 10:18:16 volumiopi3b-ss volumio[4059]: error: updateQueue error: null May 31 10:18:16 volumiopi3b-ss volumio[4059]: info: MPD running with PID4237 May 31 10:18:16 volumiopi3b-ss volumio[4059]: ,establishing connection May 31 10:18:16 volumiopi3b-ss volumio[4059]: error: updateQueue error: null May 31 10:18:16 volumiopi3b-ss volumio[4059]: info: Cannot mount NAS USB at system boot, trial number 4 ,retrying in 5 seconds May 31 10:18:21 volumiopi3b-ss volumio[4059]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 31 10:18:21 volumiopi3b-ss volumio[4059]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin bluetooth to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin multiroom to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin metavolumio to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin cd_controller to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin qobuzconnect to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin smart_inputs to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: Adding plugin tidalconnect to MyMusic Plugins May 31 10:18:24 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: Starting MyVolumio Remote Streaming Endpoints May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: MyVolumio login type: Token May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 31 10:18:26 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 31 10:18:27 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 31 10:18:27 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 31 10:18:27 volumiopi3b-ss volumio[4059]: info: Streaming services startup May 31 10:18:27 volumiopi3b-ss volumio[4059]: info: Starting Streaming Daemon May 31 10:18:27 volumiopi3b-ss sudo[4341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 10:18:27 volumiopi3b-ss volumio[4059]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 31 10:18:27 volumiopi3b-ss sudo[4341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:27 volumiopi3b-ss sudo[4341]: pam_unix(sudo:session): session closed for user root May 31 10:18:28 volumiopi3b-ss volumio[4059]: error: Cannot start Volumio Streaming Daemon May 31 10:18:28 volumiopi3b-ss volumio[4059]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 10:18:28 volumiopi3b-ss volumio[4059]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 10:18:28 volumiopi3b-ss volumio[4059]: error: MyVolumio Custom Token format not valid, refreshing it May 31 10:18:29 volumiopi3b-ss volumio[4059]: info: MyVolumio login type: Token May 31 10:18:29 volumiopi3b-ss volumio[4059]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 10:18:29 volumiopi3b-ss volumio[4059]: info: MyVolumio token set successfully May 31 10:18:29 volumiopi3b-ss volumio[4059]: info: MYVOLUMIO: Adding device May 31 10:18:29 volumiopi3b-ss volumio[4059]: info: MYVOLUMIO: Evaluating Server May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: MyVolumio status changed May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Streaming services startup May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Starting Streaming Daemon May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Removing browser output: myVolumio user plan is not superstar May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Removing audio output: May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Stoppping Tunnel 1 May 31 10:18:30 volumiopi3b-ss sudo[4352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 10:18:30 volumiopi3b-ss sudo[4352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:30 volumiopi3b-ss sudo[4354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 31 10:18:30 volumiopi3b-ss sudo[4354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss sudo[4352]: pam_unix(sudo:session): session closed for user root May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss 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. May 31 10:18:30 volumiopi3b-ss sudo[4354]: pam_unix(sudo:session): session closed for user root May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Remote SSH Stopped May 31 10:18:30 volumiopi3b-ss volumio[4059]: error: Cannot start Volumio Streaming Daemon May 31 10:18:30 volumiopi3b-ss volumio[4059]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 10:18:30 volumiopi3b-ss volumio[4059]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: Setting Geolocation for MyVolumio to us3 May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:30 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:30 volumiopi3b-ss volumio[4059]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 10:18:31 volumiopi3b-ss volumio[4059]: info: Updating MyVolumio device info May 31 10:18:31 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:31 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:31 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:31 volumiopi3b-ss volumio[4059]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Retrieving Cloud Streaming UI May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Getting Tidal Cloud Configuration May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Getting Qobuz Cloud Configuration May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Asking plugin for UI Config May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Getting Spotify Cloud Configuration May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Asking plugin for UI Config May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Saving Spotify Acccount May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Got Tidal Cloud Configuration May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Got it May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: Got it May 31 10:18:34 volumiopi3b-ss volumio[4059]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 10:18:34 volumiopi3b-ss volumio[4059]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetBrowseSources May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetBrowseSources May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetBrowseSources May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: MYVOLUMIO: Adding device May 31 10:18:34 volumiopi3b-ss volumio[4059]: info: MYVOLUMIO: Evaluating Server May 31 10:18:35 volumiopi3b-ss volumio[4059]: info: Setting Geolocation for MyVolumio to us4 May 31 10:18:35 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:35 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:35 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:35 volumiopi3b-ss volumio[4059]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 10:18:36 volumiopi3b-ss volumio[4059]: info: Updating MyVolumio device info May 31 10:18:36 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:36 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:36 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:36 volumiopi3b-ss volumio[4059]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 10:18:38 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:18:38 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 10:18:38 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:38 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 10:18:38 volumiopi3b-ss volumio-remote-updater[639]: Test mode disabled May 31 10:18:38 volumiopi3b-ss volumio-remote-updater[639]: Alpha mode disabled May 31 10:18:38 volumiopi3b-ss volumio-remote-updater[639]: Alpha legacy test mode disabled May 31 10:18:38 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 10:18:39 volumiopi3b-ss volumio[4059]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 10:18:39 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 10:18:41 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 10:18:41 volumiopi3b-ss volumio[4059]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 May 31 10:18:41 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetState May 31 10:18:41 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:42 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioGetBrowseSources May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand rescan May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: May 31 10:18:46 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand rescan took 15 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 14 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 6 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatetrue May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.708-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.709-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.710-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.711-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.713-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.714-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.715-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.716-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.718-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.719-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.721-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:46 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:46.721-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ------------------------------ 249ms May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: May 31 10:18:46 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: May 31 10:18:46 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: MPD Database updated - AlbumList cache refreshed May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: May 31 10:18:46 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: database May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: May 31 10:18:46 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 35 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 33 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 32 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 31 milliseconds May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:46 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:46 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.197-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.199-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.200-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.201-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.202-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.203-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.204-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.205-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.207-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.208-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.209-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.211-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.211-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.212-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.213-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.214-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.216-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.217-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.220-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.221-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.222-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.222-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.223-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.224-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ------------------------------ 509ms May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ------------------------------ 507ms May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: MPD Database updated - AlbumList cache refreshed May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: May 31 10:18:47 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: database May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: May 31 10:18:47 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: MPD Database updated - AlbumList cache refreshed May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: May 31 10:18:47 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: database May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: May 31 10:18:47 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 616 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 613 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 611 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 129 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 128 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 127 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 124 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 121 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 120 milliseconds May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:18:47 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: No code May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:18:47 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.921-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.922-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.923-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.924-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.927-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.929-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.931-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.932-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.932-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.932-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.933-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.934-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.937-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.938-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.939-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.940-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.941-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.942-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.944-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.945-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.946-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.947-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.947-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.948-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.950-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.951-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.952-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.954-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.955-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.955-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.956-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.958-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.959-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.960-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.960-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.962-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.964-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.966-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.967-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.968-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.968-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.969-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.970-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.972-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.973-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.974-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.974-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.976-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.977-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.978-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.979-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.980-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.981-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.981-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.982-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.982-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.983-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.983-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.983-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.986-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.987-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.987-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.989-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.991-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.992-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.993-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.993-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.994-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.995-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.996-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:18:47 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:18:47.996-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: ------------------------------ 1280ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: ------------------------------ 1281ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: ------------------------------ 794ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: ------------------------------ 795ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: ------------------------------ 790ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: ------------------------------ 791ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: Completed starting MyVolumio Plugin May 31 10:18:48 volumiopi3b-ss volumio[4059]: [Metrics] CommandRouter: 51s 97.60ms May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumiosetStartupVolume May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:48 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:18:49 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 31 10:18:49 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 10:18:49 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 31 10:18:50 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:18:54 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:18:55 volumiopi3b-ss volumio[4059]: info: BOOT COMPLETED May 31 10:18:58 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:02 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:06 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:08 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 31 10:19:08 volumiopi3b-ss sudo[4482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/USB May 31 10:19:08 volumiopi3b-ss sudo[4482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 10:19:08 volumiopi3b-ss systemd[1]: mnt-NAS-USB.mount: Deactivated successfully. May 31 10:19:08 volumiopi3b-ss sudo[4482]: pam_unix(sudo:session): session closed for user root May 31 10:19:08 volumiopi3b-ss volumio[4059]: info: Share USB successfully unmounted May 31 10:19:09 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 31 10:19:10 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: May 31 10:19:11 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: May 31 10:19:11 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: May 31 10:19:11 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 17 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 15 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatetrue May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: No code May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.497-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.499-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.500-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.501-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.502-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.502-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.506-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.505-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.509-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.509-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.511-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:11 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:11.512-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ------------------------------ 237ms May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: May 31 10:19:11 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: May 31 10:19:11 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: May 31 10:19:11 volumiopi3b-ss volumio[4059]: ---------------------------- MPD announces state update: update May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 242 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 240 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::getState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::sendMpdCommand status May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 241 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 239 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 16 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 15 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 13 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 12 milliseconds May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatetrue May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: No code May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: No code May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: No code May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:19:11 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: No code May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:11 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.048-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.049-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.050-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.051-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.052-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.053-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.054-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.055-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.057-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.059-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.060-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.061-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.063-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.064-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.065-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.065-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.067-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.069-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.070-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.071-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.073-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.074-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.075-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.076-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.078-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.079-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.080-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.080-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.084-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.084-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.085-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.085-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.088-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.088-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.089-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.090-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.096-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.097-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.098-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.099-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.101-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.101-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.102-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.103-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.105-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.106-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.107-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.107-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: ------------------------------ 849ms May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: ------------------------------ 846ms May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: ------------------------------ 622ms May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: ------------------------------ 620ms May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: [1780237152301] Database update started May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 791 milliseconds May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: sendMpdCommand status took 787 milliseconds May 31 10:19:12 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: Command Router : Notfying DB Updatefalse May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::Close All Modals sent May 31 10:19:12 volumiopi3b-ss volumio[4059]: verbose: ControllerMpd::parseState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: ControllerMpd::pushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::servicePushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:12 volumiopi3b-ss volumio[4059]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 10:19:12 volumiopi3b-ss volumio[4059]: verbose: CURRENT POSITION 0 May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState stateService stop May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::syncState currentStatus stop May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: No code May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreStateMachine::pushState May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CorePlayQueue::getTrack 0 May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::volumioPushState May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.436-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.438-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.440-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.441-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.441-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.441-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.442-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.444-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.446-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.447-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" state=STATUS_STOPPED positionMs=0 volume=84 May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.449-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.20:52333 @ 0x2f5a240" id= title= May 31 10:19:12 volumiopi3b-ss volumio5-onboarding[1328]: time=2026-05-31T10:19:12.449-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.21:50027 @ 0x2dbc990" id= title= May 31 10:19:12 volumiopi3b-ss volumio[4059]: info: ------------------------------ 927ms May 31 10:19:14 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:18 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:21 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 31 10:19:21 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 31 10:19:22 volumiopi3b-ss volumio[4059]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 10:19:22 volumiopi3b-ss volumio[4059]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L volumio-salon.local. -m SMB3_11 May 31 10:19:22 volumiopi3b-ss volumio[4059]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L moode.local. -m SMB3_11 May 31 10:19:23 volumiopi3b-ss volumio[4059]: info: Error browsing device moode for shares: Command failed: smbclient --no-pass --debuglevel=0 -L moode.local. -m SMB3_11 May 31 10:19:26 volumiopi3b-ss volumio[4059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 10:19:26 volumiopi3b-ss volumio[4059]: Error: dns service error: unknown May 31 10:19:26 volumiopi3b-ss volumio[4059]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) May 31 10:19:26 volumiopi3b-ss volumio[4059]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 31 10:19:26 volumiopi3b-ss volumio[4059]: errorCode: -65537 May 31 10:19:26 volumiopi3b-ss volumio[4059]: } May 31 10:19:26 volumiopi3b-ss volumio[4059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 10:19:27 volumiopi3b-ss sudo[4564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 10:18' May 31 10:19:27 volumiopi3b-ss sudo[4564]: 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"