Feb 17 11:18:02 dm804 volumio[6556]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 11:18:02 dm804 sudo[6826]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:02 dm804 sudo[6826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:02 dm804 sudo[6826]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:02 dm804 sudo[6828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:02 dm804 sudo[6828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:02 dm804 sudo[6828]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:02 dm804 sudo[6831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 11:18:02 dm804 sudo[6831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:02 dm804 sudo[6831]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:02 dm804 volumio[6556]: info: Initializing connection to go-librespot Websocket Feb 17 11:18:02 dm804 volumio[6556]: info: Upmpdcli Daemon Started Feb 17 11:18:02 dm804 go-librespot[6774]: time="2026-02-17T11:18:02+01:00" level=debug msg="new websocket client" Feb 17 11:18:02 dm804 volumio[6556]: info: Connection to go-librespot Websocket established Feb 17 11:18:05 dm804 volumio[6556]: info: Getting Spotify volume Feb 17 11:18:05 dm804 volumio[6556]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 17 11:18:06 dm804 volumio[6556]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:06 dm804 volumio[6556]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:06 dm804 volumio[6556]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 17 11:18:06 dm804 volumio[6556]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 11:18:06 dm804 volumio[6556]: SPOTIFY: VOLUMIO VOLUME 86 Feb 17 11:18:06 dm804 volumio[6556]: info: Aligning Spotify Volume to Volumio Volume Feb 17 11:18:06 dm804 volumio[6556]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:06 dm804 volumio[6556]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:06 dm804 volumio[6556]: info: Setting Spotify Volume from Volumio: 86 Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 11:18:06 dm804 volumio[6556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:06 dm804 volumio[6556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:06 dm804 volumio[6556]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 11:18:06 dm804 volumio[6556]: info: MyVolumio login type: Token Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 11:18:06 dm804 volumio[6556]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 11:18:06 dm804 go-librespot[6774]: time="2026-02-17T11:18:06+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Feb 17 11:18:06 dm804 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:06 dm804 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 11:18:07 dm804 volumio[6556]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 11:18:07 dm804 volumio[6556]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 11:18:07 dm804 volumio[6556]: info: Streaming services startup Feb 17 11:18:07 dm804 volumio[6556]: info: Starting Streaming Daemon Feb 17 11:18:07 dm804 sudo[6836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 11:18:07 dm804 sudo[6836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:07 dm804 volumio[6556]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 11:18:07 dm804 sudo[6836]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:07 dm804 volumio[6556]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:07 dm804 volumio[6556]: Error: socket hang up Feb 17 11:18:07 dm804 volumio[6556]: at connResetException (node:internal/errors:720:14) Feb 17 11:18:07 dm804 volumio[6556]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 11:18:07 dm804 volumio[6556]: at Socket.emit (node:events:526:35) Feb 17 11:18:07 dm804 volumio[6556]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 11:18:07 dm804 volumio[6556]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 11:18:07 dm804 volumio[6556]: code: 'ECONNRESET', Feb 17 11:18:07 dm804 volumio[6556]: response: undefined Feb 17 11:18:07 dm804 volumio[6556]: } Feb 17 11:18:07 dm804 volumio[6556]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:07 dm804 sudo[6856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 11:17' Feb 17 11:18:07 dm804 sudo[6856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:07 dm804 sudo[6856]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:07 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:07.805Z level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 17 11:18:07 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 11:18:07 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 11:18:07 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:07.808Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:51894->127.0.0.1:3000: read: connection reset by peer" Feb 17 11:18:07 dm804 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:07 dm804 systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 11:18:07 dm804 systemd[1]: volumio.service: Consumed 15.587s CPU time. Feb 17 11:18:07 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:18:07 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:18:08 dm804 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Feb 17 11:18:08 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:18:08 dm804 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 11:18:08 dm804 systemd[1]: volumio.service: Consumed 15.587s CPU time. Feb 17 11:18:08 dm804 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 11:18:08 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:18:08 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:08.809Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 17 11:18:09 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:09 dm804 volumio[6868]: info: ----- Volumio3 ---- Feb 17 11:18:09 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:09 dm804 volumio[6868]: info: ----- System startup ---- Feb 17 11:18:09 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:09 dm804 volumio[6868]: info: MYVOLUMIO Environment detected Feb 17 11:18:09 dm804 volumio[6868]: info: Plugin folders cleanup Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning into folder /volumio/app/plugins/ Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category audio_interface Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category miscellanea Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category music_service Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category plugins.json Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category system_controller Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category user_interface Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning into folder /data/plugins/ Feb 17 11:18:09 dm804 volumio[6868]: info: Scanning category music_service Feb 17 11:18:09 dm804 volumio[6868]: info: Plugin folders cleanup completed Feb 17 11:18:09 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:09 dm804 volumio[6868]: info: ----- Core plugins startup ---- Feb 17 11:18:09 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:09 dm804 volumio[6868]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 11:18:09 dm804 volumio[6868]: info: Adding plugin upnp to MyMusic Plugins Feb 17 11:18:09 dm804 volumio[6868]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 11:18:09 dm804 volumio[6868]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 11:18:09 dm804 volumio[6868]: info: Loading plugins from folder /data/plugins/ Feb 17 11:18:09 dm804 volumio[6868]: info: Loading plugin "system"... Feb 17 11:18:09 dm804 volumio[6868]: info: Loading plugin "appearance"... Feb 17 11:18:10 dm804 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 11:18:10 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:10 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:10 dm804 go-librespot[6895]: go-librespot daemon starting... Feb 17 11:18:10 dm804 go-librespot[6896]: time="2026-02-17T11:18:10+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:18:10 dm804 go-librespot[6896]: time="2026-02-17T11:18:10+01:00" level=debug msg="app state loaded" Feb 17 11:18:10 dm804 go-librespot[6896]: time="2026-02-17T11:18:10+01:00" level=debug msg="stored credentials not found" Feb 17 11:18:10 dm804 go-librespot[6896]: time="2026-02-17T11:18:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "network"... Feb 17 11:18:10 dm804 volumio[6868]: info: Refreshing Cached IP Addresses Feb 17 11:18:10 dm804 sudo[6905]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:10 dm804 sudo[6905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:10 dm804 sudo[6907]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:10 dm804 sudo[6905]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:10 dm804 sudo[6907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "services"... Feb 17 11:18:10 dm804 sudo[6907]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "volumio5onboarding"... Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "alsa_controller"... Feb 17 11:18:10 dm804 sudo[6914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 11:18:10 dm804 sudo[6914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:10 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "wizard"... Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "networkfs"... Feb 17 11:18:10 dm804 volumio[6868]: info: Starting Udev Watcher for removable devices Feb 17 11:18:10 dm804 volumio[6868]: info: Ignoring mount for partition: boot Feb 17 11:18:10 dm804 volumio[6868]: info: Ignoring mount for partition: volumio Feb 17 11:18:10 dm804 volumio[6868]: info: Ignoring mount for partition: volumio_data Feb 17 11:18:10 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "volumio_command_line_client"... Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "upnp"... Feb 17 11:18:10 dm804 volumio[6868]: info: [1771323490339] Starting Upmpd Daemon Feb 17 11:18:10 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "my_music"... Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "mpd"... Feb 17 11:18:10 dm804 volumio[6868]: info: Loading plugin "upnp_browser"... Feb 17 11:18:11 dm804 volumio[6868]: info: Starting UPNP Browser Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "alarm-clock"... Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "airplay_emulation"... Feb 17 11:18:11 dm804 volumio[6868]: info: Starting Shairport Sync Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "last_100"... Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "webradio"... Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "i2s_dacs"... Feb 17 11:18:11 dm804 volumio[6868]: info: I2S DAC not set, start Auto-detection Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "volumiodiscovery"... Feb 17 11:18:11 dm804 volumio[6868]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:11 dm804 volumio[6868]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:11 dm804 volumio[6868]: *** WARNING *** For more information see Feb 17 11:18:11 dm804 volumio[6868]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:11 dm804 volumio[6868]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:11 dm804 volumio[6868]: *** WARNING *** For more information see Feb 17 11:18:11 dm804 node[6868]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:11 dm804 node[6868]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:11 dm804 node[6868]: *** WARNING *** For more information see Feb 17 11:18:11 dm804 node[6868]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:11 dm804 node[6868]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:11 dm804 node[6868]: *** WARNING *** For more information see Feb 17 11:18:11 dm804 volumio[6868]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 11:18:11 dm804 volumio[6868]: info: Discovery: Started advertising with name: DM804 Feb 17 11:18:11 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:11 dm804 volumio[6868]: info: Loading plugin "spop"... Feb 17 11:18:12 dm804 volumio[6868]: info: Loading plugin "outputs"... Feb 17 11:18:12 dm804 volumio[6868]: info: Loading plugin "albumart"... Feb 17 11:18:12 dm804 volumio[6868]: info: Plugin example_plugin is not enabled Feb 17 11:18:12 dm804 volumio[6868]: info: Loading plugin "inputs"... Feb 17 11:18:12 dm804 volumio[6868]: info: Loading plugin "updater_comm"... Feb 17 11:18:12 dm804 volumio[6868]: info: Plugin mpdemulation is not enabled Feb 17 11:18:12 dm804 volumio[6868]: info: Loading plugin "rest_api"... Feb 17 11:18:12 dm804 volumio[6868]: info: Loading plugin "websocket"... Feb 17 11:18:12 dm804 volumio[6868]: info: Starting Socket.io Server version 1.7.4 Feb 17 11:18:12 dm804 volumio[6868]: info: Loading i18n strings for locale fr Feb 17 11:18:12 dm804 volumio[6868]: Updating browse sources language Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::initPlayerControls Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: Express server listening on port 3000 Feb 17 11:18:12 dm804 volumio[6868]: [Metrics] WebUI: 3s 409.74ms Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::resetVolumioState Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::getcurrentVolume Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: Cannot read play queue from file Feb 17 11:18:12 dm804 volumio[6868]: info: Volumio Network Manager: Network status updated: 1 Feb 17 11:18:12 dm804 volumio[6940]: Forking 3 albumart workers Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:12 dm804 volumio[6868]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::pushState Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::updateTrackBlock Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrackBlock Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::setRepeat null single undefined Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::pushState Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::setRandom null Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::pushState Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:12 dm804 volumio[6868]: info: Setting Device type: Raspberry PI Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:12 dm804 volumio[6868]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 17 11:18:12 dm804 volumio[6868]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Feb 17 11:18:12 dm804 volumio[6868]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:12 dm804 volumio[6868]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:18:12 dm804 volumio[6868]: info: CoreStateMachine::pushState Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:12 dm804 volumio[6868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:12 dm804 volumio[6868]: info: Completed loading Core Plugins Feb 17 11:18:12 dm804 volumio[6868]: info: Preparing to generate the ALSA configuration file Feb 17 11:18:12 dm804 volumio[6868]: info: Discovery: adding 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:18:12 dm804 volumio[6868]: info: Discovery: Found device DM804 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:12 dm804 volumio[6868]: info: Discovery: this is already registered, 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:18:12 dm804 volumio[6868]: info: Discovery: Found device DM804 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:12 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:12 dm804 volumio[6868]: info: Asound.conf file unchanged, so no further update is needed Feb 17 11:18:12 dm804 volumio[6868]: info: Output device has changed, restarting MPD Feb 17 11:18:12 dm804 volumio[6868]: info: Output device has changed, restarting Shairport Sync Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:12 dm804 sudo[6998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:18:12 dm804 sudo[6998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:12 dm804 sudo[6998]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:12 dm804 sudo[6999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:18:12 dm804 sudo[6999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:12 dm804 volumio[6868]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:12 dm804 volumio[6868]: info: ___________ START PLUGINS ___________ Feb 17 11:18:12 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:12] [connect] Successful connection Feb 17 11:18:12 dm804 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 11:18:12 dm804 volumio[6868]: info: ControllerMpd::onStart: Initializing MPD Feb 17 11:18:12 dm804 volumio[6868]: info: Creating MPD Configuration file Feb 17 11:18:12 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:18:12 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:18:12 dm804 systemd[1]: mpd.service: Consumed 1.790s CPU time. Feb 17 11:18:12 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:18:12 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:18:12 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:18:12 dm804 sudo[6914]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:12 dm804 volumio[6868]: info: [1771323492867] CoreMusicLibrary::Adding element Serveurs Média Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:12 dm804 volumio[6868]: info: UPNP Browser: Client initialized successfully Feb 17 11:18:12 dm804 sudo[7012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:18:12 dm804 sudo[7012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:12 dm804 sudo[7007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 17 11:18:12 dm804 sudo[7007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:12 dm804 sudo[7010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:18:12 dm804 sudo[7010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:12 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:18:12 dm804 sudo[7010]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:12 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:18:12 dm804 volumio[6868]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:12 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:12 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:18:12 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:18:12 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:18:12 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:18:12 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:18:12 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:18:12 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:18:13 dm804 volumio[6868]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:13 dm804 volumio[6868]: info: [1771323493007] CoreMusicLibrary::Adding element Last_100 Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:13 dm804 volumio[6868]: info: [1771323493011] CoreMusicLibrary::Adding element Webradio Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:13 dm804 sudo[7007]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:13 dm804 volumio[6868]: info: Initializing BBC Radios Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:13 dm804 volumio[6868]: info: Creating Spotify config file Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:13 dm804 volumio[6868]: info: Volumio Calling Home Feb 17 11:18:13 dm804 sudo[7026]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 11:18:13 dm804 sudo[7026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 11:18:13 dm804 sudo[7026]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:13 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:13.334Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.54.114:50824 @ 0x335a9c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 17 11:18:13 dm804 volumio[6955]: Starting albumart workers Feb 17 11:18:13 dm804 volumio[6956]: Starting albumart workers Feb 17 11:18:13 dm804 volumio[6957]: Starting albumart workers Feb 17 11:18:13 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:13 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:13 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:13 dm804 volumio[6868]: info: MPD Permissions set Feb 17 11:18:13 dm804 volumio[6868]: info: MPD Permissions set Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:18:13 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:18:13 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771323492 101 Feb 17 11:18:13 dm804 volumio[6868]: 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: 4 Feb 17 11:18:13 dm804 volumio[6868]: info: Volumio called home Feb 17 11:18:14 dm804 volumio[6868]: info: Spotify config file written Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 sudo[7052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 11:18:14 dm804 sudo[7052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 11:18:14 dm804 systemd[1]: go-librespot-daemon.service: Killing process 6899 (go-librespot) with signal SIGKILL. Feb 17 11:18:14 dm804 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 11:18:14 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:14 dm804 go-librespot[7059]: go-librespot daemon starting... Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:14 dm804 sudo[7052]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:14 dm804 volumio[6868]: info: No need to fix Spotify hosts Feb 17 11:18:14 dm804 go-librespot[7065]: time="2026-02-17T11:18:14+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:18:14 dm804 go-librespot[7065]: time="2026-02-17T11:18:14+01:00" level=debug msg="app state loaded" Feb 17 11:18:14 dm804 go-librespot[7065]: time="2026-02-17T11:18:14+01:00" level=debug msg="stored credentials not found" Feb 17 11:18:14 dm804 go-librespot[7065]: time="2026-02-17T11:18:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: Listing playlists Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: Listing playlists Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: Listing playlists Feb 17 11:18:14 dm804 volumio[6868]: info: Received Get System Info Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:14 dm804 volumio[6868]: info: Discovery: Getting this device information Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:14 dm804 volumio[6868]: info: Starting Shairport Sync Feb 17 11:18:14 dm804 volumio[6868]: info: Starting Shairport Sync Feb 17 11:18:14 dm804 volumio[6868]: info: Starting Shairport Sync Feb 17 11:18:14 dm804 sudo[7090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:14 dm804 sudo[7090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:14 dm804 sudo[7092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:14 dm804 sudo[7092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:14 dm804 sudo[7095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:14 dm804 sudo[7095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:14 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:18:14 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:18:14 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:14 dm804 systemd[1]: shairport-sync.service: Consumed 1.660s CPU time. Feb 17 11:18:14 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:14 dm804 sudo[7090]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:14 dm804 sudo[7092]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:14 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:18:14 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:18:14 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:14 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:14 dm804 sudo[7095]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:14 dm804 volumio[6868]: info: Shairport-Sync Started Feb 17 11:18:14 dm804 volumio[6868]: Error adding Membership: Error: addMembership EINVAL Feb 17 11:18:14 dm804 volumio[6868]: info: Shairport-Sync Started Feb 17 11:18:14 dm804 volumio[6868]: info: Shairport-Sync Started Feb 17 11:18:14 dm804 volumio[6868]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:14 dm804 volumio[6868]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:15 dm804 mpd[7043]: 2026-02-17T11:18:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 11:18:15 dm804 systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 11:18:15 dm804 sudo[7012]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:15 dm804 sudo[6999]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:15 dm804 volumio[6868]: info: Completed starting Core Plugins Feb 17 11:18:15 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:15 dm804 volumio[6868]: info: ----- MyVolumio plugins startup ---- Feb 17 11:18:15 dm804 volumio[6868]: info: ------------------------------------------- Feb 17 11:18:15 dm804 volumio[6868]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 11:18:15 dm804 volumio[6868]: error: MPD error: The expression evaluated to a falsy value: Feb 17 11:18:15 dm804 volumio[6868]: assert.ok(self.idling) Feb 17 11:18:15 dm804 volumio[6868]: error: The expression evaluated to a falsy value: Feb 17 11:18:15 dm804 volumio[6868]: assert.ok(self.idling) Feb 17 11:18:15 dm804 volumio[6868]: info: MPD running with PID7043 Feb 17 11:18:15 dm804 volumio[6868]: ,establishing connection Feb 17 11:18:15 dm804 volumio[6868]: error: updateQueue error: null Feb 17 11:18:15 dm804 volumio[6868]: error: updateQueue error: null Feb 17 11:18:17 dm804 volumio[6868]: info: go-librespot daemon successfully initialized Feb 17 11:18:20 dm804 volumio[6868]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 11:18:20 dm804 sudo[7133]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:20 dm804 sudo[7133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:20 dm804 sudo[7133]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:20 dm804 sudo[7135]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:20 dm804 sudo[7135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:20 dm804 sudo[7135]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:20 dm804 sudo[7138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 11:18:20 dm804 sudo[7138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:20 dm804 sudo[7138]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:20 dm804 volumio[6868]: info: Upmpdcli Daemon Started Feb 17 11:18:20 dm804 volumio[6868]: info: Initializing connection to go-librespot Websocket Feb 17 11:18:20 dm804 go-librespot[7065]: time="2026-02-17T11:18:20+01:00" level=debug msg="new websocket client" Feb 17 11:18:20 dm804 volumio[6868]: info: Connection to go-librespot Websocket established Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 11:18:23 dm804 volumio[6868]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 11:18:23 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:23 dm804 volumio[6868]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:23 dm804 volumio[6868]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 11:18:23 dm804 volumio[6868]: info: MyVolumio login type: Token Feb 17 11:18:24 dm804 volumio[6868]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 11:18:24 dm804 volumio[6868]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 11:18:24 dm804 go-librespot[7065]: time="2026-02-17T11:18:24+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Feb 17 11:18:24 dm804 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:24 dm804 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 11:18:24 dm804 volumio[6868]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 11:18:24 dm804 volumio[6868]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 11:18:24 dm804 volumio[6868]: info: Streaming services startup Feb 17 11:18:24 dm804 volumio[6868]: info: Starting Streaming Daemon Feb 17 11:18:24 dm804 sudo[7142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 11:18:24 dm804 sudo[7142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:24 dm804 volumio[6868]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 11:18:24 dm804 sudo[7142]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:24 dm804 volumio[6868]: info: Getting Spotify volume Feb 17 11:18:24 dm804 volumio[6868]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:24 dm804 volumio[6868]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 11:18:24 dm804 volumio[6868]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 11:18:24 dm804 volumio[6868]: errno: -111, Feb 17 11:18:24 dm804 volumio[6868]: code: 'ECONNREFUSED', Feb 17 11:18:24 dm804 volumio[6868]: syscall: 'connect', Feb 17 11:18:24 dm804 volumio[6868]: address: '127.0.0.1', Feb 17 11:18:24 dm804 volumio[6868]: port: 9879, Feb 17 11:18:24 dm804 volumio[6868]: response: undefined Feb 17 11:18:24 dm804 volumio[6868]: } Feb 17 11:18:24 dm804 volumio[6868]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:25 dm804 sudo[7162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 11:17' Feb 17 11:18:25 dm804 sudo[7162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:25 dm804 sudo[7162]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:25 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:25.353Z level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 17 11:18:25 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 11:18:25 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 11:18:25 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:25.355Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 17 11:18:25 dm804 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:25 dm804 systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 11:18:25 dm804 systemd[1]: volumio.service: Consumed 15.534s CPU time. Feb 17 11:18:25 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:18:25 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:18:25 dm804 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Feb 17 11:18:25 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:18:25 dm804 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 11:18:25 dm804 systemd[1]: volumio.service: Consumed 15.534s CPU time. Feb 17 11:18:25 dm804 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 11:18:25 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:18:26 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:26 dm804 volumio[7177]: info: ----- Volumio3 ---- Feb 17 11:18:26 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:26 dm804 volumio[7177]: info: ----- System startup ---- Feb 17 11:18:26 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:26 dm804 volumio[7177]: info: MYVOLUMIO Environment detected Feb 17 11:18:27 dm804 volumio[7177]: info: Plugin folders cleanup Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning into folder /volumio/app/plugins/ Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category audio_interface Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category miscellanea Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category music_service Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category plugins.json Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category system_controller Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category user_interface Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning into folder /data/plugins/ Feb 17 11:18:27 dm804 volumio[7177]: info: Scanning category music_service Feb 17 11:18:27 dm804 volumio[7177]: info: Plugin folders cleanup completed Feb 17 11:18:27 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:27 dm804 volumio[7177]: info: ----- Core plugins startup ---- Feb 17 11:18:27 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 11:18:27 dm804 volumio[7177]: info: Adding plugin upnp to MyMusic Plugins Feb 17 11:18:27 dm804 volumio[7177]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 11:18:27 dm804 volumio[7177]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugins from folder /data/plugins/ Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "system"... Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "appearance"... Feb 17 11:18:27 dm804 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 11:18:27 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:27 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:27 dm804 go-librespot[7204]: go-librespot daemon starting... Feb 17 11:18:27 dm804 go-librespot[7205]: time="2026-02-17T11:18:27+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:18:27 dm804 go-librespot[7205]: time="2026-02-17T11:18:27+01:00" level=debug msg="app state loaded" Feb 17 11:18:27 dm804 go-librespot[7205]: time="2026-02-17T11:18:27+01:00" level=debug msg="stored credentials not found" Feb 17 11:18:27 dm804 go-librespot[7205]: time="2026-02-17T11:18:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "network"... Feb 17 11:18:27 dm804 volumio[7177]: info: Refreshing Cached IP Addresses Feb 17 11:18:27 dm804 sudo[7214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:27 dm804 sudo[7214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:27 dm804 sudo[7216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:27 dm804 sudo[7214]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:27 dm804 sudo[7216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "services"... Feb 17 11:18:27 dm804 sudo[7216]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "volumio5onboarding"... Feb 17 11:18:27 dm804 sudo[7225]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "alsa_controller"... Feb 17 11:18:27 dm804 sudo[7225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:27 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "wizard"... Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "networkfs"... Feb 17 11:18:27 dm804 volumio[7177]: info: Starting Udev Watcher for removable devices Feb 17 11:18:27 dm804 volumio[7177]: info: Ignoring mount for partition: boot Feb 17 11:18:27 dm804 volumio[7177]: info: Ignoring mount for partition: volumio Feb 17 11:18:27 dm804 volumio[7177]: info: Ignoring mount for partition: volumio_data Feb 17 11:18:27 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "volumio_command_line_client"... Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "upnp"... Feb 17 11:18:27 dm804 volumio[7177]: info: [1771323507794] Starting Upmpd Daemon Feb 17 11:18:27 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "my_music"... Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "mpd"... Feb 17 11:18:27 dm804 volumio[7177]: info: Loading plugin "upnp_browser"... Feb 17 11:18:28 dm804 volumio[7177]: info: Starting UPNP Browser Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "alarm-clock"... Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "airplay_emulation"... Feb 17 11:18:28 dm804 volumio[7177]: info: Starting Shairport Sync Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "last_100"... Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "webradio"... Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "i2s_dacs"... Feb 17 11:18:28 dm804 volumio[7177]: info: I2S DAC not set, start Auto-detection Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "volumiodiscovery"... Feb 17 11:18:28 dm804 volumio[7177]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:28 dm804 node[7177]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:28 dm804 volumio[7177]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:28 dm804 node[7177]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:28 dm804 volumio[7177]: *** WARNING *** For more information see Feb 17 11:18:28 dm804 node[7177]: *** WARNING *** For more information see Feb 17 11:18:28 dm804 volumio[7177]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:28 dm804 node[7177]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:28 dm804 volumio[7177]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:28 dm804 node[7177]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:28 dm804 volumio[7177]: *** WARNING *** For more information see Feb 17 11:18:28 dm804 node[7177]: *** WARNING *** For more information see Feb 17 11:18:28 dm804 volumio[7177]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 11:18:28 dm804 volumio[7177]: info: Discovery: Started advertising with name: DM804 Feb 17 11:18:28 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:28 dm804 volumio[7177]: info: Loading plugin "spop"... Feb 17 11:18:29 dm804 volumio[7177]: info: Loading plugin "outputs"... Feb 17 11:18:29 dm804 volumio[7177]: info: Loading plugin "albumart"... Feb 17 11:18:29 dm804 volumio[7177]: info: Plugin example_plugin is not enabled Feb 17 11:18:29 dm804 volumio[7177]: info: Loading plugin "inputs"... Feb 17 11:18:29 dm804 volumio[7177]: info: Loading plugin "updater_comm"... Feb 17 11:18:29 dm804 volumio[7177]: info: Plugin mpdemulation is not enabled Feb 17 11:18:29 dm804 volumio[7177]: info: Loading plugin "rest_api"... Feb 17 11:18:29 dm804 volumio[7177]: info: Loading plugin "websocket"... Feb 17 11:18:29 dm804 volumio[7177]: info: Starting Socket.io Server version 1.7.4 Feb 17 11:18:29 dm804 volumio[7177]: info: Loading i18n strings for locale fr Feb 17 11:18:29 dm804 volumio[7177]: Updating browse sources language Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::initPlayerControls Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: Express server listening on port 3000 Feb 17 11:18:29 dm804 volumio[7177]: [Metrics] WebUI: 3s 420.36ms Feb 17 11:18:29 dm804 volumio[7177]: info: CoreStateMachine::resetVolumioState Feb 17 11:18:29 dm804 volumio[7177]: info: CoreStateMachine::getcurrentVolume Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:29 dm804 volumio[7177]: info: Cannot read play queue from file Feb 17 11:18:29 dm804 volumio[7177]: info: Volumio Network Manager: Network status updated: 1 Feb 17 11:18:29 dm804 volumio[7249]: Forking 3 albumart workers Feb 17 11:18:29 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 17 11:18:29 dm804 volumio[7177]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:18:29 dm804 volumio[7177]: info: CoreStateMachine::pushState Feb 17 11:18:29 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:29 dm804 volumio[7177]: info: CoreStateMachine::updateTrackBlock Feb 17 11:18:29 dm804 volumio[7177]: info: CorePlayQueue::getTrackBlock Feb 17 11:18:29 dm804 volumio[7177]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:18:29 dm804 volumio[7177]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 17 11:18:29 dm804 volumio[7177]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreStateMachine::setRepeat null single undefined Feb 17 11:18:30 dm804 volumio[7177]: info: CoreStateMachine::pushState Feb 17 11:18:30 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:30 dm804 volumio[7177]: info: CoreStateMachine::setRandom null Feb 17 11:18:30 dm804 volumio[7177]: info: CoreStateMachine::pushState Feb 17 11:18:30 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:30 dm804 volumio[7177]: info: Setting Device type: Raspberry PI Feb 17 11:18:30 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:30 dm804 volumio[7177]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 17 11:18:30 dm804 volumio[7177]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Feb 17 11:18:30 dm804 volumio[7177]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 17 11:18:30 dm804 volumio[7177]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:18:30 dm804 volumio[7177]: info: CoreStateMachine::pushState Feb 17 11:18:30 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:30 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:30 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:30 dm804 volumio[7177]: info: Completed loading Core Plugins Feb 17 11:18:30 dm804 volumio[7177]: info: Preparing to generate the ALSA configuration file Feb 17 11:18:30 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:30 dm804 volumio[7177]: info: Discovery: adding 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:18:30 dm804 volumio[7177]: info: Discovery: Found device DM804 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:30 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:30 dm804 volumio[7177]: info: Discovery: this is already registered, 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:18:30 dm804 volumio[7177]: info: Discovery: Found device DM804 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:30 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:30 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:30 dm804 volumio[7177]: info: Asound.conf file unchanged, so no further update is needed Feb 17 11:18:30 dm804 volumio[7177]: info: Output device has changed, restarting MPD Feb 17 11:18:30 dm804 volumio[7177]: info: Output device has changed, restarting Shairport Sync Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:30 dm804 sudo[7305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:18:30 dm804 sudo[7305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:30 dm804 sudo[7307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:18:30 dm804 sudo[7305]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:30 dm804 sudo[7307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:30 dm804 volumio[7177]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:30 dm804 volumio[7177]: info: ___________ START PLUGINS ___________ Feb 17 11:18:30 dm804 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 11:18:30 dm804 volumio[7177]: info: ControllerMpd::onStart: Initializing MPD Feb 17 11:18:30 dm804 volumio[7177]: info: Creating MPD Configuration file Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:30 dm804 volumio[7177]: info: [1771323510312] CoreMusicLibrary::Adding element Serveurs Média Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:30 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:18:30 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:18:30 dm804 systemd[1]: mpd.service: Consumed 1.961s CPU time. Feb 17 11:18:30 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:18:30 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:18:30 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:18:30 dm804 sudo[7225]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:30 dm804 volumio[7177]: info: UPNP Browser: Client initialized successfully Feb 17 11:18:30 dm804 sudo[7316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:18:30 dm804 sudo[7316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:30 dm804 sudo[7317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 17 11:18:30 dm804 sudo[7317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:30 dm804 sudo[7319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:18:30 dm804 sudo[7319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:30 dm804 sudo[7316]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:30 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:30] [connect] Successful connection Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:30 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:18:30 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:18:30 dm804 volumio[7177]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:30 dm804 volumio[7177]: info: [1771323510451] CoreMusicLibrary::Adding element Last_100 Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:30 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:30 dm804 volumio[7177]: info: [1771323510453] CoreMusicLibrary::Adding element Webradio Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:30 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:30 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:18:30 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:18:30 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:18:30 dm804 volumio[7177]: info: Initializing BBC Radios Feb 17 11:18:30 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:18:30 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:18:30 dm804 sudo[7317]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: Creating Spotify config file Feb 17 11:18:30 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:30 dm804 volumio[7177]: info: Volumio Calling Home Feb 17 11:18:30 dm804 sudo[7336]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 11:18:30 dm804 sudo[7336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 11:18:30 dm804 sudo[7336]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:30 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:30.788Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.54.114:50824 @ 0x335a9c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 17 11:18:30 dm804 volumio[7265]: Starting albumart workers Feb 17 11:18:30 dm804 volumio[7264]: Starting albumart workers Feb 17 11:18:31 dm804 volumio[7266]: Starting albumart workers Feb 17 11:18:31 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:31 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 17 11:18:31 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:31 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:31 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:31 dm804 volumio[7177]: info: MPD Permissions set Feb 17 11:18:31 dm804 volumio[7177]: info: MPD Permissions set Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:18:31 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771323510 101 Feb 17 11:18:31 dm804 volumio[7177]: 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: 4 Feb 17 11:18:31 dm804 volumio[7177]: info: Volumio called home Feb 17 11:18:31 dm804 volumio[7177]: info: Spotify config file written Feb 17 11:18:31 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 sudo[7354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 11:18:31 dm804 sudo[7354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:31 dm804 volumio[7177]: info: No need to fix Spotify hosts Feb 17 11:18:31 dm804 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 11:18:31 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:31 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:31 dm804 go-librespot[7367]: go-librespot daemon starting... Feb 17 11:18:31 dm804 sudo[7354]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:31 dm804 volumio[7177]: info: Starting Shairport Sync Feb 17 11:18:31 dm804 go-librespot[7373]: time="2026-02-17T11:18:31+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:18:31 dm804 go-librespot[7373]: time="2026-02-17T11:18:31+01:00" level=debug msg="app state loaded" Feb 17 11:18:31 dm804 go-librespot[7373]: time="2026-02-17T11:18:31+01:00" level=debug msg="stored credentials not found" Feb 17 11:18:31 dm804 volumio[7177]: info: Starting Shairport Sync Feb 17 11:18:31 dm804 volumio[7177]: info: Starting Shairport Sync Feb 17 11:18:31 dm804 go-librespot[7373]: time="2026-02-17T11:18:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:18:31 dm804 sudo[7384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:31 dm804 sudo[7384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:31 dm804 sudo[7390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:31 dm804 sudo[7390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:31 dm804 sudo[7392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:31 dm804 sudo[7392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:31 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:31 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:31 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:18:31 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:18:31 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:31 dm804 systemd[1]: shairport-sync.service: Consumed 1.657s CPU time. Feb 17 11:18:31 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:31 dm804 sudo[7392]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:31 dm804 sudo[7384]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:31 dm804 volumio[7177]: info: Shairport-Sync Started Feb 17 11:18:31 dm804 volumio[7177]: Error adding Membership: Error: addMembership EINVAL Feb 17 11:18:31 dm804 sudo[7390]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:31 dm804 volumio[7177]: info: Shairport-Sync Started Feb 17 11:18:31 dm804 volumio[7177]: info: Shairport-Sync Started Feb 17 11:18:32 dm804 mpd[7352]: 2026-02-17T11:18:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 11:18:32 dm804 systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 11:18:32 dm804 sudo[7307]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:32 dm804 sudo[7319]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:32 dm804 volumio[7177]: info: Completed starting Core Plugins Feb 17 11:18:32 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:32 dm804 volumio[7177]: info: ----- MyVolumio plugins startup ---- Feb 17 11:18:32 dm804 volumio[7177]: info: ------------------------------------------- Feb 17 11:18:32 dm804 volumio[7177]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 11:18:32 dm804 volumio[7177]: error: MPD error: The expression evaluated to a falsy value: Feb 17 11:18:32 dm804 volumio[7177]: assert.ok(self.idling) Feb 17 11:18:32 dm804 volumio[7177]: error: The expression evaluated to a falsy value: Feb 17 11:18:32 dm804 volumio[7177]: assert.ok(self.idling) Feb 17 11:18:32 dm804 volumio[7177]: error: updateQueue error: null Feb 17 11:18:32 dm804 volumio[7177]: info: MPD running with PID7352 Feb 17 11:18:32 dm804 volumio[7177]: ,establishing connection Feb 17 11:18:32 dm804 volumio[7177]: error: updateQueue error: null Feb 17 11:18:33 dm804 volumio[7177]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: Listing playlists Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: Listing playlists Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: Listing playlists Feb 17 11:18:33 dm804 volumio[7177]: info: Received Get System Info Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:33 dm804 volumio[7177]: info: Discovery: Getting this device information Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:33 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:33 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:34 dm804 volumio[7177]: info: go-librespot daemon successfully initialized Feb 17 11:18:37 dm804 volumio[7177]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 11:18:37 dm804 volumio[7177]: info: Initializing connection to go-librespot Websocket Feb 17 11:18:37 dm804 go-librespot[7373]: time="2026-02-17T11:18:37+01:00" level=debug msg="new websocket client" Feb 17 11:18:37 dm804 volumio[7177]: info: Connection to go-librespot Websocket established Feb 17 11:18:37 dm804 sudo[7444]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:37 dm804 sudo[7444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:37 dm804 sudo[7444]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:37 dm804 sudo[7446]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:37 dm804 sudo[7446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:37 dm804 sudo[7448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 11:18:37 dm804 sudo[7448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:37 dm804 sudo[7446]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:37 dm804 sudo[7448]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:37 dm804 volumio[7177]: info: Upmpdcli Daemon Started Feb 17 11:18:40 dm804 volumio[7177]: info: Getting Spotify volume Feb 17 11:18:40 dm804 volumio[7177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 17 11:18:40 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:40 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:40 dm804 volumio[7177]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 17 11:18:40 dm804 volumio[7177]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 11:18:40 dm804 volumio[7177]: SPOTIFY: VOLUMIO VOLUME 86 Feb 17 11:18:40 dm804 volumio[7177]: info: Aligning Spotify Volume to Volumio Volume Feb 17 11:18:40 dm804 volumio[7177]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:40 dm804 volumio[7177]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:40 dm804 volumio[7177]: info: Setting Spotify Volume from Volumio: 86 Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 11:18:40 dm804 volumio[7177]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 11:18:41 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:41 dm804 volumio[7177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:41 dm804 volumio[7177]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 11:18:41 dm804 volumio[7177]: info: MyVolumio login type: Token Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 11:18:41 dm804 go-librespot[7373]: time="2026-02-17T11:18:41+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Feb 17 11:18:41 dm804 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:41 dm804 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 11:18:41 dm804 volumio[7177]: info: Streaming services startup Feb 17 11:18:41 dm804 volumio[7177]: info: Starting Streaming Daemon Feb 17 11:18:41 dm804 sudo[7453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 11:18:41 dm804 volumio[7177]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 11:18:41 dm804 sudo[7453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:42 dm804 sudo[7453]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:42 dm804 volumio[7177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:42 dm804 volumio[7177]: Error: socket hang up Feb 17 11:18:42 dm804 volumio[7177]: at connResetException (node:internal/errors:720:14) Feb 17 11:18:42 dm804 volumio[7177]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 11:18:42 dm804 volumio[7177]: at Socket.emit (node:events:526:35) Feb 17 11:18:42 dm804 volumio[7177]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 11:18:42 dm804 volumio[7177]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 11:18:42 dm804 volumio[7177]: code: 'ECONNRESET', Feb 17 11:18:42 dm804 volumio[7177]: response: undefined Feb 17 11:18:42 dm804 volumio[7177]: } Feb 17 11:18:42 dm804 volumio[7177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:42 dm804 sudo[7473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 11:17' Feb 17 11:18:42 dm804 sudo[7473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:42 dm804 sudo[7473]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:42 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 11:18:42 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 11:18:42 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:42.793Z level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 17 11:18:42 dm804 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:42 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:42.795Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 17 11:18:42 dm804 systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 11:18:42 dm804 systemd[1]: volumio.service: Consumed 15.491s CPU time. Feb 17 11:18:42 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:18:42 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:18:43 dm804 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Feb 17 11:18:43 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:18:43 dm804 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 11:18:43 dm804 systemd[1]: volumio.service: Consumed 15.491s CPU time. Feb 17 11:18:43 dm804 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 11:18:43 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:18:43 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:43.796Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 17 11:18:44 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:44 dm804 volumio[7488]: info: ----- Volumio3 ---- Feb 17 11:18:44 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:44 dm804 volumio[7488]: info: ----- System startup ---- Feb 17 11:18:44 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:44 dm804 volumio[7488]: info: MYVOLUMIO Environment detected Feb 17 11:18:44 dm804 volumio[7488]: info: Plugin folders cleanup Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning into folder /volumio/app/plugins/ Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category audio_interface Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category miscellanea Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category music_service Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category plugins.json Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category system_controller Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category user_interface Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning into folder /data/plugins/ Feb 17 11:18:44 dm804 volumio[7488]: info: Scanning category music_service Feb 17 11:18:44 dm804 volumio[7488]: info: Plugin folders cleanup completed Feb 17 11:18:44 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:44 dm804 volumio[7488]: info: ----- Core plugins startup ---- Feb 17 11:18:44 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:44 dm804 volumio[7488]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 11:18:44 dm804 volumio[7488]: info: Adding plugin upnp to MyMusic Plugins Feb 17 11:18:44 dm804 volumio[7488]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 11:18:44 dm804 volumio[7488]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 11:18:44 dm804 volumio[7488]: info: Loading plugins from folder /data/plugins/ Feb 17 11:18:44 dm804 volumio[7488]: info: Loading plugin "system"... Feb 17 11:18:44 dm804 volumio[7488]: info: Loading plugin "appearance"... Feb 17 11:18:44 dm804 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 11:18:44 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:44 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:44 dm804 go-librespot[7515]: go-librespot daemon starting... Feb 17 11:18:44 dm804 go-librespot[7516]: time="2026-02-17T11:18:44+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:18:44 dm804 go-librespot[7516]: time="2026-02-17T11:18:44+01:00" level=debug msg="app state loaded" Feb 17 11:18:44 dm804 go-librespot[7516]: time="2026-02-17T11:18:44+01:00" level=debug msg="stored credentials not found" Feb 17 11:18:44 dm804 go-librespot[7516]: time="2026-02-17T11:18:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "network"... Feb 17 11:18:45 dm804 volumio[7488]: info: Refreshing Cached IP Addresses Feb 17 11:18:45 dm804 sudo[7525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:45 dm804 sudo[7525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:45 dm804 sudo[7527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:45 dm804 sudo[7527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "services"... Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "volumio5onboarding"... Feb 17 11:18:45 dm804 sudo[7525]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:45 dm804 sudo[7527]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "alsa_controller"... Feb 17 11:18:45 dm804 sudo[7534]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 11:18:45 dm804 sudo[7534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:45 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "wizard"... Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "networkfs"... Feb 17 11:18:45 dm804 volumio[7488]: info: Starting Udev Watcher for removable devices Feb 17 11:18:45 dm804 volumio[7488]: info: Ignoring mount for partition: boot Feb 17 11:18:45 dm804 volumio[7488]: info: Ignoring mount for partition: volumio Feb 17 11:18:45 dm804 volumio[7488]: info: Ignoring mount for partition: volumio_data Feb 17 11:18:45 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "volumio_command_line_client"... Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "upnp"... Feb 17 11:18:45 dm804 volumio[7488]: info: [1771323525345] Starting Upmpd Daemon Feb 17 11:18:45 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "my_music"... Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "mpd"... Feb 17 11:18:45 dm804 volumio[7488]: info: Loading plugin "upnp_browser"... Feb 17 11:18:46 dm804 volumio[7488]: info: Starting UPNP Browser Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "alarm-clock"... Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "airplay_emulation"... Feb 17 11:18:46 dm804 volumio[7488]: info: Starting Shairport Sync Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "last_100"... Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "webradio"... Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "i2s_dacs"... Feb 17 11:18:46 dm804 volumio[7488]: info: I2S DAC not set, start Auto-detection Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "volumiodiscovery"... Feb 17 11:18:46 dm804 volumio[7488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:46 dm804 volumio[7488]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:46 dm804 volumio[7488]: *** WARNING *** For more information see Feb 17 11:18:46 dm804 volumio[7488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:46 dm804 volumio[7488]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:46 dm804 volumio[7488]: *** WARNING *** For more information see Feb 17 11:18:46 dm804 node[7488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:46 dm804 node[7488]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:46 dm804 node[7488]: *** WARNING *** For more information see Feb 17 11:18:46 dm804 node[7488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:18:46 dm804 node[7488]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:18:46 dm804 node[7488]: *** WARNING *** For more information see Feb 17 11:18:46 dm804 volumio[7488]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 11:18:46 dm804 volumio[7488]: info: Discovery: Started advertising with name: DM804 Feb 17 11:18:46 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:18:46 dm804 volumio[7488]: info: Loading plugin "spop"... Feb 17 11:18:47 dm804 volumio[7488]: info: Loading plugin "outputs"... Feb 17 11:18:47 dm804 volumio[7488]: info: Loading plugin "albumart"... Feb 17 11:18:47 dm804 volumio[7488]: info: Plugin example_plugin is not enabled Feb 17 11:18:47 dm804 volumio[7488]: info: Loading plugin "inputs"... Feb 17 11:18:47 dm804 volumio[7488]: info: Loading plugin "updater_comm"... Feb 17 11:18:47 dm804 volumio[7488]: info: Plugin mpdemulation is not enabled Feb 17 11:18:47 dm804 volumio[7488]: info: Loading plugin "rest_api"... Feb 17 11:18:47 dm804 volumio[7488]: info: Loading plugin "websocket"... Feb 17 11:18:47 dm804 volumio[7488]: info: Starting Socket.io Server version 1.7.4 Feb 17 11:18:47 dm804 volumio[7488]: info: Loading i18n strings for locale fr Feb 17 11:18:47 dm804 volumio[7488]: Updating browse sources language Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::initPlayerControls Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: Express server listening on port 3000 Feb 17 11:18:47 dm804 volumio[7488]: [Metrics] WebUI: 3s 425.54ms Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::resetVolumioState Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::getcurrentVolume Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: Cannot read play queue from file Feb 17 11:18:47 dm804 volumio[7488]: info: Volumio Network Manager: Network status updated: 1 Feb 17 11:18:47 dm804 volumio[7560]: Forking 3 albumart workers Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 17 11:18:47 dm804 volumio[7488]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::pushState Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::updateTrackBlock Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrackBlock Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::setRepeat null single undefined Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::pushState Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::setRandom null Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::pushState Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:47 dm804 volumio[7488]: info: Setting Device type: Raspberry PI Feb 17 11:18:47 dm804 volumio[7488]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 17 11:18:47 dm804 volumio[7488]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Feb 17 11:18:47 dm804 volumio[7488]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:47 dm804 volumio[7488]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:18:47 dm804 volumio[7488]: info: CoreStateMachine::pushState Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioPushState Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:47 dm804 volumio[7488]: info: Completed loading Core Plugins Feb 17 11:18:47 dm804 volumio[7488]: info: Preparing to generate the ALSA configuration file Feb 17 11:18:47 dm804 volumio[7488]: info: Discovery: adding 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:18:47 dm804 volumio[7488]: info: Discovery: Found device DM804 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:47 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:47 dm804 volumio[7488]: info: Discovery: this is already registered, 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:18:47 dm804 volumio[7488]: info: Discovery: Found device DM804 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:47 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:47 dm804 volumio[7488]: info: Asound.conf file unchanged, so no further update is needed Feb 17 11:18:47 dm804 volumio[7488]: info: Output device has changed, restarting MPD Feb 17 11:18:47 dm804 volumio[7488]: info: Output device has changed, restarting Shairport Sync Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:47 dm804 sudo[7617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:18:47 dm804 sudo[7617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:47 dm804 sudo[7617]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:47 dm804 sudo[7619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:18:47 dm804 sudo[7619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:47 dm804 volumio[7488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:47 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:47] [connect] Successful connection Feb 17 11:18:47 dm804 volumio[7488]: info: ___________ START PLUGINS ___________ Feb 17 11:18:47 dm804 volumio[7488]: info: ControllerMpd::onStart: Initializing MPD Feb 17 11:18:47 dm804 volumio[7488]: info: Creating MPD Configuration file Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:47 dm804 volumio[7488]: info: [1771323527830] CoreMusicLibrary::Adding element Serveurs Média Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:47 dm804 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 11:18:47 dm804 volumio[7488]: info: UPNP Browser: Client initialized successfully Feb 17 11:18:47 dm804 sudo[7628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:18:47 dm804 sudo[7626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 17 11:18:47 dm804 sudo[7626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:47 dm804 sudo[7628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:47 dm804 sudo[7630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:18:47 dm804 sudo[7630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:47 dm804 sudo[7534]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:47 dm804 sudo[7628]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:47 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:18:47 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:18:47 dm804 systemd[1]: mpd.service: Consumed 1.874s CPU time. Feb 17 11:18:47 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:18:47 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:18:47 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:18:47 dm804 volumio[7488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:47 dm804 volumio[7488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:47 dm804 volumio[7488]: info: [1771323527968] CoreMusicLibrary::Adding element Last_100 Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:18:47 dm804 volumio[7488]: info: [1771323527970] CoreMusicLibrary::Adding element Webradio Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:47 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:47 dm804 volumio[7488]: info: Initializing BBC Radios Feb 17 11:18:48 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:18:48 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:18:48 dm804 sudo[7626]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: Creating Spotify config file Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: Volumio Calling Home Feb 17 11:18:48 dm804 sudo[7647]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 11:18:48 dm804 sudo[7647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 11:18:48 dm804 sudo[7647]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:48 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:48.344Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.54.114:50824 @ 0x335a9c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 17 11:18:48 dm804 volumio[7577]: Starting albumart workers Feb 17 11:18:48 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:48 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 17 11:18:48 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:48 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:48 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:48 dm804 volumio[7488]: verbose: New Socket.io Connection to 192.168.54.140 from 192.168.54.114 UA: Mozilla/5.0 (Linux; Android 16; SM-A256B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 17 11:18:48 dm804 volumio[7488]: info: MPD Permissions set Feb 17 11:18:48 dm804 volumio[7576]: Starting albumart workers Feb 17 11:18:48 dm804 volumio[7488]: info: MPD Permissions set Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:18:48 dm804 volumio-remote-updater[783]: [2026-02-17 10:18:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771323527 101 Feb 17 11:18:48 dm804 volumio[7488]: 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: 4 Feb 17 11:18:48 dm804 volumio[7488]: info: Volumio called home Feb 17 11:18:48 dm804 volumio[7488]: info: Spotify config file written Feb 17 11:18:48 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 17 11:18:48 dm804 volumio[7575]: Starting albumart workers Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 sudo[7664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 11:18:48 dm804 sudo[7664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:18:48 dm804 volumio[7488]: info: No need to fix Spotify hosts Feb 17 11:18:48 dm804 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 11:18:48 dm804 systemd[1]: go-librespot-daemon.service: Killing process 7520 (go-librespot) with signal SIGKILL. Feb 17 11:18:48 dm804 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 11:18:48 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:48 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:18:48 dm804 go-librespot[7676]: go-librespot daemon starting... Feb 17 11:18:48 dm804 sudo[7664]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:48 dm804 go-librespot[7677]: time="2026-02-17T11:18:48+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:18:48 dm804 go-librespot[7677]: time="2026-02-17T11:18:48+01:00" level=debug msg="app state loaded" Feb 17 11:18:48 dm804 go-librespot[7677]: time="2026-02-17T11:18:48+01:00" level=debug msg="stored credentials not found" Feb 17 11:18:48 dm804 go-librespot[7677]: time="2026-02-17T11:18:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:18:48 dm804 volumio[7488]: info: Starting Shairport Sync Feb 17 11:18:48 dm804 volumio[7488]: info: Starting Shairport Sync Feb 17 11:18:48 dm804 volumio[7488]: info: Starting Shairport Sync Feb 17 11:18:48 dm804 sudo[7686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:49 dm804 sudo[7686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:49 dm804 sudo[7688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:49 dm804 sudo[7688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 sudo[7690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:18:49 dm804 sudo[7690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:49 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:18:49 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:18:49 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:49 dm804 systemd[1]: shairport-sync.service: Consumed 1.651s CPU time. Feb 17 11:18:49 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:49 dm804 sudo[7686]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:49 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:18:49 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:18:49 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:49 dm804 sudo[7690]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:49 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:18:49 dm804 sudo[7688]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: Listing playlists Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: Listing playlists Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: Listing playlists Feb 17 11:18:49 dm804 volumio[7488]: info: Received Get System Info Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:18:49 dm804 volumio[7488]: info: Discovery: Getting this device information Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:49 dm804 volumio[7488]: info: Shairport-Sync Started Feb 17 11:18:49 dm804 volumio[7488]: Error adding Membership: Error: addMembership EINVAL Feb 17 11:18:49 dm804 volumio[7488]: info: Shairport-Sync Started Feb 17 11:18:49 dm804 volumio[7488]: info: Shairport-Sync Started Feb 17 11:18:49 dm804 volumio[7488]: verbose: New Socket.io Connection to 192.168.54.140:3000 from 192.168.54.114 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:18:49 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:49 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:50 dm804 mpd[7660]: 2026-02-17T11:18:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 11:18:50 dm804 systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 11:18:50 dm804 sudo[7630]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:50 dm804 sudo[7619]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:50 dm804 volumio[7488]: info: Completed starting Core Plugins Feb 17 11:18:50 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:50 dm804 volumio[7488]: info: ----- MyVolumio plugins startup ---- Feb 17 11:18:50 dm804 volumio[7488]: info: ------------------------------------------- Feb 17 11:18:50 dm804 volumio[7488]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 11:18:50 dm804 volumio[7488]: error: MPD error: The expression evaluated to a falsy value: Feb 17 11:18:50 dm804 volumio[7488]: assert.ok(self.idling) Feb 17 11:18:50 dm804 volumio[7488]: error: The expression evaluated to a falsy value: Feb 17 11:18:50 dm804 volumio[7488]: assert.ok(self.idling) Feb 17 11:18:50 dm804 volumio[7488]: error: updateQueue error: null Feb 17 11:18:50 dm804 volumio[7488]: info: MPD running with PID7660 Feb 17 11:18:50 dm804 volumio[7488]: ,establishing connection Feb 17 11:18:50 dm804 volumio[7488]: error: updateQueue error: null Feb 17 11:18:51 dm804 volumio[7488]: info: go-librespot daemon successfully initialized Feb 17 11:18:54 dm804 volumio[7488]: info: Initializing connection to go-librespot Websocket Feb 17 11:18:54 dm804 go-librespot[7677]: time="2026-02-17T11:18:54+01:00" level=debug msg="new websocket client" Feb 17 11:18:54 dm804 volumio[7488]: info: Connection to go-librespot Websocket established Feb 17 11:18:55 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:55.208Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.54.114:39760 Feb 17 11:18:55 dm804 volumio[7488]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 11:18:55 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:55.221Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.54.114:39760 Feb 17 11:18:55 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:55.221Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.54.114:39760 Feb 17 11:18:55 dm804 sudo[7750]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:18:55 dm804 sudo[7752]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:18:55 dm804 sudo[7750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:55 dm804 sudo[7752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:55 dm804 sudo[7752]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:55 dm804 sudo[7750]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:55 dm804 sudo[7756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 11:18:55 dm804 sudo[7756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:55 dm804 sudo[7756]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:55 dm804 volumio[7488]: info: Upmpdcli Daemon Started Feb 17 11:18:56 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:56.245Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.54.114:39772 Feb 17 11:18:56 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:56.332Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.54.114:39772 Feb 17 11:18:56 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:56.332Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.54.114:39772 Feb 17 11:18:57 dm804 volumio[7488]: info: Getting Spotify volume Feb 17 11:18:57 dm804 volumio[7488]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 17 11:18:57 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:57 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:57 dm804 volumio[7488]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 17 11:18:57 dm804 volumio[7488]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 11:18:57 dm804 volumio[7488]: SPOTIFY: VOLUMIO VOLUME 86 Feb 17 11:18:57 dm804 volumio[7488]: info: Aligning Spotify Volume to Volumio Volume Feb 17 11:18:57 dm804 volumio[7488]: info: CoreCommandRouter::volumioGetState Feb 17 11:18:57 dm804 volumio[7488]: info: CorePlayQueue::getTrack 0 Feb 17 11:18:57 dm804 volumio[7488]: info: Setting Spotify Volume from Volumio: 86 Feb 17 11:18:58 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:58.270Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.54.114:39800 Feb 17 11:18:58 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:58.291Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.54.114:39800 Feb 17 11:18:58 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:18:58.291Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.54.114:39800 Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 11:18:58 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:58 dm804 volumio[7488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:18:58 dm804 go-librespot[7677]: time="2026-02-17T11:18:58+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Feb 17 11:18:58 dm804 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:18:58 dm804 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 11:18:58 dm804 volumio[7488]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 11:18:58 dm804 volumio[7488]: info: MyVolumio login type: Token Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 11:18:58 dm804 volumio[7488]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 11:18:59 dm804 volumio[7488]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 11:18:59 dm804 volumio[7488]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 11:18:59 dm804 volumio[7488]: info: Streaming services startup Feb 17 11:18:59 dm804 volumio[7488]: info: Starting Streaming Daemon Feb 17 11:18:59 dm804 sudo[7760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 11:18:59 dm804 sudo[7760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:18:59 dm804 volumio[7488]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 11:18:59 dm804 sudo[7760]: pam_unix(sudo:session): session closed for user root Feb 17 11:18:59 dm804 volumio[7488]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Feb 17 11:18:59 dm804 volumio[7488]: info: Sending Spotify command with payload to local API: /player/volume Feb 17 11:18:59 dm804 volumio[7488]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:59 dm804 volumio[7488]: Error: socket hang up Feb 17 11:18:59 dm804 volumio[7488]: at connResetException (node:internal/errors:720:14) Feb 17 11:18:59 dm804 volumio[7488]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 11:18:59 dm804 volumio[7488]: at Socket.emit (node:events:526:35) Feb 17 11:18:59 dm804 volumio[7488]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 11:18:59 dm804 volumio[7488]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 11:18:59 dm804 volumio[7488]: code: 'ECONNRESET', Feb 17 11:18:59 dm804 volumio[7488]: response: undefined Feb 17 11:18:59 dm804 volumio[7488]: } Feb 17 11:18:59 dm804 volumio[7488]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:18:59 dm804 sudo[7780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 11:17' Feb 17 11:18:59 dm804 sudo[7780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:00 dm804 sudo[7780]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:00 dm804 volumio-remote-updater[783]: [2026-02-17 10:19:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 17 11:19:00 dm804 volumio-remote-updater[783]: [2026-02-17 10:19:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 17 11:19:00 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:19:00.196Z level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 17 11:19:00 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:19:00.197Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="write tcp 127.0.0.1:50320->127.0.0.1:3000: write: connection reset by peer" Feb 17 11:19:00 dm804 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:19:00 dm804 systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 11:19:00 dm804 systemd[1]: volumio.service: Consumed 15.674s CPU time. Feb 17 11:19:00 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:19:00 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:19:00 dm804 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Feb 17 11:19:00 dm804 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 11:19:00 dm804 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 11:19:00 dm804 systemd[1]: volumio.service: Consumed 15.674s CPU time. Feb 17 11:19:00 dm804 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 11:19:00 dm804 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 11:19:01 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:19:01.198Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 17 11:19:01 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:01 dm804 volumio[7793]: info: ----- Volumio3 ---- Feb 17 11:19:01 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:01 dm804 volumio[7793]: info: ----- System startup ---- Feb 17 11:19:01 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:02 dm804 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 11:19:02 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:19:02 dm804 volumio[7793]: info: MYVOLUMIO Environment detected Feb 17 11:19:02 dm804 volumio[7793]: info: Plugin folders cleanup Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning into folder /volumio/app/plugins/ Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category audio_interface Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category miscellanea Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category music_service Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category plugins.json Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category system_controller Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category user_interface Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning into folder /data/plugins/ Feb 17 11:19:02 dm804 volumio[7793]: info: Scanning category music_service Feb 17 11:19:02 dm804 volumio[7793]: info: Plugin folders cleanup completed Feb 17 11:19:02 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:02 dm804 volumio[7793]: info: ----- Core plugins startup ---- Feb 17 11:19:02 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 11:19:02 dm804 volumio[7793]: info: Adding plugin upnp to MyMusic Plugins Feb 17 11:19:02 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:19:02 dm804 go-librespot[7814]: go-librespot daemon starting... Feb 17 11:19:02 dm804 volumio[7793]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 11:19:02 dm804 volumio[7793]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugins from folder /data/plugins/ Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "system"... Feb 17 11:19:02 dm804 go-librespot[7818]: time="2026-02-17T11:19:02+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:19:02 dm804 go-librespot[7818]: time="2026-02-17T11:19:02+01:00" level=debug msg="app state loaded" Feb 17 11:19:02 dm804 go-librespot[7818]: time="2026-02-17T11:19:02+01:00" level=debug msg="stored credentials not found" Feb 17 11:19:02 dm804 go-librespot[7818]: time="2026-02-17T11:19:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "appearance"... Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "network"... Feb 17 11:19:02 dm804 volumio[7793]: info: Refreshing Cached IP Addresses Feb 17 11:19:02 dm804 sudo[7831]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:19:02 dm804 sudo[7831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:02 dm804 sudo[7833]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:19:02 dm804 sudo[7833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "services"... Feb 17 11:19:02 dm804 sudo[7831]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "volumio5onboarding"... Feb 17 11:19:02 dm804 sudo[7833]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "alsa_controller"... Feb 17 11:19:02 dm804 sudo[7841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 11:19:02 dm804 sudo[7841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:02 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "wizard"... Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "networkfs"... Feb 17 11:19:02 dm804 volumio[7793]: info: Starting Udev Watcher for removable devices Feb 17 11:19:02 dm804 volumio[7793]: info: Ignoring mount for partition: boot Feb 17 11:19:02 dm804 volumio[7793]: info: Ignoring mount for partition: volumio Feb 17 11:19:02 dm804 volumio[7793]: info: Ignoring mount for partition: volumio_data Feb 17 11:19:02 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "volumio_command_line_client"... Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "upnp"... Feb 17 11:19:02 dm804 volumio[7793]: info: [1771323542835] Starting Upmpd Daemon Feb 17 11:19:02 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "my_music"... Feb 17 11:19:02 dm804 volumio[7793]: info: Loading plugin "mpd"... Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "upnp_browser"... Feb 17 11:19:03 dm804 volumio[7793]: info: Starting UPNP Browser Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "alarm-clock"... Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "airplay_emulation"... Feb 17 11:19:03 dm804 volumio[7793]: info: Starting Shairport Sync Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "last_100"... Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "webradio"... Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "i2s_dacs"... Feb 17 11:19:03 dm804 volumio[7793]: info: I2S DAC not set, start Auto-detection Feb 17 11:19:03 dm804 volumio[7793]: info: Loading plugin "volumiodiscovery"... Feb 17 11:19:03 dm804 volumio[7793]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:19:03 dm804 volumio[7793]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:19:03 dm804 volumio[7793]: *** WARNING *** For more information see Feb 17 11:19:03 dm804 volumio[7793]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:19:03 dm804 volumio[7793]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:19:03 dm804 node[7793]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 11:19:03 dm804 volumio[7793]: *** WARNING *** For more information see Feb 17 11:19:03 dm804 node[7793]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:19:03 dm804 node[7793]: *** WARNING *** For more information see Feb 17 11:19:03 dm804 node[7793]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 11:19:03 dm804 node[7793]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 11:19:03 dm804 node[7793]: *** WARNING *** For more information see Feb 17 11:19:03 dm804 volumio[7793]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 11:19:03 dm804 volumio[7793]: info: Discovery: Started advertising with name: DM804 Feb 17 11:19:03 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "spop"... Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "outputs"... Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "albumart"... Feb 17 11:19:04 dm804 volumio[7793]: info: Plugin example_plugin is not enabled Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "inputs"... Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "updater_comm"... Feb 17 11:19:04 dm804 volumio[7793]: info: Plugin mpdemulation is not enabled Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "rest_api"... Feb 17 11:19:04 dm804 volumio[7793]: info: Loading plugin "websocket"... Feb 17 11:19:04 dm804 volumio[7793]: info: Starting Socket.io Server version 1.7.4 Feb 17 11:19:04 dm804 volumio[7793]: info: Loading i18n strings for locale fr Feb 17 11:19:04 dm804 volumio[7793]: Updating browse sources language Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::initPlayerControls Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: Express server listening on port 3000 Feb 17 11:19:04 dm804 volumio[7793]: [Metrics] WebUI: 3s 410.02ms Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::resetVolumioState Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::getcurrentVolume Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:04 dm804 volumio[7793]: info: Cannot read play queue from file Feb 17 11:19:04 dm804 volumio[7793]: info: Volumio Network Manager: Network status updated: 1 Feb 17 11:19:04 dm804 volumio[7866]: Forking 3 albumart workers Feb 17 11:19:04 dm804 volumio[7793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 17 11:19:04 dm804 volumio[7793]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::pushState Feb 17 11:19:04 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::volumioPushState Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::updateTrackBlock Feb 17 11:19:04 dm804 volumio[7793]: info: CorePlayQueue::getTrackBlock Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 11:19:04 dm804 volumio[7793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::setRepeat null single undefined Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::pushState Feb 17 11:19:04 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::volumioPushState Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::setRandom null Feb 17 11:19:04 dm804 volumio[7793]: info: CoreStateMachine::pushState Feb 17 11:19:04 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:04 dm804 volumio[7793]: info: CoreCommandRouter::volumioPushState Feb 17 11:19:04 dm804 volumio[7793]: info: Setting Device type: Raspberry PI Feb 17 11:19:05 dm804 volumio[7793]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 17 11:19:05 dm804 volumio[7793]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Feb 17 11:19:05 dm804 volumio[7793]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 17 11:19:05 dm804 volumio[7793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 17 11:19:05 dm804 volumio[7793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 17 11:19:05 dm804 volumio[7793]: info: VolumeController:: Volume=86 Mute =false Feb 17 11:19:05 dm804 volumio[7793]: info: CoreStateMachine::pushState Feb 17 11:19:05 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::volumioPushState Feb 17 11:19:05 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:19:05.072Z level=INFO msg="emitting player state changed event" component=server peer="192.168.54.114:50824 @ 0x335a9c0" state=STATUS_STOPPED positionMs=0 volume=86 Feb 17 11:19:05 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:19:05.072Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.54.114:50824 @ 0x335a9c0" id= title= Feb 17 11:19:05 dm804 volumio[7793]: info: Completed loading Core Plugins Feb 17 11:19:05 dm804 volumio[7793]: info: Preparing to generate the ALSA configuration file Feb 17 11:19:05 dm804 volumio[7793]: info: Discovery: adding 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:19:05 dm804 volumio[7793]: info: Discovery: Found device DM804 Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::volumioGetState Feb 17 11:19:05 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:05 dm804 volumio[7793]: info: Asound.conf file unchanged, so no further update is needed Feb 17 11:19:05 dm804 volumio[7793]: info: Output device has changed, restarting MPD Feb 17 11:19:05 dm804 volumio[7793]: info: Output device has changed, restarting Shairport Sync Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:05 dm804 sudo[7922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:19:05 dm804 sudo[7922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:05 dm804 sudo[7922]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:05 dm804 sudo[7924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:19:05 dm804 volumio[7793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:19:05 dm804 volumio[7793]: info: ___________ START PLUGINS ___________ Feb 17 11:19:05 dm804 sudo[7924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:05 dm804 volumio-remote-updater[783]: [2026-02-17 10:19:05] [connect] Successful connection Feb 17 11:19:05 dm804 volumio[7793]: info: ControllerMpd::onStart: Initializing MPD Feb 17 11:19:05 dm804 volumio[7793]: info: Creating MPD Configuration file Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:19:05 dm804 volumio[7793]: info: [1771323545222] CoreMusicLibrary::Adding element Serveurs Média Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:19:05 dm804 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 11:19:05 dm804 sudo[7932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 17 11:19:05 dm804 sudo[7932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:05 dm804 sudo[7934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 11:19:05 dm804 sudo[7934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:05 dm804 sudo[7936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 11:19:05 dm804 sudo[7936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:05 dm804 volumio[7793]: info: UPNP Browser: Client initialized successfully Feb 17 11:19:05 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:19:05 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:19:05 dm804 systemd[1]: mpd.service: Consumed 1.959s CPU time. Feb 17 11:19:05 dm804 sudo[7934]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:05 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:19:05 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:19:05 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:19:05 dm804 sudo[7841]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:05 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:19:05 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:19:05 dm804 volumio[7793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:19:05 dm804 volumio[7793]: info: [1771323545383] CoreMusicLibrary::Adding element Last_100 Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 11:19:05 dm804 volumio[7793]: info: [1771323545385] CoreMusicLibrary::Adding element Webradio Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:19:05 dm804 systemd[1]: mpd.service: Deactivated successfully. Feb 17 11:19:05 dm804 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 11:19:05 dm804 sudo[7932]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:05 dm804 systemd[1]: mpd.socket: Deactivated successfully. Feb 17 11:19:05 dm804 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 11:19:05 dm804 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 11:19:05 dm804 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 11:19:05 dm804 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 11:19:05 dm804 volumio[7793]: info: Initializing BBC Radios Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: Creating Spotify config file Feb 17 11:19:05 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:05 dm804 volumio[7793]: info: Volumio Calling Home Feb 17 11:19:05 dm804 sudo[7951]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 11:19:05 dm804 sudo[7951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 11:19:05 dm804 sudo[7951]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:05 dm804 volumio5-onboarding[1802]: time=2026-02-17T10:19:05.828Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.54.114:50824 @ 0x335a9c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 17 11:19:05 dm804 volumio[7882]: Starting albumart workers Feb 17 11:19:05 dm804 volumio[7881]: Starting albumart workers Feb 17 11:19:06 dm804 volumio[7793]: info: Discovery: this is already registered, 70887692-4448-44a4-bc23-2843836cbcb2 Feb 17 11:19:06 dm804 volumio[7793]: info: Discovery: Found device DM804 Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::volumioGetState Feb 17 11:19:06 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:06 dm804 volumio[7793]: info: Received Get System Info Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:19:06 dm804 volumio[7793]: info: Discovery: Getting this device information Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::volumioGetState Feb 17 11:19:06 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:19:06 dm804 volumio[7883]: Starting albumart workers Feb 17 11:19:06 dm804 volumio[7793]: info: MPD Permissions set Feb 17 11:19:06 dm804 volumio[7793]: info: MPD Permissions set Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:19:06 dm804 volumio-remote-updater[783]: [2026-02-17 10:19:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771323545 101 Feb 17 11:19:06 dm804 volumio[7793]: 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 Feb 17 11:19:06 dm804 volumio[7793]: info: Volumio called home Feb 17 11:19:06 dm804 volumio[7793]: info: Spotify config file written Feb 17 11:19:06 dm804 sudo[7974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 11:19:06 dm804 sudo[7974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:06 dm804 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 11:19:06 dm804 volumio[7793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 17 11:19:06 dm804 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:19:06 dm804 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 11:19:06 dm804 go-librespot[7980]: go-librespot daemon starting... Feb 17 11:19:06 dm804 sudo[7974]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 go-librespot[7987]: time="2026-02-17T11:19:06+01:00" level=info msg="running go-librespot 0.6.2" Feb 17 11:19:06 dm804 go-librespot[7987]: time="2026-02-17T11:19:06+01:00" level=debug msg="app state loaded" Feb 17 11:19:06 dm804 go-librespot[7987]: time="2026-02-17T11:19:06+01:00" level=debug msg="stored credentials not found" Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 11:19:06 dm804 go-librespot[7987]: time="2026-02-17T11:19:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 11:19:06 dm804 volumio[7793]: info: No need to fix Spotify hosts Feb 17 11:19:06 dm804 volumio[7793]: info: Starting Shairport Sync Feb 17 11:19:06 dm804 volumio[7793]: info: Starting Shairport Sync Feb 17 11:19:06 dm804 volumio[7793]: info: Starting Shairport Sync Feb 17 11:19:06 dm804 sudo[8005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:19:06 dm804 sudo[8005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:06 dm804 sudo[8009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:19:06 dm804 sudo[8009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:06 dm804 sudo[8008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 11:19:06 dm804 sudo[8008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:06 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:19:06 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:19:06 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:19:06 dm804 systemd[1]: shairport-sync.service: Consumed 1.549s CPU time. Feb 17 11:19:06 dm804 volumio[7793]: info: CoreCommandRouter::volumioGetState Feb 17 11:19:06 dm804 volumio[7793]: info: CorePlayQueue::getTrack 0 Feb 17 11:19:06 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:19:06 dm804 sudo[8005]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:06 dm804 sudo[8009]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:06 dm804 volumio[7793]: info: Shairport-Sync Started Feb 17 11:19:06 dm804 volumio[7793]: Error adding Membership: Error: addMembership EINVAL Feb 17 11:19:06 dm804 volumio[7793]: info: Shairport-Sync Started Feb 17 11:19:06 dm804 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 11:19:06 dm804 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 11:19:06 dm804 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:19:06 dm804 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 11:19:06 dm804 sudo[8008]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:06 dm804 volumio[7793]: info: Shairport-Sync Started Feb 17 11:19:07 dm804 mpd[7968]: 2026-02-17T11:19:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 11:19:07 dm804 systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 11:19:07 dm804 sudo[7924]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:07 dm804 sudo[7936]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:07 dm804 volumio[7793]: info: Completed starting Core Plugins Feb 17 11:19:07 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:07 dm804 volumio[7793]: info: ----- MyVolumio plugins startup ---- Feb 17 11:19:07 dm804 volumio[7793]: info: ------------------------------------------- Feb 17 11:19:07 dm804 volumio[7793]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 11:19:07 dm804 volumio[7793]: error: MPD error: The expression evaluated to a falsy value: Feb 17 11:19:07 dm804 volumio[7793]: assert.ok(self.idling) Feb 17 11:19:07 dm804 volumio[7793]: error: The expression evaluated to a falsy value: Feb 17 11:19:07 dm804 volumio[7793]: assert.ok(self.idling) Feb 17 11:19:07 dm804 volumio[7793]: error: updateQueue error: null Feb 17 11:19:07 dm804 volumio[7793]: info: MPD running with PID7968 Feb 17 11:19:07 dm804 volumio[7793]: ,establishing connection Feb 17 11:19:07 dm804 volumio[7793]: error: updateQueue error: null Feb 17 11:19:09 dm804 volumio[7793]: info: go-librespot daemon successfully initialized Feb 17 11:19:12 dm804 volumio[7793]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 11:19:12 dm804 volumio[7793]: info: Initializing connection to go-librespot Websocket Feb 17 11:19:12 dm804 go-librespot[7987]: time="2026-02-17T11:19:12+01:00" level=debug msg="new websocket client" Feb 17 11:19:12 dm804 volumio[7793]: info: Connection to go-librespot Websocket established Feb 17 11:19:12 dm804 sudo[8054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:19:12 dm804 sudo[8054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:12 dm804 sudo[8054]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:12 dm804 sudo[8056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:19:12 dm804 sudo[8056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:12 dm804 sudo[8056]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:12 dm804 sudo[8059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 11:19:12 dm804 sudo[8059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:12 dm804 sudo[8059]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:12 dm804 volumio[7793]: info: Upmpdcli Daemon Started Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 11:19:15 dm804 volumio[7793]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 11:19:16 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:16 dm804 volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:19:16 dm804 volumio[7793]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 11:19:16 dm804 volumio[7793]: info: MyVolumio login type: Token Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 11:19:16 dm804 go-librespot[7987]: time="2026-02-17T11:19:16+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Feb 17 11:19:16 dm804 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 11:19:16 dm804 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 11:19:16 dm804 volumio[7793]: info: Streaming services startup Feb 17 11:19:16 dm804 volumio[7793]: info: Starting Streaming Daemon Feb 17 11:19:16 dm804 sudo[8064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 11:19:16 dm804 volumio[7793]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 11:19:16 dm804 sudo[8064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 11:19:16 dm804 sudo[8064]: pam_unix(sudo:session): session closed for user root Feb 17 11:19:16 dm804 volumio[7793]: info: Getting Spotify volume Feb 17 11:19:16 dm804 volumio[7793]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:19:16 dm804 volumio[7793]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 11:19:16 dm804 volumio[7793]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 11:19:16 dm804 volumio[7793]: errno: -111, Feb 17 11:19:16 dm804 volumio[7793]: code: 'ECONNREFUSED', Feb 17 11:19:16 dm804 volumio[7793]: syscall: 'connect', Feb 17 11:19:16 dm804 volumio[7793]: address: '127.0.0.1', Feb 17 11:19:16 dm804 volumio[7793]: port: 9879, Feb 17 11:19:16 dm804 volumio[7793]: response: undefined Feb 17 11:19:16 dm804 volumio[7793]: } Feb 17 11:19:16 dm804 volumio[7793]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:19:17 dm804 sudo[8084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 11:18' Feb 17 11:19:17 dm804 sudo[8084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"