Dec 27 09:02:00 rpi3 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 27 09:02:00 rpi3 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 27 09:02:00 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:00] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 27 09:02:00 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:00] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 27 09:02:00 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:00] [error] handle_connect error: Connection refused Dec 27 09:02:02 rpi3 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 27 09:02:02 rpi3 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 27 09:02:02 rpi3 systemd[1]: setdatetime-helper.service: Consumed 1.954s CPU time. Dec 27 09:02:02 rpi3 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 27 09:02:02 rpi3 systemd[1]: Reached target graphical.target - Graphical Interface. Dec 27 09:02:02 rpi3 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 27 09:02:02 rpi3 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 27 09:02:02 rpi3 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 27 09:02:02 rpi3 systemd[1]: Startup finished in 19.391s (kernel) + 45.550s (userspace) = 1min 4.942s. Dec 27 09:02:03 rpi3 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 27 09:02:06 rpi3 volumio[1226]: info: ------------------------------------------- Dec 27 09:02:06 rpi3 volumio[1226]: info: ----- Volumio3 ---- Dec 27 09:02:06 rpi3 volumio[1226]: info: ------------------------------------------- Dec 27 09:02:06 rpi3 volumio[1226]: info: ----- System startup ---- Dec 27 09:02:06 rpi3 volumio[1226]: info: ------------------------------------------- Dec 27 09:02:09 rpi3 volumio[1226]: info: MYVOLUMIO Environment detected Dec 27 09:02:09 rpi3 volumio[1226]: info: Plugin folders cleanup Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning into folder /volumio/app/plugins/ Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category audio_interface Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category miscellanea Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category music_service Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category plugins.json Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category system_controller Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category user_interface Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning into folder /data/plugins/ Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category audio_interface Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category music_service Dec 27 09:02:09 rpi3 volumio[1226]: info: Scanning category system_hardware Dec 27 09:02:09 rpi3 volumio[1226]: info: Plugin folders cleanup completed Dec 27 09:02:09 rpi3 volumio[1226]: info: ------------------------------------------- Dec 27 09:02:09 rpi3 volumio[1226]: info: ----- Core plugins startup ---- Dec 27 09:02:09 rpi3 volumio[1226]: info: ------------------------------------------- Dec 27 09:02:09 rpi3 volumio[1226]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 09:02:09 rpi3 volumio[1226]: info: Adding plugin upnp to MyMusic Plugins Dec 27 09:02:09 rpi3 volumio[1226]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 09:02:09 rpi3 volumio[1226]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 09:02:09 rpi3 volumio[1226]: info: Loading plugins from folder /data/plugins/ Dec 27 09:02:09 rpi3 volumio[1226]: info: Loading plugin "system"... Dec 27 09:02:09 rpi3 volumio[1226]: info: Loading plugin "appearance"... Dec 27 09:02:10 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:10] [connect] Successful connection Dec 27 09:02:13 rpi3 volumio[1226]: info: Loading plugin "network"... Dec 27 09:02:13 rpi3 volumio[1226]: info: Refreshing Cached IP Addresses Dec 27 09:02:13 rpi3 sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 09:02:13 rpi3 volumio[1226]: info: Loading plugin "services"... Dec 27 09:02:13 rpi3 sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:02:13 rpi3 volumio[1226]: info: Loading plugin "alsa_controller"... Dec 27 09:02:14 rpi3 sudo[1349]: pam_unix(sudo:session): session closed for user root Dec 27 09:02:14 rpi3 sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 09:02:14 rpi3 sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:02:14 rpi3 sudo[1358]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 09:02:14 rpi3 sudo[1358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:02:14 rpi3 sudo[1351]: pam_unix(sudo:session): session closed for user root Dec 27 09:02:14 rpi3 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 27 09:02:14 rpi3 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:02:14 rpi3 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:02:14 rpi3 upmpdcli[1376]: Could not open config: /tmp/upmpdcli.conf Dec 27 09:02:14 rpi3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 27 09:02:14 rpi3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 27 09:02:14 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 09:02:14 rpi3 volumio[1226]: info: Loading plugin "wizard"... Dec 27 09:02:14 rpi3 volumio[1226]: info: Loading plugin "networkfs"... Dec 27 09:02:14 rpi3 volumio[1226]: info: Starting Udev Watcher for removable devices Dec 27 09:02:14 rpi3 volumio[1226]: info: Ignoring mount for partition: boot Dec 27 09:02:14 rpi3 volumio[1226]: info: Ignoring mount for partition: volumio Dec 27 09:02:14 rpi3 volumio[1226]: info: Ignoring mount for partition: volumio_data Dec 27 09:02:14 rpi3 volumio[1226]: info: Ignoring mount for partition: boot Dec 27 09:02:14 rpi3 volumio[1226]: info: Mounting Device MUSIC Dec 27 09:02:14 rpi3 sudo[1358]: pam_unix(sudo:session): session closed for user root Dec 27 09:02:14 rpi3 sudo[1390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MUSIC -o noatime Dec 27 09:02:14 rpi3 sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:02:15 rpi3 kernel: exFAT-fs (sda2): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 27 09:02:15 rpi3 sudo[1390]: pam_unix(sudo:session): session closed for user root Dec 27 09:02:15 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 09:02:15 rpi3 volumio[1226]: info: Loading plugin "volumio_command_line_client"... Dec 27 09:02:15 rpi3 volumio[1226]: info: Loading plugin "upnp"... Dec 27 09:02:15 rpi3 volumio[1226]: info: [1766844135546] Starting Upmpd Daemon Dec 27 09:02:15 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 09:02:15 rpi3 volumio[1226]: info: Loading plugin "my_music"... Dec 27 09:02:15 rpi3 volumio[1226]: info: Loading plugin "mpd"... Dec 27 09:02:16 rpi3 volumio[1226]: info: Loading plugin "upnp_browser"... Dec 27 09:02:22 rpi3 volumio[1226]: info: Starting UPNP Browser Dec 27 09:02:22 rpi3 volumio[1226]: info: Loading plugin "alarm-clock"... Dec 27 09:02:22 rpi3 volumio[1226]: info: Loading plugin "airplay_emulation"... Dec 27 09:02:22 rpi3 volumio[1226]: info: Starting Shairport Sync Dec 27 09:02:22 rpi3 volumio[1226]: info: Loading plugin "last_100"... Dec 27 09:02:22 rpi3 volumio[1226]: info: Loading plugin "webradio"... Dec 27 09:02:23 rpi3 volumio[1226]: info: Loading plugin "i2s_dacs"... Dec 27 09:02:23 rpi3 volumio[1226]: info: I2S DAC not set, start Auto-detection Dec 27 09:02:23 rpi3 volumio[1226]: info: Loading plugin "volumiodiscovery"... Dec 27 09:02:23 rpi3 volumio[1226]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 09:02:23 rpi3 volumio[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 09:02:23 rpi3 volumio[1226]: *** WARNING *** For more information see Dec 27 09:02:23 rpi3 volumio[1226]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 09:02:23 rpi3 volumio[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 09:02:23 rpi3 volumio[1226]: *** WARNING *** For more information see Dec 27 09:02:23 rpi3 node[1226]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 09:02:23 rpi3 node[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 09:02:23 rpi3 node[1226]: *** WARNING *** For more information see Dec 27 09:02:23 rpi3 node[1226]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 09:02:23 rpi3 node[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 09:02:23 rpi3 node[1226]: *** WARNING *** For more information see Dec 27 09:02:23 rpi3 volumio[1226]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 09:02:23 rpi3 volumio[1226]: info: Discovery: Started advertising with name: Rpi3 Dec 27 09:02:23 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 09:02:23 rpi3 volumio[1226]: info: Loading plugin "spop"... Dec 27 09:02:25 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:25] [connect] Successful connection Dec 27 09:02:29 rpi3 volumio[1226]: info: Loading plugin "youtube2"... Dec 27 09:02:29 rpi3 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 27 09:02:29 rpi3 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:02:29 rpi3 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:02:29 rpi3 upmpdcli[1410]: Could not open config: /tmp/upmpdcli.conf Dec 27 09:02:29 rpi3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 27 09:02:29 rpi3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 27 09:02:32 rpi3 volumio[1226]: info: Loading plugin "ytcr"... Dec 27 09:02:39 rpi3 volumio[1226]: info: Loading plugin "ytmusic"... Dec 27 09:02:40 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:40] [connect] Successful connection Dec 27 09:02:41 rpi3 volumio[1226]: info: Loading plugin "outputs"... Dec 27 09:02:41 rpi3 volumio[1226]: info: Loading plugin "albumart"... Dec 27 09:02:41 rpi3 volumio[1226]: info: Plugin example_plugin is not enabled Dec 27 09:02:41 rpi3 volumio[1226]: info: Loading plugin "inputs"... Dec 27 09:02:41 rpi3 volumio[1226]: info: Loading plugin "updater_comm"... Dec 27 09:02:42 rpi3 volumio[1226]: info: Plugin mpdemulation is not enabled Dec 27 09:02:42 rpi3 volumio[1226]: info: Loading plugin "rest_api"... Dec 27 09:02:42 rpi3 volumio[1226]: info: Loading plugin "websocket"... Dec 27 09:02:42 rpi3 volumio[1226]: info: Starting Socket.io Server version 1.7.4 Dec 27 09:02:42 rpi3 volumio[1226]: info: Loading plugin "fusiondsp"... Dec 27 09:02:44 rpi3 volumio[1425]: Forking 3 albumart workers Dec 27 09:02:45 rpi3 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 27 09:02:45 rpi3 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:02:45 rpi3 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:02:45 rpi3 upmpdcli[1452]: Could not open config: /tmp/upmpdcli.conf Dec 27 09:02:45 rpi3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 27 09:02:45 rpi3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 27 09:02:45 rpi3 volumio[1226]: info: Applying required configuration parameters for plugin fusiondsp Dec 27 09:02:47 rpi3 volumio[1226]: info: Loading plugin "mpdoutput"... Dec 27 09:02:51 rpi3 volumio[1226]: info: Loading plugin "80s80s"... Dec 27 09:02:55 rpi3 volumio-remote-updater[678]: [2025-12-27 09:02:55] [connect] Successful connection Dec 27 09:02:57 rpi3 volumio[1226]: info: Applying required configuration parameters for plugin 80s80s Dec 27 09:02:57 rpi3 volumio[1226]: info: [1766844177640] [80s80s] API delay: 30 Dec 27 09:02:57 rpi3 volumio[1226]: info: Loading plugin "minidlna"... Dec 27 09:03:00 rpi3 volumio[1226]: info: Applying required configuration parameters for plugin minidlna Dec 27 09:03:00 rpi3 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Dec 27 09:03:00 rpi3 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:03:00 rpi3 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:03:00 rpi3 upmpdcli[1494]: Could not open config: /tmp/upmpdcli.conf Dec 27 09:03:00 rpi3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 27 09:03:00 rpi3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 27 09:03:02 rpi3 volumio[1226]: info: Loading plugin "music_services_shield"... Dec 27 09:03:04 rpi3 volumio[1449]: Starting albumart workers Dec 27 09:03:04 rpi3 volumio[1451]: Starting albumart workers Dec 27 09:03:05 rpi3 volumio[1450]: Starting albumart workers Dec 27 09:03:08 rpi3 volumio[1226]: info: Applying required configuration parameters for plugin music_services_shield Dec 27 09:03:08 rpi3 volumio[1226]: info: Loading i18n strings for locale es Dec 27 09:03:09 rpi3 volumio[1226]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Dec 27 09:03:09 rpi3 volumio[1226]: Updating browse sources language Dec 27 09:03:09 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 09:03:10 rpi3 volumio-remote-updater[678]: [2025-12-27 09:03:10] [connect] Successful connection Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::initPlayerControls Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 09:03:13 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 09:03:14 rpi3 volumio[1226]: Express server listening on port 3000 Dec 27 09:03:14 rpi3 volumio[1226]: [Metrics] WebUI: 70s 45.17ms Dec 27 09:03:14 rpi3 volumio[1226]: info: CoreStateMachine::resetVolumioState Dec 27 09:03:14 rpi3 volumio[1226]: info: CoreStateMachine::getcurrentVolume Dec 27 09:03:14 rpi3 volumio[1226]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 09:03:14 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 09:03:15 rpi3 sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 09:03:15 rpi3 sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:03:15 rpi3 sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 09:03:15 rpi3 sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:03:15 rpi3 sudo[1528]: pam_unix(sudo:session): session closed for user root Dec 27 09:03:15 rpi3 sudo[1526]: pam_unix(sudo:session): session closed for user root Dec 27 09:03:15 rpi3 volumio[1226]: info: Volumio Network Manager: Network status updated: 2 Dec 27 09:03:15 rpi3 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Dec 27 09:03:15 rpi3 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:03:15 rpi3 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 09:03:16 rpi3 upmpdcli[1538]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.104 port 49152 IPV6 port 49152 Dec 27 09:03:16 rpi3 upmpdcli[1538]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 27 09:03:16 rpi3 upmpdcli[1538]: :2:../src/main.cxx:728::MPD connection failed Dec 27 09:03:17 rpi3 volumio[1226]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 27 09:03:17 rpi3 volumio[1226]: info: VolumeController:: Volume=87 Mute =false Dec 27 09:03:17 rpi3 volumio[1226]: info: CoreStateMachine::pushState Dec 27 09:03:17 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:17 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:17 rpi3 volumio[1226]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:17 rpi3 volumio[1226]: info: CoreStateMachine::updateTrackBlock Dec 27 09:03:17 rpi3 volumio[1226]: info: CorePlayQueue::getTrackBlock Dec 27 09:03:17 rpi3 volumio[1226]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 09:03:18 rpi3 volumio[1226]: info: Reloading queue from file Dec 27 09:03:18 rpi3 upmpdcli[1538]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 27 09:03:18 rpi3 upmpdcli[1538]: :2:../src/main.cxx:728::MPD connection failed Dec 27 09:03:18 rpi3 volumio[1226]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreStateMachine::setRepeat null single undefined Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreStateMachine::pushState Dec 27 09:03:18 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreStateMachine::setRandom true Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreStateMachine::pushState Dec 27 09:03:18 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:18 rpi3 volumio[1226]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:18 rpi3 volumio[1226]: info: Setting Device type: Raspberry PI Dec 27 09:03:18 rpi3 volumio[1226]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 27 09:03:18 rpi3 volumio[1226]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Dec 27 09:03:18 rpi3 volumio[1226]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 27 09:03:19 rpi3 volumio[1226]: info: VolumeController:: Volume=87 Mute =false Dec 27 09:03:19 rpi3 volumio[1226]: info: CoreStateMachine::pushState Dec 27 09:03:19 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:19 rpi3 volumio[1226]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:19 rpi3 volumio[1226]: info: Discovery: adding c3866e07-6d92-43c3-ba41-feef8dca5196 Dec 27 09:03:19 rpi3 volumio[1226]: info: Discovery: Found device Rpi3 Dec 27 09:03:19 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetState Dec 27 09:03:19 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:19 rpi3 sudo[1574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 27 09:03:19 rpi3 sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 09:03:19 rpi3 volumio[1226]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 27 09:03:19 rpi3 volumio[1226]: info: Discovery: this is already registered, c3866e07-6d92-43c3-ba41-feef8dca5196 Dec 27 09:03:19 rpi3 volumio[1226]: info: Discovery: Found device Rpi3 Dec 27 09:03:19 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetState Dec 27 09:03:19 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:19 rpi3 sudo[1574]: pam_unix(sudo:session): session closed for user root Dec 27 09:03:20 rpi3 volumio[1226]: info: Completed loading Core Plugins Dec 27 09:03:20 rpi3 volumio[1226]: info: Preparing to generate the ALSA configuration file Dec 27 09:03:20 rpi3 volumio[1226]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 27 09:03:20 rpi3 volumio[1226]: info: Reading ALSA contributions from plugins. Dec 27 09:03:20 rpi3 volumio[1226]: info: Upmpdcli Daemon Started Dec 27 09:03:20 rpi3 volumio[1226]: info: CALLMETHOD: music_service minidlna saveConf [object Object] Dec 27 09:03:20 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: minidlna , saveConf Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetState Dec 27 09:03:21 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetState Dec 27 09:03:21 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 09:03:21 rpi3 volumio[1226]: info: Received Get System Info Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 09:03:21 rpi3 volumio[1226]: info: Discovery: Getting this device information Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetState Dec 27 09:03:21 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 09:03:21 rpi3 volumio[1226]: info: CoreCommandRouter::volumioGetState Dec 27 09:03:21 rpi3 volumio[1226]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:21 rpi3 volumio[1226]: info: Listing playlists Dec 27 09:03:22 rpi3 upmpdcli[1538]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 27 09:03:22 rpi3 upmpdcli[1538]: :2:../src/main.cxx:728::MPD connection failed Dec 27 09:03:22 rpi3 volumio[1226]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 09:03:22 rpi3 volumio[1226]: TypeError: Cannot read properties of undefined (reading 'localeCompare') Dec 27 09:03:22 rpi3 volumio[1226]: at /data/plugins/music_service/minidlna/index.js:382:33 Dec 27 09:03:22 rpi3 volumio[1226]: at Array.forEach () Dec 27 09:03:22 rpi3 volumio[1226]: at /data/plugins/music_service/minidlna/index.js:354:19 Dec 27 09:03:22 rpi3 volumio[1226]: at /data/plugins/music_service/minidlna/node_modules/graceful-fs/graceful-fs.js:123:16 Dec 27 09:03:22 rpi3 volumio[1226]: at FSReqCallback.readFileAfterClose [as oncomplete] (node:internal/fs/read/context:68:3) Dec 27 09:03:22 rpi3 volumio[1226]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 09:03:25 rpi3 volumio-remote-updater[678]: [2025-12-27 09:03:25] [connect] Successful connection Dec 27 09:03:30 rpi3 upmpdcli[1538]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 27 09:03:30 rpi3 upmpdcli[1538]: :2:../src/main.cxx:728::MPD connection failed Dec 27 09:03:34 rpi3 sudo[1605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 09:02' Dec 27 09:03:34 rpi3 sudo[1605]: 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"