Apr 03 03:19:01 salle-de-bain--dressing volumio[1146]: info: Starting UPNP Browser Apr 03 03:19:01 salle-de-bain--dressing volumio[1146]: info: Loading plugin "alarm-clock"... Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Loading plugin "airplay_emulation"... Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Starting Shairport Sync Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Loading plugin "last_100"... Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Loading plugin "webradio"... Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Loading plugin "i2s_dacs"... Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Loading plugin "volumiodiscovery"... Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 03:19:02 salle-de-bain--dressing node[1146]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 03:19:02 salle-de-bain--dressing node[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: *** WARNING *** For more information see Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: *** WARNING *** For more information see Apr 03 03:19:02 salle-de-bain--dressing node[1146]: *** WARNING *** For more information see Apr 03 03:19:02 salle-de-bain--dressing node[1146]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 03:19:02 salle-de-bain--dressing node[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 03:19:02 salle-de-bain--dressing node[1146]: *** WARNING *** For more information see Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Discovery: Started advertising with name: Salle de bain / Dressing Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 03:19:02 salle-de-bain--dressing volumio[1146]: info: Loading plugin "spop"... Apr 03 03:19:05 salle-de-bain--dressing volumio[1146]: info: Loading plugin "youtube2"... Apr 03 03:19:07 salle-de-bain--dressing volumio[1146]: info: Loading plugin "ytmusic"... Apr 03 03:19:08 salle-de-bain--dressing systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 03 03:19:08 salle-de-bain--dressing volumio[1146]: info: Loading plugin "outputs"... Apr 03 03:19:08 salle-de-bain--dressing volumio[1146]: info: Loading plugin "albumart"... Apr 03 03:19:08 salle-de-bain--dressing volumio[1146]: info: Plugin example_plugin is not enabled Apr 03 03:19:08 salle-de-bain--dressing volumio[1146]: info: Loading plugin "inputs"... Apr 03 03:19:08 salle-de-bain--dressing volumio[1146]: info: Loading plugin "updater_comm"... Apr 03 03:19:09 salle-de-bain--dressing volumio-remote-updater[729]: [2026-04-03 03:19:09] [connect] Successful connection Apr 03 03:19:09 salle-de-bain--dressing volumio[1146]: info: Plugin mpdemulation is not enabled Apr 03 03:19:09 salle-de-bain--dressing volumio[1146]: info: Loading plugin "rest_api"... Apr 03 03:19:09 salle-de-bain--dressing volumio[1146]: info: Loading plugin "websocket"... Apr 03 03:19:09 salle-de-bain--dressing volumio[1146]: info: Starting Socket.io Server version 1.7.4 Apr 03 03:19:09 salle-de-bain--dressing volumio[1146]: info: Loading plugin "Bluetoothremote"... Apr 03 03:19:09 salle-de-bain--dressing sh[665]: timed out Apr 03 03:19:09 salle-de-bain--dressing dhcpcd[671]: timed out Apr 03 03:19:09 salle-de-bain--dressing sh[612]: ifup: failed to bring up eth0 Apr 03 03:19:09 salle-de-bain--dressing systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:09 salle-de-bain--dressing systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 03 03:19:09 salle-de-bain--dressing ifplugd(eth0)[911]: client: sending commands to dhcpcd process Apr 03 03:19:09 salle-de-bain--dressing dhcpcd[818]: control command: dhcpcd eth0 Apr 03 03:19:09 salle-de-bain--dressing dhcpcd[818]: control_free: No such file or directory Apr 03 03:19:10 salle-de-bain--dressing ifplugd(eth0)[911]: Program executed successfully. Apr 03 03:19:10 salle-de-bain--dressing ifplugd[843]: Network Interface Plugging Daemon...start eth0...done. Apr 03 03:19:10 salle-de-bain--dressing systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Apr 03 03:19:10 salle-de-bain--dressing volumio[1146]: info: Applying required configuration parameters for plugin Bluetoothremote Apr 03 03:19:10 salle-de-bain--dressing volumio[1238]: Forking 3 albumart workers Apr 03 03:19:10 salle-de-bain--dressing volumio[1146]: info: Loading i18n strings for locale fr Apr 03 03:19:10 salle-de-bain--dressing volumio[1146]: Updating browse sources language Apr 03 03:19:10 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::initPlayerControls Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: Express server listening on port 3000 Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: [Metrics] WebUI: 19s 646.26ms Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::resetVolumioState Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::getcurrentVolume Apr 03 03:19:11 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 03:19:11 salle-de-bain--dressing sudo[1332]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:11 salle-de-bain--dressing sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 03:19:11 salle-de-bain--dressing sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:11 salle-de-bain--dressing sudo[1332]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:12 salle-de-bain--dressing sudo[1335]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:12 salle-de-bain--dressing sudo[1335]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 03:19:12 salle-de-bain--dressing sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:12 salle-de-bain--dressing sudo[1335]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:12 salle-de-bain--dressing volumio[1146]: info: Volumio Network Manager: Network status updated: 0 Apr 03 03:19:12 salle-de-bain--dressing systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 03 03:19:12 salle-de-bain--dressing systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 03:19:12 salle-de-bain--dressing systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 03:19:13 salle-de-bain--dressing volumio-remote-updater[729]: [2026-04-03 03:19:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775179149 101 Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: 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 Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: VolumeController:: Volume=28 Mute =false Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::pushState Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioPushState Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::updateTrackBlock Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrackBlock Apr 03 03:19:13 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 03:19:14 salle-de-bain--dressing systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: Reloading queue from file Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::setRepeat true single undefined Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::pushState Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioPushState Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::setRandom null Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::pushState Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioPushState Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: Setting Device type: Raspberry PI Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: Completed loading Core Plugins Apr 03 03:19:14 salle-de-bain--dressing volumio[1146]: info: Preparing to generate the ALSA configuration file Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: VolumeController:: Volume=28 Mute =false Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CoreStateMachine::pushState Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioPushState Apr 03 03:19:15 salle-de-bain--dressing sudo[1361]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:15 salle-de-bain--dressing sudo[1361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: Asound.conf file unchanged, so no further update is needed Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: Output device has changed, restarting MPD Apr 03 03:19:15 salle-de-bain--dressing sudo[1361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: Output device has changed, restarting Shairport Sync Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:15 salle-de-bain--dressing sudo[1364]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:15 salle-de-bain--dressing sudo[1366]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:15 salle-de-bain--dressing sudo[1361]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:15 salle-de-bain--dressing sudo[1364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 03:19:15 salle-de-bain--dressing sudo[1364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:15 salle-de-bain--dressing sudo[1366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 03:19:15 salle-de-bain--dressing sudo[1366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:15 salle-de-bain--dressing sudo[1364]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: ___________ START PLUGINS ___________ Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: ControllerMpd::onStart: Initializing MPD Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: Creating MPD Configuration file Apr 03 03:19:15 salle-de-bain--dressing sudo[1374]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:15 salle-de-bain--dressing systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 03 03:19:15 salle-de-bain--dressing systemd[1]: Starting mpd.service - Music Player Daemon... Apr 03 03:19:15 salle-de-bain--dressing sudo[1374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 03:19:15 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 03:19:15 salle-de-bain--dressing sudo[1376]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: [1775179155964] CoreMusicLibrary::Adding element Serveurs Média Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 03:19:16 salle-de-bain--dressing sudo[1376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 03:19:16 salle-de-bain--dressing sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:16 salle-de-bain--dressing sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:16 salle-de-bain--dressing sudo[1380]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:16 salle-de-bain--dressing sudo[1379]: root : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:16 salle-de-bain--dressing sudo[1379]: sudo: unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:16 salle-de-bain--dressing sudo[1379]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 03:19:16 salle-de-bain--dressing sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 03:19:16 salle-de-bain--dressing sudo[1383]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 03 03:19:16 salle-de-bain--dressing sudo[1379]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:16 salle-de-bain--dressing sudo[1376]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:16 salle-de-bain--dressing sudo[1380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: UPNP Browser: Client initialized successfully Apr 03 03:19:16 salle-de-bain--dressing sudo[1380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 03:19:16 salle-de-bain--dressing sudo[1374]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: mpd.service: Deactivated successfully. Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: mpd.socket: Deactivated successfully. Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 03 03:19:16 salle-de-bain--dressing systemd[1]: Starting mpd.service - Music Player Daemon... Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: [1775179156982] CoreMusicLibrary::Adding element Last_100 Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 03:19:16 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 03:19:17 salle-de-bain--dressing volumio[1146]: info: [1775179157064] CoreMusicLibrary::Adding element Webradio Apr 03 03:19:17 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 03:19:17 salle-de-bain--dressing sudo[1395]: root : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:17 salle-de-bain--dressing sudo[1395]: sudo: unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:17 salle-de-bain--dressing sudo[1395]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 03 03:19:17 salle-de-bain--dressing sudo[1395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 03 03:19:17 salle-de-bain--dressing sudo[1402]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 03 03:19:17 salle-de-bain--dressing sudo[1395]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:17 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 03:19:17 salle-de-bain--dressing volumio[1146]: info: Initializing BBC Radios Apr 03 03:19:17 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:17.365+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 03 03:19:17 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 03:19:17 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:18 salle-de-bain--dressing volumio[1146]: info: Creating Spotify config file Apr 03 03:19:18 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:20 salle-de-bain--dressing volumio[1299]: Starting albumart workers Apr 03 03:19:21 salle-de-bain--dressing volumio[1297]: Starting albumart workers Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: [1775179162375] CoreMusicLibrary::Adding element YouTube2 Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: Cannot find translation for source YouTube2 Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: [1775179162541] CoreMusicLibrary::Adding element YouTube Music Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: Cannot find translation for source YouTube2 Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: Cannot find translation for source YouTube Music Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: Loading i18n strings for locale fr Apr 03 03:19:22 salle-de-bain--dressing volumio[1298]: Starting albumart workers Apr 03 03:19:22 salle-de-bain--dressing volumio[1146]: info: Volumio Calling Home Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: info: Discovery: adding 33ee07bd-ab2a-4684-be4a-f7da83480362 Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: info: Discovery: Found device Salle de bain / Dressing Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioGetState Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: Unhandled rejection Error: No sockets available, cannot start. Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 03 03:19:24 salle-de-bain--dressing volumio[1146]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: MPD Permissions set Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: MPD Permissions set Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: Upmpdcli Daemon Started Apr 03 03:19:25 salle-de-bain--dressing mpd[1403]: 2026-04-03T03:19:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 03 03:19:25 salle-de-bain--dressing systemd[1]: Started mpd.service - Music Player Daemon. Apr 03 03:19:25 salle-de-bain--dressing sudo[1380]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:25 salle-de-bain--dressing sudo[1366]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:25 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:25.506+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Apr 03 03:19:25 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:25.508+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Apr 03 03:19:25 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:25.510+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Apr 03 03:19:25 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:25.513+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Apr 03 03:19:25 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:25.535+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Apr 03 03:19:25 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:25.535+02:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Apr 03 03:19:25 salle-de-bain--dressing systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: 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 Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: Spotify config file written Apr 03 03:19:25 salle-de-bain--dressing sudo[1458]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: 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 Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: Received Get System Info Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: Discovery: Getting this device information Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioGetState Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:25 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 03:19:26 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:26.059+02:00 level=INFO msg="system info for 157d32675b30e02d055518c5fa30d3fb" deviceName="Salle de bain / Dressing" deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 03 03:19:26 salle-de-bain--dressing sudo[1458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 03 03:19:26 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:26.064+02:00 level=INFO msg="bootstrapping state" hasInternet=false Apr 03 03:19:26 salle-de-bain--dressing sudo[1458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:26 salle-de-bain--dressing systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 03 03:19:26 salle-de-bain--dressing systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 03 03:19:26 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:26 salle-de-bain--dressing go-librespot[1462]: go-librespot daemon starting... Apr 03 03:19:26 salle-de-bain--dressing sudo[1458]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: Completed starting Core Plugins Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: ------------------------------------------- Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: ----- MyVolumio plugins startup ---- Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: ------------------------------------------- Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:26 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:27 salle-de-bain--dressing go-librespot[1467]: time="2026-04-03T03:19:27+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:27 salle-de-bain--dressing go-librespot[1467]: time="2026-04-03T03:19:27+02:00" level=debug msg="app state loaded" Apr 03 03:19:27 salle-de-bain--dressing go-librespot[1467]: time="2026-04-03T03:19:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: No need to fix Spotify hosts Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: Received Get System Info Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: Discovery: Getting this device information Apr 03 03:19:27 salle-de-bain--dressing go-librespot[1467]: time="2026-04-03T03:19:27+02: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" Apr 03 03:19:27 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:27 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioGetState Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: MPD running with PID1403 Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: ,establishing connection Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 03 03:19:27 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 03 03:19:27 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:27.641+02:00 level=INFO msg="enabling local network discovery" Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: error: MPD error: The expression evaluated to a falsy value: Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: assert.ok(self.idling) Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: error: The expression evaluated to a falsy value: Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: assert.ok(self.idling) Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: error: MPD error: The expression evaluated to a falsy value: Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: assert.ok(self.idling) Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: error: The expression evaluated to a falsy value: Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: assert.ok(self.idling) Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: error: updateQueue error: null Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: info: Starting Shairport Sync Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: info: Starting Shairport Sync Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: info: Starting Shairport Sync Apr 03 03:19:28 salle-de-bain--dressing sudo[1516]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:28 salle-de-bain--dressing sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 03:19:28 salle-de-bain--dressing sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:28 salle-de-bain--dressing sudo[1518]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:28 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:28.464+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 03 03:19:28 salle-de-bain--dressing sudo[1520]: volumio : unable to resolve host salle-de-bain--dressing: System error Apr 03 03:19:28 salle-de-bain--dressing sudo[1518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 03:19:28 salle-de-bain--dressing sudo[1518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:28 salle-de-bain--dressing sudo[1520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 03:19:28 salle-de-bain--dressing sudo[1520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: shairport-sync.service: Deactivated successfully. Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: shairport-sync.service: Consumed 1.972s CPU time. Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 03:19:28 salle-de-bain--dressing sudo[1516]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:28 salle-de-bain--dressing sudo[1518]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: shairport-sync.service: Deactivated successfully. Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 03:19:28 salle-de-bain--dressing systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 03 03:19:28 salle-de-bain--dressing sudo[1520]: pam_unix(sudo:session): session closed for user root Apr 03 03:19:28 salle-de-bain--dressing volumio[1146]: info: Shairport-Sync Started Apr 03 03:19:29 salle-de-bain--dressing volumio[1146]: Error adding Membership: Error: addMembership EINVAL Apr 03 03:19:29 salle-de-bain--dressing volumio[1146]: info: Shairport-Sync Started Apr 03 03:19:29 salle-de-bain--dressing volumio[1146]: info: Shairport-Sync Started Apr 03 03:19:29 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioGetState Apr 03 03:19:29 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:30 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 03 03:19:30 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:30 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:30 salle-de-bain--dressing go-librespot[1566]: go-librespot daemon starting... Apr 03 03:19:30 salle-de-bain--dressing go-librespot[1567]: time="2026-04-03T03:19:30+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:30 salle-de-bain--dressing go-librespot[1567]: time="2026-04-03T03:19:30+02:00" level=debug msg="app state loaded" Apr 03 03:19:30 salle-de-bain--dressing go-librespot[1567]: time="2026-04-03T03:19:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:30 salle-de-bain--dressing go-librespot[1567]: time="2026-04-03T03:19:30+02: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" Apr 03 03:19:30 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:30 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:30 salle-de-bain--dressing volumio[1146]: info: go-librespot daemon successfully initialized Apr 03 03:19:31 salle-de-bain--dressing bash[1444]: setdatetime-helper: all HTTPS Date fallbacks failed Apr 03 03:19:31 salle-de-bain--dressing systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 03 03:19:31 salle-de-bain--dressing systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 03 03:19:32 salle-de-bain--dressing volumio[1146]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 03 03:19:33 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:33 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Apr 03 03:19:33 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:33 salle-de-bain--dressing volumio[1146]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 03:19:33 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:33 salle-de-bain--dressing go-librespot[1583]: go-librespot daemon starting... Apr 03 03:19:33 salle-de-bain--dressing go-librespot[1584]: time="2026-04-03T03:19:33+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:33 salle-de-bain--dressing go-librespot[1584]: time="2026-04-03T03:19:33+02:00" level=debug msg="app state loaded" Apr 03 03:19:33 salle-de-bain--dressing go-librespot[1584]: time="2026-04-03T03:19:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:33 salle-de-bain--dressing go-librespot[1584]: time="2026-04-03T03:19:33+02: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" Apr 03 03:19:33 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:33 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:35 salle-de-bain--dressing volumio[1146]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=157d32675b30e02d055518c5fa30d3fb" http://updates.volumio.org/downloader-v1/track-device Apr 03 03:19:35 salle-de-bain--dressing volumio[1146]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 03 03:19:35 salle-de-bain--dressing volumio[1146]: Dload Upload Total Spent Left Speed Apr 03 03:19:35 salle-de-bain--dressing volumio[1146]: [132B blob data] Apr 03 03:19:35 salle-de-bain--dressing volumio[1146]: retrying in 5 seconds, trial 0 Apr 03 03:19:35 salle-de-bain--dressing volumio[1146]: info: Volumio Calling Home Apr 03 03:19:36 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:36 salle-de-bain--dressing volumio[1146]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 03:19:36 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Apr 03 03:19:36 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:36 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:37 salle-de-bain--dressing go-librespot[1597]: go-librespot daemon starting... Apr 03 03:19:37 salle-de-bain--dressing go-librespot[1598]: time="2026-04-03T03:19:37+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:37 salle-de-bain--dressing go-librespot[1598]: time="2026-04-03T03:19:37+02:00" level=debug msg="app state loaded" Apr 03 03:19:37 salle-de-bain--dressing go-librespot[1598]: time="2026-04-03T03:19:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:37 salle-de-bain--dressing go-librespot[1598]: time="2026-04-03T03:19:37+02: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" Apr 03 03:19:37 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:37 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:39 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:39 salle-de-bain--dressing volumio[1146]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 03:19:40 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Apr 03 03:19:40 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:40 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:40 salle-de-bain--dressing go-librespot[1620]: go-librespot daemon starting... Apr 03 03:19:40 salle-de-bain--dressing go-librespot[1621]: time="2026-04-03T03:19:40+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:40 salle-de-bain--dressing go-librespot[1621]: time="2026-04-03T03:19:40+02:00" level=debug msg="app state loaded" Apr 03 03:19:40 salle-de-bain--dressing go-librespot[1621]: time="2026-04-03T03:19:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:40 salle-de-bain--dressing go-librespot[1621]: time="2026-04-03T03:19:40+02: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" Apr 03 03:19:40 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:40 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:42 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:42 salle-de-bain--dressing volumio[1146]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 03:19:43 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Apr 03 03:19:43 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:43 salle-de-bain--dressing go-librespot[1630]: go-librespot daemon starting... Apr 03 03:19:43 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:43 salle-de-bain--dressing go-librespot[1631]: time="2026-04-03T03:19:43+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:43 salle-de-bain--dressing go-librespot[1631]: time="2026-04-03T03:19:43+02:00" level=debug msg="app state loaded" Apr 03 03:19:43 salle-de-bain--dressing go-librespot[1631]: time="2026-04-03T03:19:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:43 salle-de-bain--dressing go-librespot[1631]: time="2026-04-03T03:19:43+02: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" Apr 03 03:19:43 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:43 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:44 salle-de-bain--dressing dhcpcd[818]: eth0: offered 192.168.1.24 from 192.168.1.1 Apr 03 03:19:44 salle-de-bain--dressing dhcpcd[818]: eth0: probing address 192.168.1.24/24 Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=157d32675b30e02d055518c5fa30d3fb" http://updates.volumio.org/downloader-v1/track-device Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: Dload Upload Total Spent Left Speed Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: [132B blob data] Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: retrying in 5 seconds, trial 1 Apr 03 03:19:45 salle-de-bain--dressing volumio[1146]: info: Volumio Calling Home Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: error: MyVolumio Plugin failed to start in a timely fashion Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: [Metrics] CommandRouter: 52s 830.97ms Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumiosetStartupVolume Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::Close All Modals sent Apr 03 03:19:46 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::Close All Modals sent Apr 03 03:19:47 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Apr 03 03:19:47 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:47 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:47 salle-de-bain--dressing go-librespot[1644]: go-librespot daemon starting... Apr 03 03:19:47 salle-de-bain--dressing go-librespot[1645]: time="2026-04-03T03:19:47+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:47 salle-de-bain--dressing go-librespot[1645]: time="2026-04-03T03:19:47+02:00" level=debug msg="app state loaded" Apr 03 03:19:47 salle-de-bain--dressing go-librespot[1645]: time="2026-04-03T03:19:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:47 salle-de-bain--dressing go-librespot[1645]: time="2026-04-03T03:19:47+02: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" Apr 03 03:19:47 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:47 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:47 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 03 03:19:47 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 03 03:19:47 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 03 03:19:47 salle-de-bain--dressing volumio[1146]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Apr 03 03:19:47 salle-de-bain--dressing volumio[1146]: info: Bluetoothremote--- Device list cleared and placeholder written. Apr 03 03:19:48 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 03:19:48 salle-de-bain--dressing volumio[1146]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Apr 03 03:19:48 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:48 salle-de-bain--dressing volumio[1146]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 03:19:49 salle-de-bain--dressing dhcpcd[818]: eth0: leased 192.168.1.24 for 86400 seconds Apr 03 03:19:49 salle-de-bain--dressing avahi-daemon[705]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.24. Apr 03 03:19:49 salle-de-bain--dressing avahi-daemon[705]: New relevant interface eth0.IPv4 for mDNS. Apr 03 03:19:49 salle-de-bain--dressing avahi-daemon[705]: Registering new address record for 192.168.1.24 on eth0.IPv4. Apr 03 03:19:49 salle-de-bain--dressing dhcpcd[818]: eth0: adding route to 192.168.1.0/24 Apr 03 03:19:49 salle-de-bain--dressing dhcpcd[818]: eth0: adding default route via 192.168.1.1 Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Started nmbd.service - Samba NMB Daemon. Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Starting winbind.service - Samba Winbind Daemon... Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: welcome.service: Deactivated successfully. Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Stopped welcome.service - Show a welcome message on console. Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Stopping welcome.service - Show a welcome message on console... Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Starting welcome.service - Show a welcome message on console... Apr 03 03:19:49 salle-de-bain--dressing welcome[1683]: Resolved ip:[1] 192.168.1.24 Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Finished welcome.service - Show a welcome message on console. Apr 03 03:19:49 salle-de-bain--dressing systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: Volumio Network Manager: Network status updated: 1 Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: Received Get System Info Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: Discovery: Getting this device information Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioGetState Apr 03 03:19:49 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:19:50 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 03 03:19:50 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 03 03:19:50 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 03 03:19:50 salle-de-bain--dressing winbindd[1698]: [2026/04/03 03:19:50.208514, 0] ../../source3/winbindd/winbindd.c:1440(main) Apr 03 03:19:50 salle-de-bain--dressing winbindd[1698]: winbindd version 4.17.8-Raspbian started. Apr 03 03:19:50 salle-de-bain--dressing winbindd[1698]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 03 03:19:50 salle-de-bain--dressing winbindd[1698]: [2026/04/03 03:19:50.239036, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Apr 03 03:19:50 salle-de-bain--dressing winbindd[1698]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 03 03:19:50 salle-de-bain--dressing systemd[1]: Started winbind.service - Samba Winbind Daemon. Apr 03 03:19:50 salle-de-bain--dressing winbindd[1707]: [2026/04/03 03:19:50.288271, 0] ../../lib/util/debug.c:1264(reopen_one_log) Apr 03 03:19:50 salle-de-bain--dressing winbindd[1707]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-SALLE DE BAIN / DRESSING': No such file or directory Apr 03 03:19:50 salle-de-bain--dressing winbindd[1707]: [2026/04/03 03:19:50.288578, 0] ../../lib/util/debug.c:1264(reopen_one_log) Apr 03 03:19:50 salle-de-bain--dressing winbindd[1707]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-SALLE DE BAIN / DRESSING': No such file or directory Apr 03 03:19:50 salle-de-bain--dressing winbindd[1707]: [2026/04/03 03:19:50.291246, 0] ../../lib/util/debug.c:1264(reopen_one_log) Apr 03 03:19:50 salle-de-bain--dressing winbindd[1707]: reopen_one_log: Unable to open new log file '/var/log/samba/log.wb-SALLE DE BAIN / DRESSING': No such file or directory Apr 03 03:19:50 salle-de-bain--dressing systemd[1]: Starting smbd.service - Samba SMB Daemon... Apr 03 03:19:50 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Apr 03 03:19:50 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:50 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:50 salle-de-bain--dressing go-librespot[1710]: go-librespot daemon starting... Apr 03 03:19:50 salle-de-bain--dressing go-librespot[1711]: time="2026-04-03T03:19:50+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:50 salle-de-bain--dressing go-librespot[1711]: time="2026-04-03T03:19:50+02:00" level=debug msg="app state loaded" Apr 03 03:19:50 salle-de-bain--dressing go-librespot[1711]: time="2026-04-03T03:19:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:19:50 salle-de-bain--dressing ntpd[890]: IO: Listen normally on 3 eth0 192.168.1.24:123 Apr 03 03:19:50 salle-de-bain--dressing ntpd[890]: IO: new interface(s) found: waking up resolver Apr 03 03:19:50 salle-de-bain--dressing ntpd[890]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 03 03:19:50 salle-de-bain--dressing volumio5-onboarding[1388]: time=2026-04-03T03:19:50.964+02:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 03 03:19:51 salle-de-bain--dressing smbd[1729]: [2026/04/03 03:19:51.126998, 0] ../../source3/smbd/server.c:1741(main) Apr 03 03:19:51 salle-de-bain--dressing smbd[1729]: smbd version 4.17.8-Raspbian started. Apr 03 03:19:51 salle-de-bain--dressing smbd[1729]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 03 03:19:51 salle-de-bain--dressing volumio[1146]: info: Initializing connection to go-librespot Websocket Apr 03 03:19:52 salle-de-bain--dressing upmpdcli[1338]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.24 port 49152 IPV6 port 49152 Apr 03 03:19:52 salle-de-bain--dressing upmpdcli[1338]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Apr 03 03:19:52 salle-de-bain--dressing upmpdcli[1740]: writing RSA key Apr 03 03:19:52 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 03:19:52 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 03:19:52 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 03 03:19:52 salle-de-bain--dressing systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 03 03:19:52 salle-de-bain--dressing systemd[1]: Reached target multi-user.target - Multi-User System. Apr 03 03:19:52 salle-de-bain--dressing systemd[1]: Reached target graphical.target - Graphical Interface. Apr 03 03:19:53 salle-de-bain--dressing systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 03 03:19:53 salle-de-bain--dressing systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 03 03:19:53 salle-de-bain--dressing systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 03 03:19:53 salle-de-bain--dressing systemd[1]: Startup finished in 15.564s (kernel) + 1min 17.806s (userspace) = 1min 33.371s. Apr 03 03:19:53 salle-de-bain--dressing volumio[1146]: info: BOOT COMPLETED Apr 03 03:19:56 salle-de-bain--dressing volumio[1146]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.119&uuid=157d32675b30e02d055518c5fa30d3fb" http://updates.volumio.org/downloader-v1/track-device Apr 03 03:19:56 salle-de-bain--dressing volumio[1146]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 03 03:19:56 salle-de-bain--dressing volumio[1146]: Dload Upload Total Spent Left Speed Apr 03 03:19:56 salle-de-bain--dressing volumio[1146]: [132B blob data] Apr 03 03:19:56 salle-de-bain--dressing volumio[1146]: retrying in 5 seconds, trial 2 Apr 03 03:19:56 salle-de-bain--dressing volumio[1146]: info: Volumio Calling Home Apr 03 03:19:56 salle-de-bain--dressing go-librespot[1711]: time="2026-04-03T03:19:56+02: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" Apr 03 03:19:56 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:19:56 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:19:59 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Apr 03 03:19:59 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:59 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:19:59 salle-de-bain--dressing go-librespot[1758]: go-librespot daemon starting... Apr 03 03:19:59 salle-de-bain--dressing go-librespot[1759]: time="2026-04-03T03:19:59+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:19:59 salle-de-bain--dressing go-librespot[1759]: time="2026-04-03T03:19:59+02:00" level=debug msg="app state loaded" Apr 03 03:19:59 salle-de-bain--dressing go-librespot[1759]: time="2026-04-03T03:19:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:20:01 salle-de-bain--dressing volumio[1146]: info: Discovery: adding ab5a7e3e-8e4c-4b8f-abe2-7b0d687b0ae9 Apr 03 03:20:01 salle-de-bain--dressing volumio[1146]: info: Discovery: Found device Cuisine Apr 03 03:20:01 salle-de-bain--dressing volumio[1146]: info: Discovery: Connecting to remote: 192.168.1.23 Apr 03 03:20:02 salle-de-bain--dressing volumio[1146]: info: Discovery: Connected to remote: 192.168.1.23 Apr 03 03:20:08 salle-de-bain--dressing volumio[1146]: info: Discovery: this is already registered, 33ee07bd-ab2a-4684-be4a-f7da83480362 Apr 03 03:20:08 salle-de-bain--dressing volumio[1146]: info: Discovery: Found device Salle de bain / Dressing Apr 03 03:20:08 salle-de-bain--dressing volumio[1146]: info: CoreCommandRouter::volumioGetState Apr 03 03:20:08 salle-de-bain--dressing volumio[1146]: info: CorePlayQueue::getTrack 0 Apr 03 03:20:08 salle-de-bain--dressing ntpd[890]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Apr 03 03:20:08 salle-de-bain--dressing ntpd[890]: DNS: dns_check: DNS error: -11, System error Apr 03 03:20:08 salle-de-bain--dressing ntpd[890]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Apr 03 03:20:09 salle-de-bain--dressing ntpd[890]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 03 03:20:10 salle-de-bain--dressing go-librespot[1759]: time="2026-04-03T03:20:10+02:00" level=debug msg="new websocket client" Apr 03 03:20:10 salle-de-bain--dressing volumio[1146]: info: Connection to go-librespot Websocket established Apr 03 03:20:12 salle-de-bain--dressing ntpd[890]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 03 03:20:12 salle-de-bain--dressing ntpd[890]: DNS: dns_check: DNS error: -11, System error Apr 03 03:20:12 salle-de-bain--dressing ntpd[890]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Apr 03 03:20:13 salle-de-bain--dressing ntpd[890]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 03 03:20:14 salle-de-bain--dressing volumio[1146]: info: Getting Spotify volume Apr 03 03:20:17 salle-de-bain--dressing go-librespot[1759]: time="2026-04-03T03:20:17+02: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" Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 03:20:17 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 03:20:17 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: Error: socket hang up Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: at connResetException (node:internal/errors:720:14) Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: at Socket.emit (node:events:526:35) Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: code: 'ECONNRESET', Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: response: undefined Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: } Apr 03 03:20:17 salle-de-bain--dressing volumio[1146]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 03:20:21 salle-de-bain--dressing systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Apr 03 03:20:21 salle-de-bain--dressing systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:20:21 salle-de-bain--dressing systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 03:20:21 salle-de-bain--dressing go-librespot[1828]: go-librespot daemon starting... Apr 03 03:20:21 salle-de-bain--dressing go-librespot[1829]: time="2026-04-03T03:20:21+02:00" level=info msg="running go-librespot 0.6.2" Apr 03 03:20:21 salle-de-bain--dressing go-librespot[1829]: time="2026-04-03T03:20:21+02:00" level=debug msg="app state loaded" Apr 03 03:20:21 salle-de-bain--dressing go-librespot[1829]: time="2026-04-03T03:20:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 03:20:22 salle-de-bain--dressing ntpd[890]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 03 03:20:22 salle-de-bain--dressing ntpd[890]: DNS: dns_check: DNS error: -11, System error Apr 03 03:20:22 salle-de-bain--dressing ntpd[890]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 03 03:20:23 salle-de-bain--dressing sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-03 03:19' Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: Pool taking: 193.42.63.23 Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: Pool taking: 51.91.15.107 Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: Pool taking: 51.210.244.82 Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: Pool taking: 162.159.200.123 Apr 03 03:20:23 salle-de-bain--dressing ntpd[890]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 03 03:20:23 salle-de-bain--dressing sudo[1840]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"