Jan 28 10:06:24 volumio volumio[1084]: verbose: New Socket.io Connection to 192.168.1.92:3000 from 192.168.1.218 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 28 10:06:24 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 10:06:24 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 10:06:24 volumio volumio[1084]: info: Discovery: Getting this device information Jan 28 10:06:24 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState Jan 28 10:06:24 volumio volumio[1084]: info: CorePlayQueue::getTrack 1 Jan 28 10:06:24 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 10:06:46 volumio volumio[1084]: Searching plugin music_service/mpd Jan 28 10:06:46 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 10:06:47 volumio volumio[1084]: info: All search sources collected, pushing search results Jan 28 10:06:47 volumio volumio[1084]: Searching plugin music_service/mpd Jan 28 10:06:47 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 10:06:48 volumio volumio[1084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:06:48 volumio volumio[1084]: AggregateError Jan 28 10:06:48 volumio volumio[1084]: at internalConnectMultiple (node:net:1114:18) Jan 28 10:06:48 volumio volumio[1084]: at afterConnectMultiple (node:net:1667:5) { Jan 28 10:06:48 volumio volumio[1084]: code: 'ECONNREFUSED', Jan 28 10:06:48 volumio volumio[1084]: [errors]: [ Jan 28 10:06:48 volumio volumio[1084]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 10:06:48 volumio volumio[1084]: at createConnectionError (node:net:1634:14) Jan 28 10:06:48 volumio volumio[1084]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:06:48 volumio volumio[1084]: errno: -111, Jan 28 10:06:48 volumio volumio[1084]: code: 'ECONNREFUSED', Jan 28 10:06:48 volumio volumio[1084]: syscall: 'connect', Jan 28 10:06:48 volumio volumio[1084]: address: '158.69.210.98', Jan 28 10:06:48 volumio volumio[1084]: port: 443 Jan 28 10:06:48 volumio volumio[1084]: }, Jan 28 10:06:48 volumio volumio[1084]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 10:06:48 volumio volumio[1084]: at createConnectionError (node:net:1634:14) Jan 28 10:06:48 volumio volumio[1084]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:06:48 volumio volumio[1084]: errno: -111, Jan 28 10:06:48 volumio volumio[1084]: code: 'ECONNREFUSED', Jan 28 10:06:48 volumio volumio[1084]: syscall: 'connect', Jan 28 10:06:48 volumio volumio[1084]: address: '158.69.209.125', Jan 28 10:06:48 volumio volumio[1084]: port: 443 Jan 28 10:06:48 volumio volumio[1084]: } Jan 28 10:06:48 volumio volumio[1084]: ] Jan 28 10:06:48 volumio volumio[1084]: } Jan 28 10:06:48 volumio volumio[1084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:06:49 volumio sudo[5093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:05' Jan 28 10:06:49 volumio sudo[5093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:49 volumio sudo[5093]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:06:49 volumio volumio-remote-updater[704]: [2026-01-28 10:06:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 28 10:06:49 volumio volumio-remote-updater[704]: [2026-01-28 10:06:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 28 10:06:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 10:06:49 volumio systemd[1]: volumio.service: Consumed 41min 597ms CPU time. Jan 28 10:06:49 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:06:49 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Jan 28 10:06:49 volumio systemd[1]: upmpdcli.service: Consumed 49.527s CPU time. Jan 28 10:06:49 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:06:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 28 10:06:49 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:06:49 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 28 10:06:49 volumio systemd[1]: volumio.service: Consumed 41min 597ms CPU time. Jan 28 10:06:49 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 28 10:06:49 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:06:51 volumio volumio[5137]: info: ------------------------------------------- Jan 28 10:06:51 volumio volumio[5137]: info: ----- Volumio3 ---- Jan 28 10:06:51 volumio volumio[5137]: info: ------------------------------------------- Jan 28 10:06:51 volumio volumio[5137]: info: ----- System startup ---- Jan 28 10:06:51 volumio volumio[5137]: info: ------------------------------------------- Jan 28 10:06:51 volumio volumio[5137]: info: MYVOLUMIO Environment detected Jan 28 10:06:51 volumio volumio[5137]: info: Plugin folders cleanup Jan 28 10:06:51 volumio volumio[5137]: info: Scanning into folder /volumio/app/plugins/ Jan 28 10:06:51 volumio volumio[5137]: info: Scanning category audio_interface Jan 28 10:06:51 volumio volumio[5137]: info: Scanning category miscellanea Jan 28 10:06:51 volumio volumio[5137]: info: Scanning category music_service Jan 28 10:06:51 volumio volumio[5137]: info: Scanning category plugins.json Jan 28 10:06:51 volumio volumio[5137]: info: Scanning category system_controller Jan 28 10:06:51 volumio volumio[5137]: info: Scanning category user_interface Jan 28 10:06:51 volumio volumio[5137]: info: Scanning into folder /data/plugins/ Jan 28 10:06:51 volumio volumio[5137]: info: Plugin folders cleanup completed Jan 28 10:06:51 volumio volumio[5137]: info: ------------------------------------------- Jan 28 10:06:51 volumio volumio[5137]: info: ----- Core plugins startup ---- Jan 28 10:06:51 volumio volumio[5137]: info: ------------------------------------------- Jan 28 10:06:51 volumio volumio[5137]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 10:06:51 volumio volumio[5137]: info: Adding plugin upnp to MyMusic Plugins Jan 28 10:06:51 volumio volumio[5137]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 10:06:51 volumio volumio[5137]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 10:06:51 volumio volumio[5137]: info: Loading plugins from folder /data/plugins/ Jan 28 10:06:51 volumio volumio[5137]: info: Loading plugin "system"... Jan 28 10:06:51 volumio volumio[5137]: info: Loading plugin "appearance"... Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "network"... Jan 28 10:06:52 volumio volumio[5137]: info: Refreshing Cached IP Addresses Jan 28 10:06:52 volumio sudo[5166]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 10:06:52 volumio sudo[5166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "services"... Jan 28 10:06:52 volumio sudo[5166]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "alsa_controller"... Jan 28 10:06:52 volumio sudo[5168]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 10:06:52 volumio sudo[5168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:52 volumio sudo[5168]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:52 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "wizard"... Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "networkfs"... Jan 28 10:06:52 volumio volumio[5137]: info: Cannot mount NAS MonPC at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:06:52 volumio volumio[5137]: info: Cannot mount NAS Serveur at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:06:52 volumio volumio[5137]: info: Starting Udev Watcher for removable devices Jan 28 10:06:52 volumio volumio[5137]: info: Ignoring mount for partition: boot Jan 28 10:06:52 volumio volumio[5137]: info: Ignoring mount for partition: volumio Jan 28 10:06:52 volumio volumio[5137]: info: Ignoring mount for partition: volumio_data Jan 28 10:06:52 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "volumio_command_line_client"... Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "upnp"... Jan 28 10:06:52 volumio volumio[5137]: info: [1769591212792] Starting Upmpd Daemon Jan 28 10:06:52 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "my_music"... Jan 28 10:06:52 volumio volumio[5137]: info: Loading plugin "mpd"... Jan 28 10:06:53 volumio volumio[5137]: info: Loading plugin "upnp_browser"... Jan 28 10:06:53 volumio volumio[5137]: info: Starting UPNP Browser Jan 28 10:06:53 volumio volumio[5137]: info: Loading plugin "alarm-clock"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "airplay_emulation"... Jan 28 10:06:54 volumio volumio[5137]: info: Starting Shairport Sync Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "last_100"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "webradio"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "i2s_dacs"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "volumiodiscovery"... Jan 28 10:06:54 volumio volumio[5137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:06:54 volumio volumio[5137]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:06:54 volumio node[5137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:06:54 volumio volumio[5137]: *** WARNING *** For more information see Jan 28 10:06:54 volumio volumio[5137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:06:54 volumio volumio[5137]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:06:54 volumio volumio[5137]: *** WARNING *** For more information see Jan 28 10:06:54 volumio node[5137]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:06:54 volumio node[5137]: *** WARNING *** For more information see Jan 28 10:06:54 volumio node[5137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:06:54 volumio node[5137]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:06:54 volumio node[5137]: *** WARNING *** For more information see Jan 28 10:06:54 volumio volumio[5137]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 10:06:54 volumio volumio[5137]: info: Discovery: Started advertising with name: Volumio Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "outputs"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "albumart"... Jan 28 10:06:54 volumio volumio[5137]: info: Plugin example_plugin is not enabled Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "inputs"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "updater_comm"... Jan 28 10:06:54 volumio volumio[5137]: info: Plugin mpdemulation is not enabled Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "rest_api"... Jan 28 10:06:54 volumio volumio[5137]: info: Loading plugin "websocket"... Jan 28 10:06:54 volumio volumio[5137]: info: Starting Socket.io Server version 1.7.4 Jan 28 10:06:54 volumio volumio[5137]: info: Loading i18n strings for locale fr Jan 28 10:06:54 volumio volumio[5137]: Updating browse sources language Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:06:54 volumio volumio-remote-updater[704]: [2026-01-28 10:06:54] [connect] Successful connection Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::initPlayerControls Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: Express server listening on port 3000 Jan 28 10:06:54 volumio volumio[5137]: [Metrics] WebUI: 3s 907.09ms Jan 28 10:06:54 volumio volumio[5195]: Forking 3 albumart workers Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::resetVolumioState Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::getcurrentVolume Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::pushState Jan 28 10:06:54 volumio volumio[5137]: info: CorePlayQueue::getTrack 0 Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::volumioPushState Jan 28 10:06:54 volumio volumio[5137]: info: Volumio Network Manager: Network status updated: 1 Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:54 volumio volumio[5137]: info: Reloading queue from file Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::setRepeat true single undefined Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::pushState Jan 28 10:06:54 volumio volumio[5137]: info: CorePlayQueue::getTrack 0 Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::volumioPushState Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::setRandom false Jan 28 10:06:54 volumio volumio[5137]: info: CoreStateMachine::pushState Jan 28 10:06:54 volumio volumio[5137]: info: CorePlayQueue::getTrack 0 Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:06:54 volumio volumio[5137]: info: CoreCommandRouter::volumioPushState Jan 28 10:06:54 volumio volumio[5137]: info: Setting Device type: Raspberry PI Jan 28 10:06:54 volumio volumio[5137]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 28 10:06:54 volumio volumio[5137]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jan 28 10:06:54 volumio volumio[5137]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 28 10:06:54 volumio volumio[5137]: info: Completed loading Core Plugins Jan 28 10:06:54 volumio volumio[5137]: info: Preparing to generate the ALSA configuration file Jan 28 10:06:55 volumio volumio[5137]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.217 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 28 10:06:55 volumio volumio[5137]: info: Asound.conf file unchanged, so no further update is needed Jan 28 10:06:55 volumio volumio[5137]: info: Output device has changed, restarting MPD Jan 28 10:06:55 volumio volumio[5137]: info: Output device has changed, restarting Shairport Sync Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:55 volumio sudo[5246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:06:55 volumio sudo[5246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:55 volumio sudo[5246]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:55 volumio sudo[5248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:06:55 volumio sudo[5248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:55 volumio volumio[5137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:06:55 volumio volumio[5137]: info: ___________ START PLUGINS ___________ Jan 28 10:06:55 volumio volumio[5137]: info: ControllerMpd::onStart: Initializing MPD Jan 28 10:06:55 volumio volumio[5137]: info: Creating MPD Configuration file Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:06:55 volumio volumio[5137]: info: [1769591215108] CoreMusicLibrary::Adding element Serveurs Média Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:06:55 volumio volumio[5137]: info: UPNP Browser: Client initialized successfully Jan 28 10:06:55 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:55 volumio sudo[5256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:06:55 volumio sudo[5256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:55 volumio sudo[5258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:06:55 volumio sudo[5258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:55 volumio sudo[5256]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:55 volumio volumio[5137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:06:55 volumio volumio[5137]: info: [1769591215220] CoreMusicLibrary::Adding element Last_100 Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:06:55 volumio volumio[5137]: info: [1769591215223] CoreMusicLibrary::Adding element Webradio Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:06:55 volumio volumio[5137]: info: Initializing BBC Radios Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: Volumio Calling Home Jan 28 10:06:55 volumio volumio[5210]: Starting albumart workers Jan 28 10:06:55 volumio volumio[5209]: Starting albumart workers Jan 28 10:06:55 volumio volumio[5206]: Starting albumart workers Jan 28 10:06:55 volumio volumio[5137]: info: MPD Permissions set Jan 28 10:06:55 volumio volumio[5137]: info: MPD Permissions set Jan 28 10:06:55 volumio volumio[5137]: info: Volumio called home Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:06:55 volumio volumio[5137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:06:56 volumio volumio-remote-updater[704]: [2026-01-28 10:06:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769591214 101 Jan 28 10:06:56 volumio volumio[5137]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Jan 28 10:06:56 volumio volumio[5137]: info: Discovery: adding 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:06:56 volumio volumio[5137]: info: Discovery: Found device Volumio Jan 28 10:06:56 volumio volumio[5137]: info: CoreCommandRouter::volumioGetState Jan 28 10:06:56 volumio volumio[5137]: info: CorePlayQueue::getTrack 0 Jan 28 10:06:56 volumio volumio[5137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 28 10:06:56 volumio volumio[5137]: info: Starting Shairport Sync Jan 28 10:06:56 volumio volumio[5137]: info: Starting Shairport Sync Jan 28 10:06:56 volumio volumio[5137]: info: Starting Shairport Sync Jan 28 10:06:56 volumio sudo[5320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:06:56 volumio sudo[5320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:56 volumio sudo[5322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:06:56 volumio volumio[5137]: info: Discovery: this is already registered, 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:06:56 volumio sudo[5322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:56 volumio volumio[5137]: info: Discovery: Found device Volumio Jan 28 10:06:56 volumio volumio[5137]: info: CoreCommandRouter::volumioGetState Jan 28 10:06:56 volumio volumio[5137]: info: CorePlayQueue::getTrack 0 Jan 28 10:06:56 volumio sudo[5325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:06:56 volumio sudo[5325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:06:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:06:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:06:56 volumio systemd[1]: shairport-sync.service: Consumed 12min 32.914s CPU time. Jan 28 10:06:56 volumio volumio[5137]: info: CoreCommandRouter::volumioGetState Jan 28 10:06:56 volumio volumio[5137]: info: CorePlayQueue::getTrack 0 Jan 28 10:06:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:06:56 volumio sudo[5322]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:56 volumio sudo[5320]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:56 volumio volumio[5137]: info: Shairport-Sync Started Jan 28 10:06:56 volumio volumio[5137]: Error adding Membership: Error: addMembership EINVAL Jan 28 10:06:56 volumio volumio[5137]: info: Shairport-Sync Started Jan 28 10:06:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:06:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:06:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:06:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:06:56 volumio sudo[5325]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:56 volumio volumio[5137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:06:56 volumio volumio[5137]: AggregateError Jan 28 10:06:56 volumio volumio[5137]: at internalConnectMultiple (node:net:1114:18) Jan 28 10:06:56 volumio volumio[5137]: at afterConnectMultiple (node:net:1667:5) { Jan 28 10:06:56 volumio volumio[5137]: code: 'ECONNREFUSED', Jan 28 10:06:56 volumio volumio[5137]: [errors]: [ Jan 28 10:06:56 volumio volumio[5137]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 10:06:56 volumio volumio[5137]: at createConnectionError (node:net:1634:14) Jan 28 10:06:56 volumio volumio[5137]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:06:56 volumio volumio[5137]: errno: -111, Jan 28 10:06:56 volumio volumio[5137]: code: 'ECONNREFUSED', Jan 28 10:06:56 volumio volumio[5137]: syscall: 'connect', Jan 28 10:06:56 volumio volumio[5137]: address: '158.69.210.98', Jan 28 10:06:56 volumio volumio[5137]: port: 443 Jan 28 10:06:56 volumio volumio[5137]: }, Jan 28 10:06:56 volumio volumio[5137]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 10:06:56 volumio volumio[5137]: at createConnectionError (node:net:1634:14) Jan 28 10:06:56 volumio volumio[5137]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:06:56 volumio volumio[5137]: errno: -111, Jan 28 10:06:56 volumio volumio[5137]: code: 'ECONNREFUSED', Jan 28 10:06:56 volumio volumio[5137]: syscall: 'connect', Jan 28 10:06:56 volumio volumio[5137]: address: '158.69.209.125', Jan 28 10:06:56 volumio volumio[5137]: port: 443 Jan 28 10:06:56 volumio volumio[5137]: } Jan 28 10:06:56 volumio volumio[5137]: ] Jan 28 10:06:56 volumio volumio[5137]: } Jan 28 10:06:56 volumio volumio[5137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:06:56 volumio sudo[5359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:05' Jan 28 10:06:56 volumio sudo[5359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:56 volumio sudo[5359]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:56 volumio volumio-remote-updater[704]: [2026-01-28 10:06:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 28 10:06:56 volumio volumio-remote-updater[704]: [2026-01-28 10:06:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 28 10:06:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:06:56 volumio sudo[5258]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:56 volumio sudo[5248]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 10:06:56 volumio systemd[1]: volumio.service: Consumed 12.540s CPU time. Jan 28 10:06:57 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:06:57 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:06:57 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:06:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:06:57 volumio systemd[1]: mpd.service: Consumed 4min 14.546s CPU time. Jan 28 10:06:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:06:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:06:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:06:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:06:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:06:57 volumio sudo[5381]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 10:06:57 volumio sudo[5381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 10:06:57 volumio sudo[5381]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jan 28 10:06:57 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:06:57 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 28 10:06:57 volumio systemd[1]: volumio.service: Consumed 12.540s CPU time. Jan 28 10:06:57 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 28 10:06:57 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:06:58 volumio volumio[5385]: info: ------------------------------------------- Jan 28 10:06:58 volumio volumio[5385]: info: ----- Volumio3 ---- Jan 28 10:06:58 volumio volumio[5385]: info: ------------------------------------------- Jan 28 10:06:58 volumio volumio[5385]: info: ----- System startup ---- Jan 28 10:06:58 volumio volumio[5385]: info: ------------------------------------------- Jan 28 10:06:59 volumio volumio[5385]: info: MYVOLUMIO Environment detected Jan 28 10:06:59 volumio volumio[5385]: info: Plugin folders cleanup Jan 28 10:06:59 volumio volumio[5385]: info: Scanning into folder /volumio/app/plugins/ Jan 28 10:06:59 volumio volumio[5385]: info: Scanning category audio_interface Jan 28 10:06:59 volumio volumio[5385]: info: Scanning category miscellanea Jan 28 10:06:59 volumio volumio[5385]: info: Scanning category music_service Jan 28 10:06:59 volumio volumio[5385]: info: Scanning category plugins.json Jan 28 10:06:59 volumio volumio[5385]: info: Scanning category system_controller Jan 28 10:06:59 volumio volumio[5385]: info: Scanning category user_interface Jan 28 10:06:59 volumio volumio[5385]: info: Scanning into folder /data/plugins/ Jan 28 10:06:59 volumio volumio[5385]: info: Plugin folders cleanup completed Jan 28 10:06:59 volumio volumio[5385]: info: ------------------------------------------- Jan 28 10:06:59 volumio volumio[5385]: info: ----- Core plugins startup ---- Jan 28 10:06:59 volumio volumio[5385]: info: ------------------------------------------- Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 10:06:59 volumio volumio[5385]: info: Adding plugin upnp to MyMusic Plugins Jan 28 10:06:59 volumio volumio[5385]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 10:06:59 volumio volumio[5385]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugins from folder /data/plugins/ Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "system"... Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "appearance"... Jan 28 10:06:59 volumio mpd[5383]: 2026-01-28T10:06:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 10:06:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "network"... Jan 28 10:06:59 volumio volumio[5385]: info: Refreshing Cached IP Addresses Jan 28 10:06:59 volumio sudo[5416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 10:06:59 volumio sudo[5416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "services"... Jan 28 10:06:59 volumio sudo[5418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 10:06:59 volumio sudo[5418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "alsa_controller"... Jan 28 10:06:59 volumio sudo[5416]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:59 volumio sudo[5418]: pam_unix(sudo:session): session closed for user root Jan 28 10:06:59 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "wizard"... Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "networkfs"... Jan 28 10:06:59 volumio volumio[5385]: info: Cannot mount NAS MonPC at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:06:59 volumio volumio[5385]: info: Cannot mount NAS Serveur at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:06:59 volumio volumio[5385]: info: Starting Udev Watcher for removable devices Jan 28 10:06:59 volumio volumio[5385]: info: Ignoring mount for partition: boot Jan 28 10:06:59 volumio volumio[5385]: info: Ignoring mount for partition: volumio Jan 28 10:06:59 volumio volumio[5385]: info: Ignoring mount for partition: volumio_data Jan 28 10:06:59 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "volumio_command_line_client"... Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "upnp"... Jan 28 10:06:59 volumio volumio[5385]: info: [1769591219898] Starting Upmpd Daemon Jan 28 10:06:59 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "my_music"... Jan 28 10:06:59 volumio volumio[5385]: info: Loading plugin "mpd"... Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "upnp_browser"... Jan 28 10:07:00 volumio volumio[5385]: info: Starting UPNP Browser Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "alarm-clock"... Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "airplay_emulation"... Jan 28 10:07:00 volumio volumio[5385]: info: Starting Shairport Sync Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "last_100"... Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "webradio"... Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "i2s_dacs"... Jan 28 10:07:00 volumio volumio[5385]: info: Loading plugin "volumiodiscovery"... Jan 28 10:07:01 volumio volumio[5385]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:01 volumio volumio[5385]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:01 volumio volumio[5385]: *** WARNING *** For more information see Jan 28 10:07:01 volumio volumio[5385]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:01 volumio volumio[5385]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:01 volumio volumio[5385]: *** WARNING *** For more information see Jan 28 10:07:01 volumio node[5385]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:01 volumio node[5385]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:01 volumio node[5385]: *** WARNING *** For more information see Jan 28 10:07:01 volumio node[5385]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:01 volumio node[5385]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:01 volumio node[5385]: *** WARNING *** For more information see Jan 28 10:07:01 volumio volumio[5385]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 10:07:01 volumio volumio[5385]: info: Discovery: Started advertising with name: Volumio Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:01 volumio volumio[5385]: info: Loading plugin "outputs"... Jan 28 10:07:01 volumio volumio[5385]: info: Loading plugin "albumart"... Jan 28 10:07:01 volumio volumio[5385]: info: Plugin example_plugin is not enabled Jan 28 10:07:01 volumio volumio[5385]: info: Loading plugin "inputs"... Jan 28 10:07:01 volumio volumio[5385]: info: Loading plugin "updater_comm"... Jan 28 10:07:01 volumio volumio[5385]: info: Plugin mpdemulation is not enabled Jan 28 10:07:01 volumio volumio[5385]: info: Loading plugin "rest_api"... Jan 28 10:07:01 volumio volumio[5385]: info: Loading plugin "websocket"... Jan 28 10:07:01 volumio volumio[5385]: info: Starting Socket.io Server version 1.7.4 Jan 28 10:07:01 volumio volumio[5385]: info: Loading i18n strings for locale fr Jan 28 10:07:01 volumio volumio[5385]: Updating browse sources language Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::initPlayerControls Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: Express server listening on port 3000 Jan 28 10:07:01 volumio volumio[5385]: [Metrics] WebUI: 3s 12.65ms Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::resetVolumioState Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::getcurrentVolume Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::pushState Jan 28 10:07:01 volumio volumio[5385]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:01 volumio volumio[5385]: info: Volumio Network Manager: Network status updated: 1 Jan 28 10:07:01 volumio volumio[5385]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.217 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 28 10:07:01 volumio volumio[5445]: Forking 3 albumart workers Jan 28 10:07:01 volumio volumio[5385]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.217 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 2 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: Reloading queue from file Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::setRepeat true single undefined Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::pushState Jan 28 10:07:01 volumio volumio[5385]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::setRandom false Jan 28 10:07:01 volumio volumio[5385]: info: CoreStateMachine::pushState Jan 28 10:07:01 volumio volumio[5385]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:01 volumio volumio[5385]: info: Setting Device type: Raspberry PI Jan 28 10:07:01 volumio volumio[5385]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 28 10:07:01 volumio volumio[5385]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jan 28 10:07:01 volumio volumio[5385]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 28 10:07:01 volumio volumio[5385]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/JUICE%20NEWTON/f911b358-8474-4c98-8979-61b029df875c.jpg' Jan 28 10:07:01 volumio volumio[5385]: info: Completed loading Core Plugins Jan 28 10:07:01 volumio volumio[5385]: info: Preparing to generate the ALSA configuration file Jan 28 10:07:01 volumio volumio[5385]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Julie%20London/c0e68e69-5fd4-48e1-bad6-627dccc2d855.jpg' Jan 28 10:07:01 volumio volumio[5385]: info: Asound.conf file unchanged, so no further update is needed Jan 28 10:07:01 volumio volumio[5385]: info: Output device has changed, restarting MPD Jan 28 10:07:01 volumio volumio[5385]: info: Output device has changed, restarting Shairport Sync Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:01 volumio sudo[5495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:07:01 volumio sudo[5497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:07:01 volumio sudo[5495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:01 volumio sudo[5497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:01 volumio volumio[5385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:01 volumio volumio[5385]: info: ___________ START PLUGINS ___________ Jan 28 10:07:01 volumio sudo[5495]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:01 volumio volumio[5385]: info: ControllerMpd::onStart: Initializing MPD Jan 28 10:07:01 volumio volumio[5385]: info: Creating MPD Configuration file Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:01 volumio volumio[5385]: info: [1769591221782] CoreMusicLibrary::Adding element Serveurs Média Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:01 volumio sudo[5505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:07:01 volumio sudo[5505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:01 volumio sudo[5507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:07:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 10:07:01 volumio volumio[5385]: info: UPNP Browser: Client initialized successfully Jan 28 10:07:01 volumio sudo[5505]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio sudo[5507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:07:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:07:01 volumio systemd[1]: mpd.service: Consumed 2.249s CPU time. Jan 28 10:07:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:07:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:07:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:07:01 volumio volumio[5385]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:01 volumio volumio[5385]: info: [1769591221906] CoreMusicLibrary::Adding element Last_100 Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:01 volumio volumio[5385]: info: [1769591221912] CoreMusicLibrary::Adding element Webradio Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:07:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:07:01 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:01 volumio volumio[5385]: info: Initializing BBC Radios Jan 28 10:07:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:07:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:07:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:07:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:07:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:07:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:07:01 volumio volumio-remote-updater[704]: [2026-01-28 10:07:01] [connect] Successful connection Jan 28 10:07:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: Volumio Calling Home Jan 28 10:07:02 volumio sudo[5525]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 10:07:02 volumio sudo[5525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 10:07:02 volumio sudo[5525]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:02 volumio volumio[5460]: Starting albumart workers Jan 28 10:07:02 volumio volumio[5459]: Starting albumart workers Jan 28 10:07:02 volumio volumio[5385]: info: MPD Permissions set Jan 28 10:07:02 volumio volumio[5385]: info: MPD Permissions set Jan 28 10:07:02 volumio volumio-remote-updater[704]: [2026-01-28 10:07:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769591221 101 Jan 28 10:07:02 volumio volumio[5385]: 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: 3 Jan 28 10:07:02 volumio volumio[5385]: info: Volumio called home Jan 28 10:07:02 volumio volumio[5385]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5461]: Starting albumart workers Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:02 volumio volumio[5385]: info: Discovery: adding 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:07:02 volumio volumio[5385]: info: Discovery: Found device Volumio Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:02 volumio volumio[5385]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:02 volumio volumio[5385]: info: Discovery: this is already registered, 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:07:02 volumio volumio[5385]: info: Discovery: Found device Volumio Jan 28 10:07:02 volumio volumio[5385]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:02 volumio volumio[5385]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:02 volumio volumio[5385]: info: Starting Shairport Sync Jan 28 10:07:02 volumio volumio[5385]: info: Starting Shairport Sync Jan 28 10:07:03 volumio sudo[5559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:03 volumio sudo[5559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:03 volumio volumio[5385]: info: Starting Shairport Sync Jan 28 10:07:03 volumio sudo[5564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:03 volumio sudo[5564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:07:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:07:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:03 volumio systemd[1]: shairport-sync.service: Consumed 1.637s CPU time. Jan 28 10:07:03 volumio sudo[5568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:03 volumio sudo[5568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:03 volumio sudo[5559]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:07:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:07:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:03 volumio sudo[5564]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:07:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:07:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:03 volumio volumio[5385]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.217 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 28 10:07:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:03 volumio sudo[5568]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:03 volumio volumio[5385]: info: Shairport-Sync Started Jan 28 10:07:03 volumio volumio[5385]: Error adding Membership: Error: addMembership EINVAL Jan 28 10:07:03 volumio volumio[5385]: info: Shairport-Sync Started Jan 28 10:07:03 volumio volumio[5385]: info: Shairport-Sync Started Jan 28 10:07:03 volumio volumio[5385]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:03 volumio volumio[5385]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:03 volumio volumio[5385]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:07:03 volumio volumio[5385]: AggregateError Jan 28 10:07:03 volumio volumio[5385]: at internalConnectMultiple (node:net:1114:18) Jan 28 10:07:03 volumio volumio[5385]: at afterConnectMultiple (node:net:1667:5) { Jan 28 10:07:03 volumio volumio[5385]: code: 'ECONNREFUSED', Jan 28 10:07:03 volumio volumio[5385]: [errors]: [ Jan 28 10:07:03 volumio volumio[5385]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 10:07:03 volumio volumio[5385]: at createConnectionError (node:net:1634:14) Jan 28 10:07:03 volumio volumio[5385]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:07:03 volumio volumio[5385]: errno: -111, Jan 28 10:07:03 volumio volumio[5385]: code: 'ECONNREFUSED', Jan 28 10:07:03 volumio volumio[5385]: syscall: 'connect', Jan 28 10:07:03 volumio volumio[5385]: address: '158.69.210.98', Jan 28 10:07:03 volumio volumio[5385]: port: 443 Jan 28 10:07:03 volumio volumio[5385]: }, Jan 28 10:07:03 volumio volumio[5385]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 10:07:03 volumio volumio[5385]: at createConnectionError (node:net:1634:14) Jan 28 10:07:03 volumio volumio[5385]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:07:03 volumio volumio[5385]: errno: -111, Jan 28 10:07:03 volumio volumio[5385]: code: 'ECONNREFUSED', Jan 28 10:07:03 volumio volumio[5385]: syscall: 'connect', Jan 28 10:07:03 volumio volumio[5385]: address: '158.69.209.125', Jan 28 10:07:03 volumio volumio[5385]: port: 443 Jan 28 10:07:03 volumio volumio[5385]: } Jan 28 10:07:03 volumio volumio[5385]: ] Jan 28 10:07:03 volumio volumio[5385]: } Jan 28 10:07:03 volumio volumio[5385]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:07:03 volumio sudo[5611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:06' Jan 28 10:07:03 volumio sudo[5611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:03 volumio sudo[5611]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:04 volumio volumio-remote-updater[704]: [2026-01-28 10:07:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 28 10:07:04 volumio volumio-remote-updater[704]: [2026-01-28 10:07:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 28 10:07:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:07:04 volumio sudo[5497]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:04 volumio sudo[5507]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 10:07:04 volumio systemd[1]: volumio.service: Consumed 12.460s CPU time. Jan 28 10:07:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:07:04 volumio mpd[5539]: 2026-01-28T10:07:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 10:07:04 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:07:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jan 28 10:07:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 10:07:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:07:04 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 28 10:07:04 volumio systemd[1]: volumio.service: Consumed 12.460s CPU time. Jan 28 10:07:04 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 28 10:07:04 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:07:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 28 10:07:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 10:07:04 volumio systemd[1]: upmpdcli.service: Consumed 49.527s CPU time. Jan 28 10:07:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 10:07:05 volumio volumio[5643]: info: ------------------------------------------- Jan 28 10:07:05 volumio volumio[5643]: info: ----- Volumio3 ---- Jan 28 10:07:05 volumio volumio[5643]: info: ------------------------------------------- Jan 28 10:07:05 volumio volumio[5643]: info: ----- System startup ---- Jan 28 10:07:05 volumio volumio[5643]: info: ------------------------------------------- Jan 28 10:07:05 volumio volumio[5643]: info: MYVOLUMIO Environment detected Jan 28 10:07:05 volumio volumio[5643]: info: Plugin folders cleanup Jan 28 10:07:05 volumio volumio[5643]: info: Scanning into folder /volumio/app/plugins/ Jan 28 10:07:05 volumio volumio[5643]: info: Scanning category audio_interface Jan 28 10:07:05 volumio volumio[5643]: info: Scanning category miscellanea Jan 28 10:07:05 volumio volumio[5643]: info: Scanning category music_service Jan 28 10:07:05 volumio volumio[5643]: info: Scanning category plugins.json Jan 28 10:07:05 volumio volumio[5643]: info: Scanning category system_controller Jan 28 10:07:05 volumio volumio[5643]: info: Scanning category user_interface Jan 28 10:07:05 volumio volumio[5643]: info: Scanning into folder /data/plugins/ Jan 28 10:07:05 volumio volumio[5643]: info: Plugin folders cleanup completed Jan 28 10:07:05 volumio volumio[5643]: info: ------------------------------------------- Jan 28 10:07:05 volumio volumio[5643]: info: ----- Core plugins startup ---- Jan 28 10:07:05 volumio volumio[5643]: info: ------------------------------------------- Jan 28 10:07:05 volumio volumio[5643]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 10:07:05 volumio volumio[5643]: info: Adding plugin upnp to MyMusic Plugins Jan 28 10:07:05 volumio volumio[5643]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 10:07:05 volumio volumio[5643]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 10:07:05 volumio volumio[5643]: info: Loading plugins from folder /data/plugins/ Jan 28 10:07:05 volumio volumio[5643]: info: Loading plugin "system"... Jan 28 10:07:05 volumio volumio[5643]: info: Loading plugin "appearance"... Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "network"... Jan 28 10:07:06 volumio volumio[5643]: info: Refreshing Cached IP Addresses Jan 28 10:07:06 volumio sudo[5682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "services"... Jan 28 10:07:06 volumio sudo[5682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:06 volumio sudo[5684]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 10:07:06 volumio sudo[5684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "alsa_controller"... Jan 28 10:07:06 volumio sudo[5682]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:06 volumio sudo[5684]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:06 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "wizard"... Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "networkfs"... Jan 28 10:07:06 volumio volumio[5643]: info: Cannot mount NAS MonPC at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:07:06 volumio volumio[5643]: info: Cannot mount NAS Serveur at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:07:06 volumio volumio[5643]: info: Starting Udev Watcher for removable devices Jan 28 10:07:06 volumio volumio[5643]: info: Ignoring mount for partition: boot Jan 28 10:07:06 volumio volumio[5643]: info: Ignoring mount for partition: volumio Jan 28 10:07:06 volumio volumio[5643]: info: Ignoring mount for partition: volumio_data Jan 28 10:07:06 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "volumio_command_line_client"... Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "upnp"... Jan 28 10:07:06 volumio volumio[5643]: info: [1769591226636] Starting Upmpd Daemon Jan 28 10:07:06 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "my_music"... Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "mpd"... Jan 28 10:07:06 volumio volumio[5643]: info: Loading plugin "upnp_browser"... Jan 28 10:07:07 volumio volumio[5643]: info: Starting UPNP Browser Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "alarm-clock"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "airplay_emulation"... Jan 28 10:07:07 volumio volumio[5643]: info: Starting Shairport Sync Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "last_100"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "webradio"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "i2s_dacs"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "volumiodiscovery"... Jan 28 10:07:07 volumio volumio[5643]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:07 volumio volumio[5643]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:07 volumio volumio[5643]: *** WARNING *** For more information see Jan 28 10:07:07 volumio volumio[5643]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:07 volumio volumio[5643]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:07 volumio node[5643]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:07 volumio volumio[5643]: *** WARNING *** For more information see Jan 28 10:07:07 volumio node[5643]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:07 volumio node[5643]: *** WARNING *** For more information see Jan 28 10:07:07 volumio node[5643]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:07 volumio node[5643]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:07 volumio node[5643]: *** WARNING *** For more information see Jan 28 10:07:07 volumio volumio[5643]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 10:07:07 volumio volumio[5643]: info: Discovery: Started advertising with name: Volumio Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "outputs"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "albumart"... Jan 28 10:07:07 volumio volumio[5643]: info: Plugin example_plugin is not enabled Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "inputs"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "updater_comm"... Jan 28 10:07:07 volumio volumio[5643]: info: Plugin mpdemulation is not enabled Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "rest_api"... Jan 28 10:07:07 volumio volumio[5643]: info: Loading plugin "websocket"... Jan 28 10:07:07 volumio volumio[5643]: info: Starting Socket.io Server version 1.7.4 Jan 28 10:07:07 volumio volumio[5643]: info: Loading i18n strings for locale fr Jan 28 10:07:07 volumio volumio[5643]: Updating browse sources language Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::initPlayerControls Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:07 volumio volumio[5643]: Express server listening on port 3000 Jan 28 10:07:07 volumio volumio[5643]: [Metrics] WebUI: 2s 820.36ms Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::resetVolumioState Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::getcurrentVolume Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::pushState Jan 28 10:07:08 volumio volumio[5643]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:08 volumio volumio[5643]: info: Volumio Network Manager: Network status updated: 1 Jan 28 10:07:08 volumio volumio[5711]: Forking 3 albumart workers Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:08 volumio volumio[5643]: info: Reloading queue from file Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::setRepeat true single undefined Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::pushState Jan 28 10:07:08 volumio volumio[5643]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::setRandom false Jan 28 10:07:08 volumio volumio[5643]: info: CoreStateMachine::pushState Jan 28 10:07:08 volumio volumio[5643]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:08 volumio volumio[5643]: info: Setting Device type: Raspberry PI Jan 28 10:07:08 volumio volumio[5643]: info: Completed loading Core Plugins Jan 28 10:07:08 volumio volumio[5643]: info: Preparing to generate the ALSA configuration file Jan 28 10:07:08 volumio volumio[5643]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 28 10:07:08 volumio volumio[5643]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jan 28 10:07:08 volumio volumio[5643]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 28 10:07:08 volumio volumio[5643]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Justice/dbb01613-624a-4339-9a99-4edfc06f7248.jpg' Jan 28 10:07:08 volumio volumio[5643]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Patrick%20Juvet/13eeac5b-4a16-405c-8368-df8a45a3e73a.jpg' Jan 28 10:07:08 volumio volumio[5643]: info: Asound.conf file unchanged, so no further update is needed Jan 28 10:07:08 volumio volumio[5643]: info: Output device has changed, restarting MPD Jan 28 10:07:08 volumio volumio[5643]: info: Output device has changed, restarting Shairport Sync Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:08 volumio upmpdcli[5762]: writing RSA key Jan 28 10:07:08 volumio sudo[5764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:07:08 volumio sudo[5764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:08 volumio volumio[5643]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:08 volumio volumio[5643]: info: ___________ START PLUGINS ___________ Jan 28 10:07:08 volumio sudo[5766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:07:08 volumio sudo[5766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:08 volumio sudo[5764]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:08 volumio volumio[5643]: info: ControllerMpd::onStart: Initializing MPD Jan 28 10:07:08 volumio volumio[5643]: info: Creating MPD Configuration file Jan 28 10:07:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:08 volumio sudo[5776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:07:08 volumio sudo[5776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:08 volumio sudo[5776]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:08 volumio volumio[5643]: info: [1769591228583] CoreMusicLibrary::Adding element Serveurs Média Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:08 volumio sudo[5778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:07:08 volumio sudo[5778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:08 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:07:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:07:08 volumio systemd[1]: mpd.service: Consumed 2.183s CPU time. Jan 28 10:07:08 volumio volumio[5643]: info: UPNP Browser: Client initialized successfully Jan 28 10:07:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:07:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:07:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:08 volumio volumio[5643]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:07:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:07:08 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:07:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:07:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:07:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:07:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:07:08 volumio volumio[5643]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:08 volumio volumio[5643]: info: [1769591228734] CoreMusicLibrary::Adding element Last_100 Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:08 volumio volumio[5643]: info: [1769591228741] CoreMusicLibrary::Adding element Webradio Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:07:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:08 volumio volumio[5643]: info: Initializing BBC Radios Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:08 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:08 volumio volumio[5643]: info: Volumio Calling Home Jan 28 10:07:08 volumio sudo[5793]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 10:07:08 volumio sudo[5793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 10:07:08 volumio sudo[5793]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:09 volumio volumio-remote-updater[704]: [2026-01-28 10:07:09] [connect] Successful connection Jan 28 10:07:09 volumio volumio[5726]: Starting albumart workers Jan 28 10:07:09 volumio volumio[5727]: Starting albumart workers Jan 28 10:07:09 volumio volumio[5643]: info: MPD Permissions set Jan 28 10:07:09 volumio volumio[5643]: info: MPD Permissions set Jan 28 10:07:09 volumio volumio[5725]: Starting albumart workers Jan 28 10:07:09 volumio volumio-remote-updater[704]: [2026-01-28 10:07:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769591229 101 Jan 28 10:07:09 volumio volumio[5643]: 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: 1 Jan 28 10:07:09 volumio volumio[5643]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 28 10:07:09 volumio volumio[5643]: info: Volumio called home Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:09 volumio volumio[5643]: info: Discovery: adding 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:07:09 volumio volumio[5643]: info: Discovery: Found device Volumio Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:09 volumio volumio[5643]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:09 volumio volumio[5643]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.217 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 28 10:07:09 volumio volumio[5643]: info: Starting Shairport Sync Jan 28 10:07:09 volumio volumio[5643]: info: Starting Shairport Sync Jan 28 10:07:09 volumio volumio[5643]: info: Starting Shairport Sync Jan 28 10:07:09 volumio volumio[5643]: info: Discovery: this is already registered, 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:07:09 volumio volumio[5643]: info: Discovery: Found device Volumio Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:09 volumio sudo[5837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:09 volumio volumio[5643]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:09 volumio sudo[5837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:09 volumio sudo[5840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:09 volumio sudo[5840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:09 volumio volumio[5643]: Searching plugin music_service/mpd Jan 28 10:07:09 volumio volumio[5643]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 10:07:09 volumio sudo[5841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:09 volumio sudo[5841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:09 volumio volumio[5643]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:07:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:07:09 volumio volumio[5643]: TypeError: Cannot read properties of undefined (reading 'then') Jan 28 10:07:09 volumio volumio[5643]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Jan 28 10:07:09 volumio volumio[5643]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jan 28 10:07:09 volumio volumio[5643]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) Jan 28 10:07:09 volumio volumio[5643]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Jan 28 10:07:09 volumio volumio[5643]: at Socket.emit (node:events:514:28) Jan 28 10:07:09 volumio volumio[5643]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 28 10:07:09 volumio volumio[5643]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 28 10:07:09 volumio volumio[5643]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:07:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:07:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:10 volumio systemd[1]: shairport-sync.service: Consumed 1.601s CPU time. Jan 28 10:07:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:10 volumio sudo[5840]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:10 volumio sudo[5837]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:07:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:07:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:10 volumio sudo[5841]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:10 volumio sudo[5881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:06' Jan 28 10:07:10 volumio sudo[5881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:10 volumio sudo[5881]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:10 volumio mpd[5810]: 2026-01-28T10:07:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 10:07:10 volumio volumio-remote-updater[704]: [2026-01-28 10:07:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 28 10:07:10 volumio volumio-remote-updater[704]: [2026-01-28 10:07:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 28 10:07:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:07:10 volumio sudo[5766]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:10 volumio sudo[5778]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 10:07:10 volumio systemd[1]: volumio.service: Consumed 12.063s CPU time. Jan 28 10:07:10 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:07:10 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:07:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jan 28 10:07:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 10:07:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 10:07:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 28 10:07:11 volumio systemd[1]: volumio.service: Consumed 12.063s CPU time. Jan 28 10:07:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 28 10:07:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 10:07:12 volumio volumio[5903]: info: ------------------------------------------- Jan 28 10:07:12 volumio volumio[5903]: info: ----- Volumio3 ---- Jan 28 10:07:12 volumio volumio[5903]: info: ------------------------------------------- Jan 28 10:07:12 volumio volumio[5903]: info: ----- System startup ---- Jan 28 10:07:12 volumio volumio[5903]: info: ------------------------------------------- Jan 28 10:07:12 volumio volumio[5903]: info: MYVOLUMIO Environment detected Jan 28 10:07:12 volumio volumio[5903]: info: Plugin folders cleanup Jan 28 10:07:12 volumio volumio[5903]: info: Scanning into folder /volumio/app/plugins/ Jan 28 10:07:12 volumio volumio[5903]: info: Scanning category audio_interface Jan 28 10:07:12 volumio volumio[5903]: info: Scanning category miscellanea Jan 28 10:07:12 volumio volumio[5903]: info: Scanning category music_service Jan 28 10:07:12 volumio volumio[5903]: info: Scanning category plugins.json Jan 28 10:07:12 volumio volumio[5903]: info: Scanning category system_controller Jan 28 10:07:12 volumio volumio[5903]: info: Scanning category user_interface Jan 28 10:07:12 volumio volumio[5903]: info: Scanning into folder /data/plugins/ Jan 28 10:07:12 volumio volumio[5903]: info: Plugin folders cleanup completed Jan 28 10:07:12 volumio volumio[5903]: info: ------------------------------------------- Jan 28 10:07:12 volumio volumio[5903]: info: ----- Core plugins startup ---- Jan 28 10:07:12 volumio volumio[5903]: info: ------------------------------------------- Jan 28 10:07:12 volumio volumio[5903]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 10:07:12 volumio volumio[5903]: info: Adding plugin upnp to MyMusic Plugins Jan 28 10:07:12 volumio volumio[5903]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 10:07:12 volumio volumio[5903]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 10:07:12 volumio volumio[5903]: info: Loading plugins from folder /data/plugins/ Jan 28 10:07:12 volumio volumio[5903]: info: Loading plugin "system"... Jan 28 10:07:12 volumio volumio[5903]: info: Loading plugin "appearance"... Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "network"... Jan 28 10:07:13 volumio volumio[5903]: info: Refreshing Cached IP Addresses Jan 28 10:07:13 volumio sudo[5932]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 10:07:13 volumio sudo[5932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "services"... Jan 28 10:07:13 volumio sudo[5934]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 10:07:13 volumio sudo[5934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "alsa_controller"... Jan 28 10:07:13 volumio sudo[5934]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:13 volumio sudo[5932]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:13 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "wizard"... Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "networkfs"... Jan 28 10:07:13 volumio volumio[5903]: info: Cannot mount NAS MonPC at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:07:13 volumio volumio[5903]: info: Cannot mount NAS Serveur at system boot, trial number 1 ,retrying in 5 seconds Jan 28 10:07:13 volumio volumio[5903]: info: Starting Udev Watcher for removable devices Jan 28 10:07:13 volumio volumio[5903]: info: Ignoring mount for partition: boot Jan 28 10:07:13 volumio volumio[5903]: info: Ignoring mount for partition: volumio Jan 28 10:07:13 volumio volumio[5903]: info: Ignoring mount for partition: volumio_data Jan 28 10:07:13 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "volumio_command_line_client"... Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "upnp"... Jan 28 10:07:13 volumio volumio[5903]: info: [1769591233284] Starting Upmpd Daemon Jan 28 10:07:13 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "my_music"... Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "mpd"... Jan 28 10:07:13 volumio volumio[5903]: info: Loading plugin "upnp_browser"... Jan 28 10:07:14 volumio volumio[5903]: info: Starting UPNP Browser Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "alarm-clock"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "airplay_emulation"... Jan 28 10:07:14 volumio volumio[5903]: info: Starting Shairport Sync Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "last_100"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "webradio"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "i2s_dacs"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "volumiodiscovery"... Jan 28 10:07:14 volumio volumio[5903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:14 volumio volumio[5903]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:14 volumio volumio[5903]: *** WARNING *** For more information see Jan 28 10:07:14 volumio volumio[5903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:14 volumio volumio[5903]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:14 volumio volumio[5903]: *** WARNING *** For more information see Jan 28 10:07:14 volumio node[5903]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:14 volumio node[5903]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:14 volumio node[5903]: *** WARNING *** For more information see Jan 28 10:07:14 volumio node[5903]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 10:07:14 volumio node[5903]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 10:07:14 volumio node[5903]: *** WARNING *** For more information see Jan 28 10:07:14 volumio volumio[5903]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 10:07:14 volumio volumio[5903]: info: Discovery: Started advertising with name: Volumio Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "outputs"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "albumart"... Jan 28 10:07:14 volumio volumio[5903]: info: Plugin example_plugin is not enabled Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "inputs"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "updater_comm"... Jan 28 10:07:14 volumio volumio[5903]: info: Plugin mpdemulation is not enabled Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "rest_api"... Jan 28 10:07:14 volumio volumio[5903]: info: Loading plugin "websocket"... Jan 28 10:07:14 volumio volumio[5903]: info: Starting Socket.io Server version 1.7.4 Jan 28 10:07:14 volumio volumio[5903]: info: Loading i18n strings for locale fr Jan 28 10:07:14 volumio volumio[5903]: Updating browse sources language Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::initPlayerControls Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: Express server listening on port 3000 Jan 28 10:07:14 volumio volumio[5903]: [Metrics] WebUI: 2s 812.21ms Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::resetVolumioState Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::getcurrentVolume Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::pushState Jan 28 10:07:14 volumio volumio[5903]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:14 volumio volumio[5903]: info: Volumio Network Manager: Network status updated: 1 Jan 28 10:07:14 volumio volumio[5961]: Forking 3 albumart workers Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:14 volumio volumio[5903]: info: Reloading queue from file Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::setRepeat true single undefined Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::pushState Jan 28 10:07:14 volumio volumio[5903]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::setRandom false Jan 28 10:07:14 volumio volumio[5903]: info: CoreStateMachine::pushState Jan 28 10:07:14 volumio volumio[5903]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:07:14 volumio volumio[5903]: info: CoreCommandRouter::volumioPushState Jan 28 10:07:14 volumio volumio[5903]: info: Setting Device type: Raspberry PI Jan 28 10:07:14 volumio volumio[5903]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 28 10:07:14 volumio volumio[5903]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jan 28 10:07:14 volumio volumio[5903]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 28 10:07:14 volumio volumio[5903]: info: Completed loading Core Plugins Jan 28 10:07:14 volumio volumio[5903]: info: Preparing to generate the ALSA configuration file Jan 28 10:07:14 volumio volumio[5903]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Justin%20Timberlake/48d2c48f-8acf-4f79-a02e-45a06b43c8bc.jpg' Jan 28 10:07:15 volumio volumio[5903]: info: Asound.conf file unchanged, so no further update is needed Jan 28 10:07:15 volumio volumio[5903]: info: Output device has changed, restarting MPD Jan 28 10:07:15 volumio volumio[5903]: info: Output device has changed, restarting Shairport Sync Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:15 volumio sudo[6013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:07:15 volumio sudo[6013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:15 volumio sudo[6013]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:15 volumio sudo[6014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:07:15 volumio sudo[6014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:15 volumio volumio[5903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:15 volumio volumio[5903]: info: ___________ START PLUGINS ___________ Jan 28 10:07:15 volumio volumio[5903]: info: ControllerMpd::onStart: Initializing MPD Jan 28 10:07:15 volumio volumio[5903]: info: Creating MPD Configuration file Jan 28 10:07:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 10:07:15 volumio sudo[6023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 10:07:15 volumio sudo[6023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:15 volumio sudo[6024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 10:07:15 volumio sudo[6024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 10:07:15 volumio sudo[6023]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:15 volumio volumio[5903]: info: [1769591235263] CoreMusicLibrary::Adding element Serveurs Média Jan 28 10:07:15 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:07:15 volumio systemd[1]: mpd.service: Consumed 2.163s CPU time. Jan 28 10:07:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:07:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:07:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:07:15 volumio volumio[5903]: info: UPNP Browser: Client initialized successfully Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:07:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:07:15 volumio volumio[5903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:15 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 10:07:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 10:07:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 10:07:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 10:07:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 10:07:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 10:07:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 10:07:15 volumio volumio[5903]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:15 volumio volumio[5903]: info: [1769591235396] CoreMusicLibrary::Adding element Last_100 Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 10:07:15 volumio volumio[5903]: info: [1769591235399] CoreMusicLibrary::Adding element Webradio Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:15 volumio volumio[5903]: info: Initializing BBC Radios Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:07:15 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:15 volumio volumio[5903]: info: Volumio Calling Home Jan 28 10:07:15 volumio sudo[6038]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 10:07:15 volumio sudo[6038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 10:07:15 volumio sudo[6038]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:15 volumio volumio[5975]: Starting albumart workers Jan 28 10:07:15 volumio volumio[5977]: Starting albumart workers Jan 28 10:07:15 volumio volumio[5976]: Starting albumart workers Jan 28 10:07:15 volumio volumio-remote-updater[704]: [2026-01-28 10:07:15] [connect] Successful connection Jan 28 10:07:15 volumio volumio[5903]: info: MPD Permissions set Jan 28 10:07:15 volumio volumio[5903]: info: MPD Permissions set Jan 28 10:07:16 volumio volumio[5903]: info: Volumio called home Jan 28 10:07:16 volumio volumio-remote-updater[704]: [2026-01-28 10:07:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769591235 101 Jan 28 10:07:16 volumio volumio[5903]: 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: 1 Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 10:07:16 volumio volumio[5903]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 28 10:07:16 volumio volumio[5903]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Julio%20Iglesias/14d4b802-a361-4a83-b419-b4a1a1eea077.jpg' Jan 28 10:07:16 volumio volumio[5903]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.217 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 28 10:07:16 volumio volumio[5903]: info: Discovery: adding 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:07:16 volumio volumio[5903]: info: Discovery: Found device Volumio Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:16 volumio volumio[5903]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:16 volumio volumio[5903]: info: Starting Shairport Sync Jan 28 10:07:16 volumio volumio[5903]: info: Starting Shairport Sync Jan 28 10:07:16 volumio volumio[5903]: info: Starting Shairport Sync Jan 28 10:07:16 volumio sudo[6089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:16 volumio sudo[6089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:16 volumio sudo[6091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:16 volumio sudo[6091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:16 volumio volumio[5903]: info: Discovery: this is already registered, 4cf85f61-8b88-4374-bc4d-41f91bcfd3c0 Jan 28 10:07:16 volumio volumio[5903]: info: Discovery: Found device Volumio Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:16 volumio volumio[5903]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:16 volumio sudo[6094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 10:07:16 volumio sudo[6094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 10:07:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 10:07:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 10:07:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:16 volumio systemd[1]: shairport-sync.service: Consumed 1.623s CPU time. Jan 28 10:07:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 10:07:16 volumio sudo[6089]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:16 volumio sudo[6094]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:16 volumio sudo[6091]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:16 volumio volumio[5903]: info: Shairport-Sync Started Jan 28 10:07:16 volumio volumio[5903]: Error adding Membership: Error: addMembership EINVAL Jan 28 10:07:16 volumio volumio[5903]: info: Shairport-Sync Started Jan 28 10:07:16 volumio volumio[5903]: info: CoreCommandRouter::volumioGetState Jan 28 10:07:16 volumio volumio[5903]: info: CorePlayQueue::getTrack 0 Jan 28 10:07:16 volumio volumio[5903]: info: Shairport-Sync Started Jan 28 10:07:17 volumio volumio[5903]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:07:17 volumio volumio[5903]: AggregateError Jan 28 10:07:17 volumio volumio[5903]: at internalConnectMultiple (node:net:1114:18) Jan 28 10:07:17 volumio volumio[5903]: at afterConnectMultiple (node:net:1667:5) { Jan 28 10:07:17 volumio volumio[5903]: code: 'ECONNREFUSED', Jan 28 10:07:17 volumio volumio[5903]: [errors]: [ Jan 28 10:07:17 volumio volumio[5903]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 10:07:17 volumio volumio[5903]: at createConnectionError (node:net:1634:14) Jan 28 10:07:17 volumio volumio[5903]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:07:17 volumio volumio[5903]: errno: -111, Jan 28 10:07:17 volumio volumio[5903]: code: 'ECONNREFUSED', Jan 28 10:07:17 volumio volumio[5903]: syscall: 'connect', Jan 28 10:07:17 volumio volumio[5903]: address: '158.69.209.125', Jan 28 10:07:17 volumio volumio[5903]: port: 443 Jan 28 10:07:17 volumio volumio[5903]: }, Jan 28 10:07:17 volumio volumio[5903]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 10:07:17 volumio volumio[5903]: at createConnectionError (node:net:1634:14) Jan 28 10:07:17 volumio volumio[5903]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:07:17 volumio volumio[5903]: errno: -111, Jan 28 10:07:17 volumio volumio[5903]: code: 'ECONNREFUSED', Jan 28 10:07:17 volumio volumio[5903]: syscall: 'connect', Jan 28 10:07:17 volumio volumio[5903]: address: '158.69.210.98', Jan 28 10:07:17 volumio volumio[5903]: port: 443 Jan 28 10:07:17 volumio volumio[5903]: } Jan 28 10:07:17 volumio volumio[5903]: ] Jan 28 10:07:17 volumio volumio[5903]: } Jan 28 10:07:17 volumio volumio[5903]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:07:17 volumio mpd[6054]: 2026-01-28T10:07:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 10:07:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 10:07:17 volumio sudo[6014]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:17 volumio sudo[6024]: pam_unix(sudo:session): session closed for user root Jan 28 10:07:17 volumio sudo[6128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:06' Jan 28 10:07:17 volumio sudo[6128]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"