Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::initPlayerControls Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 00:36:00 volumio volumio[3388]: Express server listening on port 3000 Dec 19 00:36:00 volumio volumio[3388]: [Metrics] WebUI: 5s 352.41ms Dec 19 00:36:00 volumio volumio[3388]: info: CoreStateMachine::resetVolumioState Dec 19 00:36:00 volumio volumio[3388]: info: CoreStateMachine::getcurrentVolume Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 00:36:00 volumio volumio[3388]: info: CoreStateMachine::pushState Dec 19 00:36:00 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 00:36:00 volumio volumio[3388]: info: CoreCommandRouter::volumioPushState Dec 19 00:36:00 volumio volumio[3388]: info: Volumio Network Manager: Network status updated: 1 Dec 19 00:36:00 volumio volumio[3448]: Forking 3 albumart workers Dec 19 00:36:01 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:01 volumio volumio[3388]: info: Executing start script for DAC Volumio Zero ES9039 DAC Dec 19 00:36:01 volumio sudo[3499]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x48 0x00 0x02 Dec 19 00:36:01 volumio sudo[3499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:01 volumio sudo[3499]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:01 volumio volumio[3467]: Starting albumart workers Dec 19 00:36:01 volumio volumio[3468]: Starting albumart workers Dec 19 00:36:01 volumio volumio[3466]: Starting albumart workers Dec 19 00:36:02 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Dec 19 00:36:02 volumio volumio[3388]: info: Cannot mount NAS TimeMachine at system boot, trial number 2 ,retrying in 5 seconds Dec 19 00:36:03 volumio volumio[3388]: info: CoreStateMachine::setRepeat null single undefined Dec 19 00:36:03 volumio volumio[3388]: info: CoreStateMachine::pushState Dec 19 00:36:03 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::volumioPushState Dec 19 00:36:03 volumio volumio[3388]: info: CoreStateMachine::setRandom true Dec 19 00:36:03 volumio volumio[3388]: info: CoreStateMachine::pushState Dec 19 00:36:03 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::volumioPushState Dec 19 00:36:03 volumio volumio[3388]: info: Setting Device type: Raspberry PI Dec 19 00:36:03 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 2 Dec 19 00:36:03 volumio volumio[3388]: info: DAC script executed Dec 19 00:36:03 volumio volumio[3388]: info: Completed loading Core Plugins Dec 19 00:36:03 volumio volumio[3388]: info: Preparing to generate the ALSA configuration file Dec 19 00:36:03 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Dec 19 00:36:03 volumio volumio[3388]: info: Reloading queue from file Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:03 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 00:36:03 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Dec 19 00:36:03 volumio volumio[3388]: info: Asound.conf file unchanged, so no further update is needed Dec 19 00:36:03 volumio volumio[3388]: info: Output device has changed, restarting MPD Dec 19 00:36:03 volumio volumio[3388]: info: Output device has changed, restarting Shairport Sync Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:03 volumio sudo[3525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 00:36:03 volumio sudo[3525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:03 volumio sudo[3525]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:03 volumio sudo[3527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 00:36:03 volumio sudo[3527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:03 volumio volumio[3388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 00:36:03 volumio volumio[3388]: info: ___________ START PLUGINS ___________ Dec 19 00:36:03 volumio volumio[3388]: info: ControllerMpd::onStart: Initializing MPD Dec 19 00:36:03 volumio volumio[3388]: info: Creating MPD Configuration file Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 00:36:03 volumio volumio[3388]: info: [1766075763494] CoreMusicLibrary::Adding element 媒体服务器 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 00:36:03 volumio sudo[3535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 00:36:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 19 00:36:03 volumio sudo[3535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:03 volumio sudo[3535]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:03 volumio volumio[3388]: info: UPNP Browser: Client initialized successfully Dec 19 00:36:03 volumio sudo[3537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 00:36:03 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 00:36:03 volumio volumio[3388]: info: [1766075763599] CoreMusicLibrary::Adding element Last_100 Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 00:36:03 volumio volumio[3388]: info: [1766075763605] CoreMusicLibrary::Adding element Webradio Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 00:36:03 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 19 00:36:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 00:36:03 volumio systemd[1]: mpd.service: Consumed 4.416s CPU time. Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 00:36:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 19 00:36:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 00:36:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 00:36:03 volumio volumio[3388]: info: Initializing BBC Radios Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 00:36:03 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:03 volumio volumio[3388]: info: Volumio Calling Home Dec 19 00:36:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 00:36:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 00:36:03 volumio sudo[3564]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 00:36:03 volumio sudo[3564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 00:36:03 volumio sudo[3564]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:04 volumio volumio[3388]: info: Discovery: adding 2cdbb8c5-b5cf-4a08-b8a5-3be62f29c871 Dec 19 00:36:04 volumio volumio[3388]: info: Discovery: Found device Volumio Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:04 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:04 volumio volumio[3388]: info: Discovery: this is already registered, 2cdbb8c5-b5cf-4a08-b8a5-3be62f29c871 Dec 19 00:36:04 volumio volumio[3388]: info: Discovery: Found device Volumio Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:04 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:04 volumio volumio[3388]: info: MPD Permissions set Dec 19 00:36:04 volumio volumio[3388]: info: MPD Permissions set Dec 19 00:36:04 volumio volumio[3388]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 19 00:36:04 volumio volumio[3388]: info: Volumio called home Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:04 volumio volumio[3388]: info: Starting Shairport Sync Dec 19 00:36:04 volumio volumio[3388]: info: Starting Shairport Sync Dec 19 00:36:04 volumio volumio[3388]: info: Starting Shairport Sync Dec 19 00:36:04 volumio sudo[3579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 00:36:04 volumio sudo[3579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:04 volumio sudo[3581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 00:36:04 volumio sudo[3581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:04 volumio sudo[3583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 00:36:04 volumio sudo[3583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 00:36:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 00:36:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 00:36:04 volumio systemd[1]: shairport-sync.service: Consumed 2.066s CPU time. Dec 19 00:36:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 00:36:04 volumio sudo[3581]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:04 volumio sudo[3579]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:04 volumio sudo[3583]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:05 volumio volumio[3388]: info: Shairport-Sync Started Dec 19 00:36:05 volumio volumio[3388]: Error adding Membership: Error: addMembership EINVAL Dec 19 00:36:05 volumio volumio[3388]: info: Shairport-Sync Started Dec 19 00:36:05 volumio volumio[3388]: info: Shairport-Sync Started Dec 19 00:36:05 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:05 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:06 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Dec 19 00:36:07 volumio mpd[3567]: 2025-12-19T00:36:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 00:36:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 00:36:07 volumio sudo[3527]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:07 volumio sudo[3537]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:07 volumio volumio[3388]: info: Completed starting Core Plugins Dec 19 00:36:07 volumio volumio[3388]: info: ------------------------------------------- Dec 19 00:36:07 volumio volumio[3388]: info: ----- MyVolumio plugins startup ---- Dec 19 00:36:07 volumio volumio[3388]: info: ------------------------------------------- Dec 19 00:36:07 volumio volumio[3388]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 19 00:36:07 volumio volumio[3388]: error: MPD error: The expression evaluated to a falsy value: Dec 19 00:36:07 volumio volumio[3388]: assert.ok(self.idling) Dec 19 00:36:07 volumio volumio[3388]: error: The expression evaluated to a falsy value: Dec 19 00:36:07 volumio volumio[3388]: assert.ok(self.idling) Dec 19 00:36:07 volumio volumio[3388]: error: updateQueue error: null Dec 19 00:36:07 volumio volumio[3388]: info: MPD running with PID3567 Dec 19 00:36:07 volumio volumio[3388]: ,establishing connection Dec 19 00:36:07 volumio volumio[3388]: info: Cannot mount NAS TimeMachine at system boot, trial number 3 ,retrying in 5 seconds Dec 19 00:36:07 volumio volumio[3388]: error: updateQueue error: null Dec 19 00:36:07 volumio sudo[3622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 00:36:07 volumio sudo[3622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:07 volumio sudo[3624]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 00:36:07 volumio sudo[3624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:07 volumio sudo[3622]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:07 volumio sudo[3624]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:07 volumio sudo[3627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 19 00:36:07 volumio sudo[3627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:07 volumio sudo[3627]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:07 volumio volumio[3388]: info: Upmpdcli Daemon Started Dec 19 00:36:10 volumio sudo[3631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 00:36:10 volumio sudo[3631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:10 volumio sudo[3633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 00:36:10 volumio sudo[3633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:10 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 00:36:10 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 00:36:10 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 00:36:10 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 00:36:10 volumio sudo[3633]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:10 volumio sudo[3631]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:10 volumio mpd_monitor.sh[3636]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 00:36:10 volumio volumio-remote-updater[706]: [2025-12-19 00:36:10] [connect] Successful connection Dec 19 00:36:10 volumio volumio[3388]: info: Successfully started MPD Monitor Dec 19 00:36:10 volumio volumio-remote-updater[706]: [2025-12-19 00:36:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766075770 101 Dec 19 00:36:10 volumio volumio[3388]: 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: 7 Dec 19 00:36:10 volumio volumio[3388]: info: Successfully started MPD Monitor Dec 19 00:36:11 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Dec 19 00:36:11 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:11 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:11 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 19 00:36:11 volumio volumio[3388]: info: Listing playlists Dec 19 00:36:11 volumio volumio[3388]: info: CoreCommandRouter::volumioGetQueue Dec 19 00:36:11 volumio volumio[3388]: info: CoreStateMachine::getQueue Dec 19 00:36:11 volumio volumio[3388]: info: CorePlayQueue::getQueue Dec 19 00:36:15 volumio volumio[3388]: info: Cannot mount NAS TimeMachine at system boot, trial number 4 ,retrying in 5 seconds Dec 19 00:36:16 volumio volumio[3388]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 19 00:36:17 volumio volumio[3388]: Cannot compose Albumart path Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin bluetooth to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin multiroom to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin metavolumio to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin cd_controller to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 19 00:36:20 volumio volumio[3388]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 19 00:36:21 volumio volumio[3388]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 19 00:36:21 volumio volumio[3388]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 19 00:36:21 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:21 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:21 volumio volumio[3388]: info: Starting MyVolumio Remote Streaming Endpoints Dec 19 00:36:21 volumio volumio[3388]: info: MyVolumio not started Dec 19 00:36:21 volumio volumio[3388]: info: Initializing device activation check Dec 19 00:36:21 volumio volumio[3388]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 19 00:36:21 volumio volumio[3388]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 19 00:36:22 volumio volumio[3388]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 19 00:36:22 volumio volumio[3388]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 19 00:36:22 volumio volumio[3388]: info: Streaming services startup Dec 19 00:36:22 volumio volumio[3388]: info: Starting Streaming Daemon Dec 19 00:36:22 volumio sudo[3656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 19 00:36:22 volumio sudo[3656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:22 volumio volumio[3388]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 19 00:36:22 volumio volumio[3388]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 19 00:36:22 volumio sudo[3656]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:23 volumio volumio[3388]: error: Cannot start Volumio Streaming Daemon Dec 19 00:36:23 volumio volumio[3388]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 19 00:36:23 volumio volumio[3388]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 19 00:36:34 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 00:36:34 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:34 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 00:36:36 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 19 00:36:36 volumio volumio[3388]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 19 00:36:36 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:36 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 00:36:41 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 00:36:42 volumio volumio[3388]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 19 00:36:42 volumio volumio[3388]: info: Completed starting MyVolumio Plugin Dec 19 00:36:42 volumio volumio[3388]: [Metrics] CommandRouter: 47s 12.10ms Dec 19 00:36:42 volumio volumio[3388]: info: CoreCommandRouter::volumiosetStartupVolume Dec 19 00:36:42 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 00:36:42 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 00:36:42 volumio volumio[3388]: info: CoreCommandRouter::Close All Modals sent Dec 19 00:36:42 volumio volumio[3388]: info: CoreCommandRouter::Close All Modals sent Dec 19 00:36:43 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 19 00:36:43 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 19 00:36:43 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 19 00:36:48 volumio sudo[3714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 00:36:48 volumio sudo[3714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:48 volumio sudo[3714]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:48 volumio sudo[3716]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 00:36:48 volumio sudo[3716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:48 volumio sudo[3716]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:48 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: Mozilla/5.0 (Linux; U; Android 14; zh-CN; RMX2202 Build/UKQ1.230924.001) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/123.0.6312.80 UCBrowser/18.2.6.1452 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 19 00:36:49 volumio volumio[3388]: info: BOOT COMPLETED Dec 19 00:36:49 volumio sudo[3720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 00:36:49 volumio sudo[3720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:49 volumio sudo[3720]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:49 volumio sudo[3722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 00:36:49 volumio sudo[3722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 00:36:49 volumio sudo[3722]: pam_unix(sudo:session): session closed for user root Dec 19 00:36:49 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: Mozilla/5.0 (Linux; U; Android 14; zh-CN; RMX2202 Build/UKQ1.230924.001) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/123.0.6312.80 UCBrowser/18.2.6.1452 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:36:50 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 19 00:36:50 volumio volumio[3388]: info: Listing playlists Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 00:36:50 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 19 00:36:53 volumio volumio-remote-updater[706]: Test mode disabled Dec 19 00:36:53 volumio volumio-remote-updater[706]: Alpha mode disabled Dec 19 00:36:53 volumio volumio-remote-updater[706]: Alpha legacy test mode disabled Dec 19 00:36:53 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 19 00:36:54 volumio volumio[3388]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 19 00:36:54 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 19 00:36:54 volumio volumio[3388]: info: CoreCommandRouter::volumioGetQueue Dec 19 00:36:54 volumio volumio[3388]: info: CoreStateMachine::getQueue Dec 19 00:36:54 volumio volumio[3388]: info: CorePlayQueue::getQueue Dec 19 00:37:03 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/space%20x/0321/d4e712d1-93ea-43fa-bb68-61d4fe150a52.jpg' Dec 19 00:37:09 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 00:37:09 volumio volumio[3388]: info: Received Get System Info Dec 19 00:37:09 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 00:37:09 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 00:37:09 volumio volumio[3388]: info: Discovery: Getting this device information Dec 19 00:37:09 volumio volumio[3388]: info: CoreCommandRouter::volumioGetState Dec 19 00:37:09 volumio volumio[3388]: info: CorePlayQueue::getTrack 0 Dec 19 00:37:09 volumio volumio[3388]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 00:37:14 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Brian%20Crain/A%20Change%20of%20Season/296fa8f2-6ebb-4076-8d00-51147cba6245.jpg' Dec 19 00:37:15 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Charlie%20Puth/Attention/98acc81e-2a19-4652-9941-d1e6fd547a6f.jpg' Dec 19 00:37:18 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Stanton%20Lanier/Unveiled/6123f5da-e86e-400c-9350-d23243de487e.jpg' Dec 19 00:37:18 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Yanni/In%20My%20Time/378a79b6-ff86-477f-bc67-eb486daf98fb.png' Dec 19 00:37:19 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/PANTHEPACK/PANTHEPACK%20REMIXES/281feb05-3904-4a7f-8c8c-3118ccf5555f.jpg' Dec 19 00:37:20 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beatrich/Wish%20You%20Wanted%20To%20See%20Me/a9f24bb0-8825-4133-95de-7161e8060cc6.jpg' Dec 19 00:37:21 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/By2/%E6%95%B0%E5%88%B0%E4%BA%94/beca7a25-49ec-4cfe-b290-a0629ffd98be.jpg' Dec 19 00:37:23 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/CNBLUE/TRIGGER/3afae073-d503-49a9-80b7-49e16fb7d6fa.jpg' Dec 19 00:37:26 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Charlie%20Puth/Charlie%20Be%20Quiet!/23edc705-78d3-4b77-bb38-abac631b0160.jpg' Dec 19 00:37:26 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bandari/Moonlight%20Bay/edd5a832-0e87-4c5a-9fb6-489204d8a55e.jpg' Dec 19 00:37:29 volumio volumio[3388]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sasha%20Sloan/Dancing%20With%20Your%20Ghost/9f2a3ea7-1364-4497-bf20-d23d83d5894d.png' Dec 19 00:37:31 volumio volumio[3388]: verbose: New Socket.io Connection to 192.168.9.18 from 192.168.9.7 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8 Dec 19 00:37:33 volumio volumio[3388]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 00:37:34 volumio volumio[3388]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 00:37:34 volumio volumio[3388]: Error: connect ECONNREFUSED 4.78.139.50:443 Dec 19 00:37:34 volumio volumio[3388]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 19 00:37:34 volumio volumio[3388]: errno: -111, Dec 19 00:37:34 volumio volumio[3388]: code: 'ECONNREFUSED', Dec 19 00:37:34 volumio volumio[3388]: syscall: 'connect', Dec 19 00:37:34 volumio volumio[3388]: address: '4.78.139.50', Dec 19 00:37:34 volumio volumio[3388]: port: 443 Dec 19 00:37:34 volumio volumio[3388]: } Dec 19 00:37:34 volumio volumio[3388]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 00:37:35 volumio sudo[3809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 00:36' Dec 19 00:37:35 volumio sudo[3809]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"