Jan 11 11:34:00 volumio volumio-remote-updater[741]: [2026-01-11 11:34:00] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Jan 11 11:34:00 volumio volumio-remote-updater[741]: [2026-01-11 11:34:00] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Jan 11 11:34:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 11:34:00 volumio systemd[1]: volumio.service: Consumed 14.813s CPU time. Jan 11 11:34:01 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 11 11:34:01 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 11 11:34:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 36. Jan 11 11:34:01 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 11 11:34:01 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 11 11:34:01 volumio systemd[1]: volumio.service: Consumed 14.813s CPU time. Jan 11 11:34:01 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 11 11:34:01 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 11 11:34:01 volumio ntpd[1026]: IO: Deleting interface #15 eth0, 192.168.0.28#123, interface stats: received=0, sent=0, dropped=0, active_time=63 secs Jan 11 11:34:01 volumio ntpd[1026]: PROTO: 195.178.58.245 unlink local addr 192.168.0.28 -> Jan 11 11:34:01 volumio ntpd[1026]: PROTO: 195.252.108.16 unlink local addr 192.168.0.28 -> Jan 11 11:34:01 volumio ntpd[1026]: PROTO: 217.24.20.5 unlink local addr 192.168.0.28 -> Jan 11 11:34:01 volumio ntpd[1026]: PROTO: 147.91.26.20 unlink local addr 192.168.0.28 -> Jan 11 11:34:01 volumio ntpd[1026]: PROTO: 147.91.8.1 unlink local addr 192.168.0.28 -> Jan 11 11:34:01 volumio ntpd[1026]: PROTO: 195.250.114.105 unlink local addr 192.168.0.28 -> Jan 11 11:34:02 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:02 volumio volumio[24687]: info: ----- Volumio3 ---- Jan 11 11:34:02 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:02 volumio volumio[24687]: info: ----- System startup ---- Jan 11 11:34:02 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:02 volumio volumio[24687]: info: MYVOLUMIO Environment detected Jan 11 11:34:02 volumio volumio[24687]: info: Plugin folders cleanup Jan 11 11:34:02 volumio volumio[24687]: info: Scanning into folder /volumio/app/plugins/ Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category audio_interface Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category miscellanea Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category music_service Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category plugins.json Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category system_controller Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category user_interface Jan 11 11:34:02 volumio volumio[24687]: info: Scanning into folder /data/plugins/ Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category music_service Jan 11 11:34:02 volumio volumio[24687]: info: Scanning category user_interface Jan 11 11:34:02 volumio volumio[24687]: info: Plugin folders cleanup completed Jan 11 11:34:02 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:02 volumio volumio[24687]: info: ----- Core plugins startup ---- Jan 11 11:34:02 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:02 volumio volumio[24687]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 11:34:02 volumio volumio[24687]: info: Adding plugin upnp to MyMusic Plugins Jan 11 11:34:02 volumio volumio[24687]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 11:34:02 volumio volumio[24687]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 11:34:02 volumio volumio[24687]: info: Loading plugins from folder /data/plugins/ Jan 11 11:34:02 volumio volumio[24687]: info: Loading plugin "system"... Jan 11 11:34:02 volumio volumio[24687]: info: Loading plugin "appearance"... Jan 11 11:34:03 volumio go-librespot[24622]: time="2026-01-11T11:34:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:34:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "network"... Jan 11 11:34:03 volumio volumio[24687]: info: Refreshing Cached IP Addresses Jan 11 11:34:03 volumio sudo[24715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "services"... Jan 11 11:34:03 volumio sudo[24715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "alsa_controller"... Jan 11 11:34:03 volumio sudo[24717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 11:34:03 volumio sudo[24717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:03 volumio sudo[24715]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:03 volumio sudo[24717]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:03 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "wizard"... Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "networkfs"... Jan 11 11:34:03 volumio volumio[24687]: info: Cannot mount NAS DSD at system boot, trial number 1 ,retrying in 5 seconds Jan 11 11:34:03 volumio volumio[24687]: info: Starting Udev Watcher for removable devices Jan 11 11:34:03 volumio volumio[24687]: info: Ignoring mount for partition: boot Jan 11 11:34:03 volumio volumio[24687]: info: Ignoring mount for partition: volumio Jan 11 11:34:03 volumio volumio[24687]: info: Ignoring mount for partition: volumio_data Jan 11 11:34:03 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "volumio_command_line_client"... Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "upnp"... Jan 11 11:34:03 volumio volumio[24687]: info: [1768127643341] Starting Upmpd Daemon Jan 11 11:34:03 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "my_music"... Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "mpd"... Jan 11 11:34:03 volumio volumio[24687]: info: Loading plugin "upnp_browser"... Jan 11 11:34:04 volumio volumio[24687]: info: Starting UPNP Browser Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "alarm-clock"... Jan 11 11:34:04 volumio volumio[24687]: info: Plugin airplay_emulation is not enabled Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "last_100"... Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "webradio"... Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "i2s_dacs"... Jan 11 11:34:04 volumio volumio[24687]: info: I2S DAC not set, start Auto-detection Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "volumiodiscovery"... Jan 11 11:34:04 volumio volumio[24687]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:04 volumio volumio[24687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:04 volumio volumio[24687]: *** WARNING *** For more information see Jan 11 11:34:04 volumio volumio[24687]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:04 volumio volumio[24687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:04 volumio volumio[24687]: *** WARNING *** For more information see Jan 11 11:34:04 volumio node[24687]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:04 volumio node[24687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:04 volumio node[24687]: *** WARNING *** For more information see Jan 11 11:34:04 volumio node[24687]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:04 volumio node[24687]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:04 volumio node[24687]: *** WARNING *** For more information see Jan 11 11:34:04 volumio volumio[24687]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 11:34:04 volumio volumio[24687]: info: Discovery: Started advertising with name: Volumio Jan 11 11:34:04 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "mixcloud"... Jan 11 11:34:04 volumio volumio[24687]: info: Loading plugin "spop"... Jan 11 11:34:04 volumio dhcpcd[794]: eth0: probing for an IPv4LL address Jan 11 11:34:04 volumio dhcpcd[674]: eth0: probing for an IPv4LL address Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "outputs"... Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "albumart"... Jan 11 11:34:05 volumio volumio[24687]: info: Plugin example_plugin is not enabled Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "inputs"... Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "updater_comm"... Jan 11 11:34:05 volumio volumio[24687]: info: Plugin mpdemulation is not enabled Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "rest_api"... Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "websocket"... Jan 11 11:34:05 volumio volumio[24687]: info: Starting Socket.io Server version 1.7.4 Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "motherearthradio"... Jan 11 11:34:05 volumio volumio[24687]: info: Applying required configuration parameters for plugin motherearthradio Jan 11 11:34:05 volumio volumio[24687]: info: [1768127645475] [MotherEarth] API delay: 5 Jan 11 11:34:05 volumio volumio[24687]: info: Loading plugin "Systeminfo"... Jan 11 11:34:05 volumio volumio[24746]: Forking 3 albumart workers Jan 11 11:34:05 volumio volumio[24687]: info: Loading i18n strings for locale en Jan 11 11:34:05 volumio volumio[24687]: Updating browse sources language Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::initPlayerControls Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: Express server listening on port 3000 Jan 11 11:34:05 volumio volumio[24687]: [Metrics] WebUI: 3s 946.87ms Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::resetVolumioState Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::getcurrentVolume Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::pushState Jan 11 11:34:05 volumio volumio[24687]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::volumioPushState Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:05 volumio volumio[24687]: info: Volumio Network Manager: Network status updated: 0 Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::setRepeat null single undefined Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::pushState Jan 11 11:34:05 volumio volumio[24687]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::volumioPushState Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::setRandom null Jan 11 11:34:05 volumio volumio[24687]: info: CoreStateMachine::pushState Jan 11 11:34:05 volumio volumio[24687]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:05 volumio volumio[24687]: info: CoreCommandRouter::volumioPushState Jan 11 11:34:05 volumio volumio[24687]: info: Reloading queue from file Jan 11 11:34:05 volumio volumio[24687]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 11 11:34:05 volumio volumio[24687]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 11 11:34:05 volumio volumio[24687]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 11 11:34:05 volumio volumio[24687]: info: Setting Device type: Raspberry PI Jan 11 11:34:05 volumio volumio-remote-updater[741]: [2026-01-11 11:34:05] [connect] Successful connection Jan 11 11:34:05 volumio volumio-remote-updater[741]: [2026-01-11 11:34:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768127645 101 Jan 11 11:34:06 volumio volumio[24687]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Jan 11 11:34:06 volumio volumio[24687]: info: Completed loading Core Plugins Jan 11 11:34:06 volumio volumio[24687]: info: Preparing to generate the ALSA configuration file Jan 11 11:34:06 volumio volumio[24687]: info: Discovery: adding 93ca9a51-c787-409d-bf9f-180c0b111d7b Jan 11 11:34:06 volumio volumio[24687]: info: Discovery: Found device Volumio Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::volumioGetState Jan 11 11:34:06 volumio volumio[24687]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:06 volumio volumio[24687]: info: Asound.conf file unchanged, so no further update is needed Jan 11 11:34:06 volumio volumio[24687]: info: Output device has changed, restarting MPD Jan 11 11:34:06 volumio volumio[24687]: info: ___________ START PLUGINS ___________ Jan 11 11:34:06 volumio volumio[24687]: info: ControllerMpd::onStart: Initializing MPD Jan 11 11:34:06 volumio sudo[24801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 11:34:06 volumio sudo[24801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:06 volumio sudo[24801]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:06 volumio volumio[24687]: info: Creating MPD Configuration file Jan 11 11:34:06 volumio sudo[24803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 11:34:06 volumio sudo[24803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:06 volumio volumio[24687]: info: [1768127646144] CoreMusicLibrary::Adding element Media Servers Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:06 volumio sudo[24806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 11:34:06 volumio sudo[24806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:06 volumio volumio[24687]: info: UPNP Browser: Client initialized successfully Jan 11 11:34:06 volumio sudo[24806]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:06 volumio sudo[24808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:06 volumio volumio[24687]: info: [1768127646176] CoreMusicLibrary::Adding element Last_100 Jan 11 11:34:06 volumio sudo[24808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:06 volumio volumio[24687]: info: [1768127646189] CoreMusicLibrary::Adding element Webradio Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:34:06 volumio volumio[24687]: info: Initializing BBC Radios Jan 11 11:34:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 11 11:34:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:06 volumio volumio[24687]: info: [1768127646282] CoreMusicLibrary::Adding element Mixcloud Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:06 volumio volumio[24687]: Cannot find translation for source Mixcloud Jan 11 11:34:06 volumio volumio[24687]: info: Creating Spotify config file Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:06 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 11 11:34:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 11:34:06 volumio systemd[1]: mpd.service: Consumed 1.671s CPU time. Jan 11 11:34:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 11:34:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 11:34:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 11:34:06 volumio go-librespot[24814]: go-librespot daemon starting... Jan 11 11:34:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 11:34:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 11:34:06 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 11 11:34:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 11:34:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 11:34:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 11:34:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 11:34:06 volumio go-librespot[24826]: time="2026-01-11T11:34:06+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:34:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 11:34:06 volumio go-librespot[24826]: time="2026-01-11T11:34: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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:34:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 11:34:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:34:06 volumio volumio[24758]: Starting albumart workers Jan 11 11:34:06 volumio sudo[24835]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 11:34:06 volumio sudo[24835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 11:34:06 volumio sudo[24835]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:06 volumio volumio[24756]: Starting albumart workers Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:06 volumio volumio[24687]: info: [1768127646563] CoreMusicLibrary::Adding element Mother Earth Radio Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:06 volumio volumio[24687]: Cannot find translation for source Mixcloud Jan 11 11:34:06 volumio volumio[24687]: Cannot find translation for source Mother Earth Radio Jan 11 11:34:06 volumio volumio[24687]: info: Volumio Calling Home Jan 11 11:34:06 volumio volumio[24757]: Starting albumart workers Jan 11 11:34:06 volumio volumio[24687]: Unhandled rejection Error: No sockets available, cannot start. Jan 11 11:34:06 volumio volumio[24687]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 11 11:34:06 volumio volumio[24687]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 11 11:34:06 volumio volumio[24687]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 11 11:34:06 volumio volumio[24687]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 11 11:34:06 volumio volumio[24687]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 11 11:34:06 volumio volumio[24687]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 11 11:34:06 volumio volumio[24687]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 11 11:34:06 volumio volumio[24687]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 11 11:34:06 volumio volumio[24687]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Jan 11 11:34:06 volumio volumio[24687]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 11 11:34:06 volumio volumio[24687]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 11 11:34:06 volumio volumio[24687]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 11 11:34:06 volumio volumio[24687]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 11 11:34:06 volumio volumio[24687]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 11 11:34:06 volumio volumio[24687]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 11 11:34:06 volumio volumio[24687]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 11 11:34:06 volumio volumio[24687]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jan 11 11:34:06 volumio volumio[24687]: info: MPD Permissions set Jan 11 11:34:06 volumio volumio[24687]: info: MPD Permissions set Jan 11 11:34:06 volumio volumio[24687]: info: Spotify config file written Jan 11 11:34:06 volumio volumio[24687]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio sudo[24842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 11:34:06 volumio sudo[24842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:06 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:06 volumio go-librespot[24849]: go-librespot daemon starting... Jan 11 11:34:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio sudo[24842]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio go-librespot[24850]: time="2026-01-11T11:34:07+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:34:07 volumio go-librespot[24850]: time="2026-01-11T11:34:07+01:00" level=debug msg="app state loaded" Jan 11 11:34:07 volumio go-librespot[24850]: time="2026-01-11T11:34:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 11:34:07 volumio go-librespot[24850]: time="2026-01-11T11:34:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:34:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:07 volumio volumio[24687]: info: No need to fix Spotify hosts Jan 11 11:34:07 volumio volumio[24687]: info: CoreCommandRouter::volumioGetState Jan 11 11:34:07 volumio volumio[24687]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:08 volumio mpd[24837]: 2026-01-11T11:34:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 11:34:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 11:34:08 volumio sudo[24808]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:08 volumio sudo[24803]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:08 volumio volumio[24687]: info: Completed starting Core Plugins Jan 11 11:34:08 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:08 volumio volumio[24687]: info: ----- MyVolumio plugins startup ---- Jan 11 11:34:08 volumio volumio[24687]: info: ------------------------------------------- Jan 11 11:34:08 volumio volumio[24687]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 11 11:34:08 volumio volumio[24687]: error: MPD error: The expression evaluated to a falsy value: Jan 11 11:34:08 volumio volumio[24687]: assert.ok(self.idling) Jan 11 11:34:08 volumio volumio[24687]: error: The expression evaluated to a falsy value: Jan 11 11:34:08 volumio volumio[24687]: assert.ok(self.idling) Jan 11 11:34:08 volumio volumio[24687]: error: updateQueue error: null Jan 11 11:34:08 volumio volumio[24687]: info: MPD running with PID24837 Jan 11 11:34:08 volumio volumio[24687]: ,establishing connection Jan 11 11:34:08 volumio volumio[24687]: error: updateQueue error: null Jan 11 11:34:08 volumio volumio[24687]: info: Cannot mount NAS DSD at system boot, trial number 2 ,retrying in 5 seconds Jan 11 11:34:09 volumio dhcpcd[794]: eth0: using IPv4LL address 169.254.217.53 Jan 11 11:34:09 volumio avahi-daemon[730]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.217.53. Jan 11 11:34:09 volumio avahi-daemon[730]: New relevant interface eth0.IPv4 for mDNS. Jan 11 11:34:09 volumio dhcpcd[794]: eth0: adding route to 169.254.0.0/16 Jan 11 11:34:09 volumio avahi-daemon[730]: Registering new address record for 169.254.217.53 on eth0.IPv4. Jan 11 11:34:09 volumio dhcpcd[794]: eth0: adding default route Jan 11 11:34:09 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:34:09 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:34:09 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:34:09 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:34:09 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:34:09 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:34:09 volumio welcome[24894]: Resolved ip:[1] 169.254.217.53 Jan 11 11:34:09 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:34:09 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:34:09 volumio dhcpcd[674]: eth0: using IPv4LL address 169.254.7.251 Jan 11 11:34:09 volumio dhcpcd[674]: eth0: adding route to 169.254.0.0/16 Jan 11 11:34:09 volumio avahi-daemon[730]: Registering new address record for 169.254.7.251 on eth0.IPv4. Jan 11 11:34:09 volumio dhcpcd[674]: eth0: adding default route Jan 11 11:34:09 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:34:09 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:34:09 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:34:09 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:34:09 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:34:09 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:34:09 volumio welcome[24909]: Resolved ip:[2] 169.254.217.53 169.254.7.251 Jan 11 11:34:09 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:34:09 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:34:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 11 11:34:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:10 volumio volumio[24687]: info: go-librespot daemon successfully initialized Jan 11 11:34:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:10 volumio go-librespot[24914]: go-librespot daemon starting... Jan 11 11:34:10 volumio go-librespot[24915]: time="2026-01-11T11:34:10+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:34:10 volumio go-librespot[24915]: time="2026-01-11T11:34:10+01:00" level=debug msg="app state loaded" Jan 11 11:34:10 volumio go-librespot[24915]: time="2026-01-11T11:34:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 11:34:11 volumio sudo[24923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 11:34:11 volumio sudo[24923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:11 volumio sudo[24925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 11:34:11 volumio sudo[24925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 11:34:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 11:34:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 11:34:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 11:34:11 volumio sudo[24923]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:11 volumio sudo[24925]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:11 volumio mpd_monitor.sh[24928]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 11:34:11 volumio volumio[24687]: info: Successfully started MPD Monitor Jan 11 11:34:11 volumio volumio[24687]: info: Successfully started MPD Monitor Jan 11 11:34:11 volumio ntpd[1026]: IO: Listen normally on 16 eth0 169.254.217.53:123 Jan 11 11:34:11 volumio ntpd[1026]: IO: Listen normally on 17 eth0 169.254.7.251:123 Jan 11 11:34:11 volumio ntpd[1026]: IO: new interface(s) found: waking up resolver Jan 11 11:34:13 volumio volumio[24687]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 11 11:34:13 volumio volumio[24687]: info: Initializing connection to go-librespot Websocket Jan 11 11:34:13 volumio go-librespot[24915]: time="2026-01-11T11:34:13+01:00" level=debug msg="new websocket client" Jan 11 11:34:13 volumio volumio[24687]: info: Connection to go-librespot Websocket established Jan 11 11:34:13 volumio volumio[24687]: info: Volumio Network Manager: Network status updated: 1 Jan 11 11:34:13 volumio volumio[24687]: info: Cannot mount NAS DSD at system boot, trial number 3 ,retrying in 5 seconds Jan 11 11:34:13 volumio sudo[24947]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 11:34:13 volumio sudo[24947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:13 volumio sudo[24949]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 11:34:13 volumio sudo[24949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:13 volumio sudo[24947]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:13 volumio sudo[24949]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:13 volumio sudo[24951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 11 11:34:13 volumio sudo[24951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:13 volumio sudo[24951]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:13 volumio volumio[24687]: info: Upmpdcli Daemon Started Jan 11 11:34:16 volumio volumio[24687]: info: Getting Spotify volume Jan 11 11:34:16 volumio volumio[24687]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=fc3106e42447160aaad541f426e619e6" http://updates.volumio.org/downloader-v1/track-device Jan 11 11:34:16 volumio volumio[24687]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 11 11:34:16 volumio volumio[24687]: Dload Upload Total Spent Left Speed Jan 11 11:34:16 volumio volumio[24687]: [132B blob data] Jan 11 11:34:16 volumio volumio[24687]: retrying in 5 seconds, trial 0 Jan 11 11:34:16 volumio volumio[24687]: info: Volumio Calling Home Jan 11 11:34:18 volumio volumio[24687]: info: Cannot mount NAS DSD at system boot, trial number 4 ,retrying in 5 seconds Jan 11 11:34:22 volumio go-librespot[24915]: time="2026-01-11T11:34:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:34:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:34:22 volumio volumio[24687]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 11:34:22 volumio volumio[24687]: Error: socket hang up Jan 11 11:34:22 volumio volumio[24687]: at connResetException (node:internal/errors:720:14) Jan 11 11:34:22 volumio volumio[24687]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 11 11:34:22 volumio volumio[24687]: at Socket.emit (node:events:526:35) Jan 11 11:34:22 volumio volumio[24687]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 11 11:34:22 volumio volumio[24687]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 11 11:34:22 volumio volumio[24687]: code: 'ECONNRESET', Jan 11 11:34:22 volumio volumio[24687]: response: undefined Jan 11 11:34:22 volumio volumio[24687]: } Jan 11 11:34:22 volumio volumio[24687]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 11:34:22 volumio sudo[24973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 11:33' Jan 11 11:34:22 volumio sudo[24973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:22 volumio sudo[24973]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 11 11:34:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:25 volumio go-librespot[24995]: go-librespot daemon starting... Jan 11 11:34:25 volumio go-librespot[24996]: time="2026-01-11T11:34:25+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:34:25 volumio go-librespot[24996]: time="2026-01-11T11:34:25+01:00" level=debug msg="app state loaded" Jan 11 11:34:25 volumio go-librespot[24996]: time="2026-01-11T11:34:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 11:34:31 volumio volumio-remote-updater[741]: [2026-01-11 11:34:31] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Jan 11 11:34:31 volumio volumio-remote-updater[741]: [2026-01-11 11:34:31] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Jan 11 11:34:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 11:34:31 volumio systemd[1]: volumio.service: Consumed 13.477s CPU time. Jan 11 11:34:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 11 11:34:32 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 11 11:34:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 37. Jan 11 11:34:32 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 11 11:34:32 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 11 11:34:32 volumio systemd[1]: volumio.service: Consumed 13.477s CPU time. Jan 11 11:34:32 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 11 11:34:32 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 11 11:34:33 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:33 volumio volumio[25015]: info: ----- Volumio3 ---- Jan 11 11:34:33 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:33 volumio volumio[25015]: info: ----- System startup ---- Jan 11 11:34:33 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:33 volumio volumio[25015]: info: MYVOLUMIO Environment detected Jan 11 11:34:33 volumio volumio[25015]: info: Plugin folders cleanup Jan 11 11:34:33 volumio volumio[25015]: info: Scanning into folder /volumio/app/plugins/ Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category audio_interface Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category miscellanea Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category music_service Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category plugins.json Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category system_controller Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category user_interface Jan 11 11:34:33 volumio volumio[25015]: info: Scanning into folder /data/plugins/ Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category music_service Jan 11 11:34:33 volumio volumio[25015]: info: Scanning category user_interface Jan 11 11:34:33 volumio volumio[25015]: info: Plugin folders cleanup completed Jan 11 11:34:33 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:33 volumio volumio[25015]: info: ----- Core plugins startup ---- Jan 11 11:34:33 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:33 volumio volumio[25015]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 11:34:33 volumio volumio[25015]: info: Adding plugin upnp to MyMusic Plugins Jan 11 11:34:33 volumio volumio[25015]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 11:34:33 volumio volumio[25015]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 11:34:33 volumio volumio[25015]: info: Loading plugins from folder /data/plugins/ Jan 11 11:34:33 volumio volumio[25015]: info: Loading plugin "system"... Jan 11 11:34:33 volumio volumio[25015]: info: Loading plugin "appearance"... Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "network"... Jan 11 11:34:34 volumio volumio[25015]: info: Refreshing Cached IP Addresses Jan 11 11:34:34 volumio sudo[25043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "services"... Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "alsa_controller"... Jan 11 11:34:34 volumio sudo[25043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:34 volumio sudo[25045]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 11:34:34 volumio sudo[25045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:34 volumio sudo[25043]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:34 volumio sudo[25045]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:34 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "wizard"... Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "networkfs"... Jan 11 11:34:34 volumio volumio[25015]: info: Cannot mount NAS DSD at system boot, trial number 1 ,retrying in 5 seconds Jan 11 11:34:34 volumio volumio[25015]: info: Starting Udev Watcher for removable devices Jan 11 11:34:34 volumio volumio[25015]: info: Ignoring mount for partition: boot Jan 11 11:34:34 volumio volumio[25015]: info: Ignoring mount for partition: volumio Jan 11 11:34:34 volumio volumio[25015]: info: Ignoring mount for partition: volumio_data Jan 11 11:34:34 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "volumio_command_line_client"... Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "upnp"... Jan 11 11:34:34 volumio volumio[25015]: info: [1768127674353] Starting Upmpd Daemon Jan 11 11:34:34 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "my_music"... Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "mpd"... Jan 11 11:34:34 volumio volumio[25015]: info: Loading plugin "upnp_browser"... Jan 11 11:34:35 volumio volumio[25015]: info: Starting UPNP Browser Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "alarm-clock"... Jan 11 11:34:35 volumio volumio[25015]: info: Plugin airplay_emulation is not enabled Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "last_100"... Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "webradio"... Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "i2s_dacs"... Jan 11 11:34:35 volumio volumio[25015]: info: I2S DAC not set, start Auto-detection Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "volumiodiscovery"... Jan 11 11:34:35 volumio volumio[25015]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:35 volumio volumio[25015]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:35 volumio volumio[25015]: *** WARNING *** For more information see Jan 11 11:34:35 volumio volumio[25015]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:35 volumio volumio[25015]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:35 volumio volumio[25015]: *** WARNING *** For more information see Jan 11 11:34:35 volumio node[25015]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:35 volumio node[25015]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:35 volumio node[25015]: *** WARNING *** For more information see Jan 11 11:34:35 volumio node[25015]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 11:34:35 volumio node[25015]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 11:34:35 volumio node[25015]: *** WARNING *** For more information see Jan 11 11:34:35 volumio volumio[25015]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 11:34:35 volumio volumio[25015]: info: Discovery: Started advertising with name: Volumio Jan 11 11:34:35 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "mixcloud"... Jan 11 11:34:35 volumio volumio[25015]: info: Loading plugin "spop"... Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "outputs"... Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "albumart"... Jan 11 11:34:36 volumio volumio[25015]: info: Plugin example_plugin is not enabled Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "inputs"... Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "updater_comm"... Jan 11 11:34:36 volumio volumio[25015]: info: Plugin mpdemulation is not enabled Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "rest_api"... Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "websocket"... Jan 11 11:34:36 volumio volumio[25015]: info: Starting Socket.io Server version 1.7.4 Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "motherearthradio"... Jan 11 11:34:36 volumio volumio[25015]: info: Applying required configuration parameters for plugin motherearthradio Jan 11 11:34:36 volumio volumio[25015]: info: [1768127676527] [MotherEarth] API delay: 5 Jan 11 11:34:36 volumio volumio[25015]: info: Loading plugin "Systeminfo"... Jan 11 11:34:36 volumio volumio[25073]: Forking 3 albumart workers Jan 11 11:34:36 volumio volumio[25015]: info: Loading i18n strings for locale en Jan 11 11:34:36 volumio volumio[25015]: Updating browse sources language Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::initPlayerControls Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: Express server listening on port 3000 Jan 11 11:34:36 volumio volumio[25015]: [Metrics] WebUI: 3s 961.82ms Jan 11 11:34:36 volumio volumio[25015]: info: CoreStateMachine::resetVolumioState Jan 11 11:34:36 volumio volumio[25015]: info: CoreStateMachine::getcurrentVolume Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 11:34:36 volumio volumio[25015]: info: CoreStateMachine::pushState Jan 11 11:34:36 volumio volumio[25015]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::volumioPushState Jan 11 11:34:36 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:36 volumio volumio[25015]: info: Volumio Network Manager: Network status updated: 1 Jan 11 11:34:36 volumio volumio-remote-updater[741]: [2026-01-11 11:34:36] [connect] Successful connection Jan 11 11:34:36 volumio volumio[25015]: info: Reloading queue from file Jan 11 11:34:37 volumio volumio-remote-updater[741]: [2026-01-11 11:34:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1768127676 101 Jan 11 11:34:37 volumio volumio[25015]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Jan 11 11:34:37 volumio volumio[25015]: info: CoreStateMachine::setRepeat null single undefined Jan 11 11:34:37 volumio volumio[25015]: info: CoreStateMachine::pushState Jan 11 11:34:37 volumio volumio[25015]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioPushState Jan 11 11:34:37 volumio volumio[25015]: info: CoreStateMachine::setRandom null Jan 11 11:34:37 volumio volumio[25015]: info: CoreStateMachine::pushState Jan 11 11:34:37 volumio volumio[25015]: info: CorePlayQueue::getTrack 0 Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioPushState Jan 11 11:34:37 volumio volumio[25015]: info: Setting Device type: Raspberry PI Jan 11 11:34:37 volumio volumio[25015]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 11 11:34:37 volumio volumio[25015]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 11 11:34:37 volumio volumio[25015]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 11 11:34:37 volumio volumio[25015]: info: Completed loading Core Plugins Jan 11 11:34:37 volumio volumio[25015]: info: Preparing to generate the ALSA configuration file Jan 11 11:34:37 volumio volumio[25015]: info: Asound.conf file unchanged, so no further update is needed Jan 11 11:34:37 volumio volumio[25015]: info: Output device has changed, restarting MPD Jan 11 11:34:37 volumio volumio[25015]: info: ___________ START PLUGINS ___________ Jan 11 11:34:37 volumio volumio[25015]: info: ControllerMpd::onStart: Initializing MPD Jan 11 11:34:37 volumio volumio[25015]: info: Creating MPD Configuration file Jan 11 11:34:37 volumio sudo[25126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 11:34:37 volumio sudo[25126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 11:34:37 volumio sudo[25128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 11:34:37 volumio sudo[25128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:37 volumio sudo[25126]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:37 volumio volumio[25015]: info: [1768127677220] CoreMusicLibrary::Adding element Media Servers Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:37 volumio sudo[25131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 11:34:37 volumio sudo[25131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:37 volumio volumio[25015]: info: UPNP Browser: Client initialized successfully Jan 11 11:34:37 volumio sudo[25131]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:37 volumio volumio[25015]: info: [1768127677255] CoreMusicLibrary::Adding element Last_100 Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:37 volumio volumio[25015]: info: [1768127677258] CoreMusicLibrary::Adding element Webradio Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:34:37 volumio volumio[25015]: info: Initializing BBC Radios Jan 11 11:34:37 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 11:34:37 volumio sudo[25133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 11:34:37 volumio sudo[25133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:37 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 11 11:34:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 11:34:37 volumio systemd[1]: mpd.service: Consumed 1.623s CPU time. Jan 11 11:34:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 11:34:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 11:34:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:37 volumio volumio[25015]: info: [1768127677350] CoreMusicLibrary::Adding element Mixcloud Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:37 volumio volumio[25015]: Cannot find translation for source Mixcloud Jan 11 11:34:37 volumio volumio[25015]: info: Creating Spotify config file Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 11:34:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 11:34:37 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 11 11:34:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 11:34:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 11:34:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 11:34:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 11:34:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 11:34:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 11:34:37 volumio volumio[25084]: Starting albumart workers Jan 11 11:34:37 volumio sudo[25153]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 11:34:37 volumio sudo[25153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 11:34:37 volumio sudo[25153]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:37 volumio volumio[25083]: Starting albumart workers Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 11:34:37 volumio volumio[25015]: info: [1768127677626] CoreMusicLibrary::Adding element Mother Earth Radio Jan 11 11:34:37 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 11:34:37 volumio volumio[25015]: Cannot find translation for source Mixcloud Jan 11 11:34:37 volumio volumio[25015]: Cannot find translation for source Mother Earth Radio Jan 11 11:34:37 volumio volumio[25015]: info: Volumio Calling Home Jan 11 11:34:37 volumio volumio[25085]: Starting albumart workers Jan 11 11:34:37 volumio volumio[25015]: info: MPD Permissions set Jan 11 11:34:37 volumio volumio[25015]: info: MPD Permissions set Jan 11 11:34:37 volumio volumio[25015]: info: Spotify config file written Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio sudo[25166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 11:34:38 volumio sudo[25166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:38 volumio volumio[25015]: info: No need to fix Spotify hosts Jan 11 11:34:38 volumio go-librespot[24996]: time="2026-01-11T11:34:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:34:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:34:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:39 volumio mpd[25155]: 2026-01-11T11:34:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 11:34:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 11:34:39 volumio sudo[25133]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:39 volumio sudo[25128]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:39 volumio volumio[25015]: info: Completed starting Core Plugins Jan 11 11:34:39 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:39 volumio volumio[25015]: info: ----- MyVolumio plugins startup ---- Jan 11 11:34:39 volumio volumio[25015]: info: ------------------------------------------- Jan 11 11:34:39 volumio volumio[25015]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 11 11:34:39 volumio volumio[25015]: error: MPD error: The expression evaluated to a falsy value: Jan 11 11:34:39 volumio volumio[25015]: assert.ok(self.idling) Jan 11 11:34:39 volumio volumio[25015]: error: The expression evaluated to a falsy value: Jan 11 11:34:39 volumio volumio[25015]: assert.ok(self.idling) Jan 11 11:34:39 volumio volumio[25015]: info: MPD running with PID25155 Jan 11 11:34:39 volumio volumio[25015]: ,establishing connection Jan 11 11:34:39 volumio volumio[25015]: error: updateQueue error: null Jan 11 11:34:39 volumio volumio[25015]: error: updateQueue error: null Jan 11 11:34:39 volumio volumio[25015]: info: Cannot mount NAS DSD at system boot, trial number 2 ,retrying in 5 seconds Jan 11 11:34:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 11 11:34:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:41 volumio go-librespot[25197]: go-librespot daemon starting... Jan 11 11:34:41 volumio sudo[25166]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:41 volumio go-librespot[25198]: time="2026-01-11T11:34:41+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:34:41 volumio go-librespot[25198]: time="2026-01-11T11:34:41+01:00" level=debug msg="app state loaded" Jan 11 11:34:41 volumio go-librespot[25198]: time="2026-01-11T11:34:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 11:34:42 volumio sudo[25205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 11:34:42 volumio sudo[25205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:42 volumio sudo[25207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 11:34:42 volumio sudo[25207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:42 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 11:34:42 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 11:34:42 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 11:34:42 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 11:34:42 volumio sudo[25205]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:42 volumio mpd_monitor.sh[25210]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 11:34:42 volumio volumio[25015]: info: Successfully started MPD Monitor Jan 11 11:34:42 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 11 11:34:42 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 11 11:34:42 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 11 11:34:42 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 11 11:34:42 volumio sudo[25207]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:42 volumio mpd_monitor.sh[25213]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 11:34:42 volumio volumio[25015]: info: Successfully started MPD Monitor Jan 11 11:34:44 volumio volumio[25015]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 11 11:34:44 volumio volumio[25015]: info: Cannot mount NAS DSD at system boot, trial number 3 ,retrying in 5 seconds Jan 11 11:34:44 volumio sudo[25232]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 11:34:44 volumio sudo[25232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:44 volumio sudo[25234]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 11:34:44 volumio sudo[25232]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:44 volumio sudo[25234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:44 volumio sudo[25234]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:44 volumio sudo[25237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 11 11:34:44 volumio sudo[25237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 11:34:44 volumio volumio[25015]: info: go-librespot daemon successfully initialized Jan 11 11:34:44 volumio sudo[25237]: pam_unix(sudo:session): session closed for user root Jan 11 11:34:44 volumio volumio[25015]: info: Upmpdcli Daemon Started Jan 11 11:34:47 volumio volumio[25015]: info: Initializing connection to go-librespot Websocket Jan 11 11:34:49 volumio volumio[25015]: info: Cannot mount NAS DSD at system boot, trial number 4 ,retrying in 5 seconds Jan 11 11:34:53 volumio go-librespot[25198]: time="2026-01-11T11:34:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:34:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:34:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:34:54 volumio volumio[25015]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 11 11:34:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 11 11:34:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:34:57 volumio go-librespot[25255]: go-librespot daemon starting... Jan 11 11:34:57 volumio go-librespot[25256]: time="2026-01-11T11:34:57+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:34:57 volumio go-librespot[25256]: time="2026-01-11T11:34:57+01:00" level=debug msg="app state loaded" Jan 11 11:34:57 volumio go-librespot[25256]: time="2026-01-11T11:34:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 11:34:57 volumio volumio[25015]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=fc3106e42447160aaad541f426e619e6" http://updates.volumio.org/downloader-v1/track-device Jan 11 11:34:57 volumio volumio[25015]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 11 11:34:57 volumio volumio[25015]: Dload Upload Total Spent Left Speed Jan 11 11:34:57 volumio volumio[25015]: [922B blob data] Jan 11 11:34:57 volumio volumio[25015]: retrying in 5 seconds, trial 0 Jan 11 11:34:57 volumio volumio[25015]: info: Volumio Calling Home Jan 11 11:34:59 volumio dhcpcd[794]: eth0: offered 192.168.0.28 from 192.168.0.1 Jan 11 11:34:59 volumio volumio[25015]: error: MyVolumio Plugin failed to start in a timely fashion Jan 11 11:34:59 volumio volumio[25015]: [Metrics] CommandRouter: 25s 957.27ms Jan 11 11:34:59 volumio volumio[25015]: info: CoreCommandRouter::volumiosetStartupVolume Jan 11 11:34:59 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 11:34:59 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:34:59 volumio volumio[25015]: info: CoreCommandRouter::Close All Modals sent Jan 11 11:34:59 volumio volumio[25015]: info: CoreCommandRouter::Close All Modals sent Jan 11 11:35:00 volumio dhcpcd[794]: eth0: probing address 192.168.0.28/24 Jan 11 11:35:00 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 11 11:35:00 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 11:35:00 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 11 11:35:00 volumio volumio[25015]: info: Discovery: adding 93ca9a51-c787-409d-bf9f-180c0b111d7b Jan 11 11:35:00 volumio volumio[25015]: info: Discovery: Found device Volumio Jan 11 11:35:00 volumio volumio[25015]: info: CoreCommandRouter::volumioGetState Jan 11 11:35:00 volumio volumio[25015]: info: CorePlayQueue::getTrack 0 Jan 11 11:35:00 volumio volumio[25015]: info: Discovery: this is already registered, 93ca9a51-c787-409d-bf9f-180c0b111d7b Jan 11 11:35:00 volumio volumio[25015]: info: Discovery: Found device Volumio Jan 11 11:35:00 volumio volumio[25015]: info: CoreCommandRouter::volumioGetState Jan 11 11:35:00 volumio volumio[25015]: info: CorePlayQueue::getTrack 0 Jan 11 11:35:00 volumio volumio[25015]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 11 11:35:01 volumio dhcpcd[674]: eth0: offered 192.168.0.28 from 192.168.0.1 Jan 11 11:35:02 volumio dhcpcd[674]: eth0: probing address 192.168.0.28/24 Jan 11 11:35:05 volumio dhcpcd[794]: eth0: leased 192.168.0.28 for 60 seconds Jan 11 11:35:05 volumio avahi-daemon[730]: Registering new address record for 192.168.0.28 on eth0.IPv4. Jan 11 11:35:05 volumio dhcpcd[794]: eth0: adding route to 192.168.0.0/24 Jan 11 11:35:05 volumio dhcpcd[794]: eth0: changing default route via 192.168.0.1 Jan 11 11:35:05 volumio dhcpcd[674]: eth0: pid 794 deleted default route Jan 11 11:35:05 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:35:05 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:35:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio avahi-daemon[730]: Withdrawing address record for 169.254.217.53 on eth0. Jan 11 11:35:05 volumio avahi-daemon[730]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.217.53. Jan 11 11:35:05 volumio avahi-daemon[730]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.28. Jan 11 11:35:05 volumio dhcpcd[674]: eth0: pid 0 deleted route to 169.254.0.0/16 Jan 11 11:35:05 volumio dhcpcd[674]: eth0: adding route to 169.254.0.0/16 Jan 11 11:35:05 volumio dhcpcd[794]: eth0: deleting route to 169.254.0.0/16 Jan 11 11:35:05 volumio avahi-daemon[730]: Withdrawing address record for 169.254.7.251 on eth0. Jan 11 11:35:05 volumio dhcpcd[674]: if_route (ADD): Invalid argument Jan 11 11:35:05 volumio dhcpcd[674]: eth0: adding default route Jan 11 11:35:05 volumio dhcpcd[674]: if_route (ADD): Invalid argument Jan 11 11:35:05 volumio dhcpcd[674]: eth0: pid 794 deleted IP address 169.254.7.251/16 Jan 11 11:35:05 volumio dhcpcd[674]: eth0: probing for an IPv4LL address Jan 11 11:35:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio welcome[25307]: Resolved ip:[1] 192.168.0.28 Jan 11 11:35:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:35:05 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:35:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio welcome[25349]: Resolved ip:[1] 192.168.0.28 Jan 11 11:35:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:35:05 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:35:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio welcome[25355]: Resolved ip:[1] 192.168.0.28 Jan 11 11:35:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:05 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:35:05 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:35:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:35:05 volumio welcome[25361]: Resolved ip:[1] 192.168.0.28 Jan 11 11:35:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:35:05 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:06 volumio volumio[25015]: info: BOOT COMPLETED Jan 11 11:35:06 volumio dhcpcd[674]: eth0: leased 192.168.0.28 for 60 seconds Jan 11 11:35:06 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:06 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 11 11:35:06 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 11 11:35:06 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 11 11:35:06 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 11 11:35:06 volumio dhcpcd[674]: eth0: adding route to 192.168.0.0/24 Jan 11 11:35:06 volumio dhcpcd[674]: eth0: adding default route via 192.168.0.1 Jan 11 11:35:06 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 11 11:35:06 volumio welcome[25384]: Resolved ip:[1] 192.168.0.28 Jan 11 11:35:06 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 11 11:35:06 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 11 11:35:07 volumio ntpd[1026]: IO: Listen normally on 18 eth0 192.168.0.28:123 Jan 11 11:35:07 volumio ntpd[1026]: IO: Deleting interface #16 eth0, 169.254.217.53#123, interface stats: received=0, sent=0, dropped=0, active_time=56 secs Jan 11 11:35:07 volumio ntpd[1026]: PROTO: 195.178.58.245 unlink local addr 169.254.217.53 -> Jan 11 11:35:07 volumio ntpd[1026]: PROTO: 195.252.108.16 unlink local addr 169.254.217.53 -> Jan 11 11:35:07 volumio ntpd[1026]: PROTO: 217.24.20.5 unlink local addr 169.254.217.53 -> Jan 11 11:35:07 volumio ntpd[1026]: PROTO: 147.91.26.20 unlink local addr 169.254.217.53 -> Jan 11 11:35:07 volumio ntpd[1026]: PROTO: 147.91.8.1 unlink local addr 169.254.217.53 -> Jan 11 11:35:07 volumio ntpd[1026]: PROTO: 195.250.114.105 unlink local addr 169.254.217.53 -> Jan 11 11:35:07 volumio ntpd[1026]: IO: Deleting interface #17 eth0, 169.254.7.251#123, interface stats: received=0, sent=0, dropped=0, active_time=56 secs Jan 11 11:35:07 volumio ntpd[1026]: IO: new interface(s) found: waking up resolver Jan 11 11:35:07 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 11:35:07 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 11:35:07 volumio volumio[25015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 11:35:13 volumio go-librespot[25256]: time="2026-01-11T11:35:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:35:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:35:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:35:13 volumio volumio[25015]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jan 11 11:35:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 11 11:35:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:35:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 11:35:16 volumio go-librespot[25413]: go-librespot daemon starting... Jan 11 11:35:16 volumio go-librespot[25414]: time="2026-01-11T11:35:16+01:00" level=info msg="running go-librespot 0.4.0" Jan 11 11:35:16 volumio go-librespot[25414]: time="2026-01-11T11:35:16+01:00" level=debug msg="app state loaded" Jan 11 11:35:16 volumio go-librespot[25414]: time="2026-01-11T11:35:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 11:35:23 volumio volumio[25015]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=fc3106e42447160aaad541f426e619e6" http://updates.volumio.org/downloader-v1/track-device Jan 11 11:35:23 volumio volumio[25015]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 11 11:35:23 volumio volumio[25015]: Dload Upload Total Spent Left Speed Jan 11 11:35:23 volumio volumio[25015]: [1.3K blob data] Jan 11 11:35:23 volumio volumio[25015]: retrying in 5 seconds, trial 1 Jan 11 11:35:23 volumio volumio[25015]: info: Volumio Calling Home Jan 11 11:35:41 volumio go-librespot[25414]: time="2026-01-11T11:35:41+01:00" level=debug msg="new websocket client" Jan 11 11:35:41 volumio volumio[25015]: info: Connection to go-librespot Websocket established Jan 11 11:35:44 volumio volumio[25015]: info: Getting Spotify volume Jan 11 11:35:44 volumio go-librespot[25414]: time="2026-01-11T11:35:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 11:35:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 11:35:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 11:35:44 volumio volumio[25015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 11:35:44 volumio volumio[25015]: Error: socket hang up Jan 11 11:35:44 volumio volumio[25015]: at connResetException (node:internal/errors:720:14) Jan 11 11:35:44 volumio volumio[25015]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 11 11:35:44 volumio volumio[25015]: at Socket.emit (node:events:526:35) Jan 11 11:35:44 volumio volumio[25015]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 11 11:35:44 volumio volumio[25015]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 11 11:35:44 volumio volumio[25015]: code: 'ECONNRESET', Jan 11 11:35:44 volumio volumio[25015]: response: undefined Jan 11 11:35:44 volumio volumio[25015]: } Jan 11 11:35:44 volumio volumio[25015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 11:35:45 volumio sudo[25489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 11:34' Jan 11 11:35:45 volumio sudo[25489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"