-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2026-08-28 11:22:26 CEST. -- Aug 28 11:21:00 volumio-clos-adama-rdc sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Aug 28 11:21:00 volumio-clos-adama-rdc sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: pulseaudio: no process found Aug 28 11:21:00 volumio-clos-adama-rdc sudo[2040]: pam_unix(sudo:session): session closed for user root Aug 28 11:21:00 volumio-clos-adama-rdc dbus-daemon[514]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.34' (uid=1000 pid=2044 comm="/usr/bin/pulseaudio --high-priority=true ") Aug 28 11:21:00 volumio-clos-adama-rdc mpd[2036]: Aug 28 11:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 11:21:00 volumio-clos-adama-rdc systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 28 11:21:00 volumio-clos-adama-rdc systemd[1]: Started Music Player Daemon. Aug 28 11:21:00 volumio-clos-adama-rdc sudo[2026]: pam_unix(sudo:session): session closed for user root Aug 28 11:21:00 volumio-clos-adama-rdc volumio[1135]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 28 11:21:00 volumio-clos-adama-rdc dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 28 11:21:00 volumio-clos-adama-rdc systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 28 11:21:00 volumio-clos-adama-rdc rtkit-daemon[2045]: Successfully called chroot. Aug 28 11:21:00 volumio-clos-adama-rdc rtkit-daemon[2045]: Successfully dropped privileges. Aug 28 11:21:00 volumio-clos-adama-rdc rtkit-daemon[2045]: Successfully limited resources. Aug 28 11:21:00 volumio-clos-adama-rdc rtkit-daemon[2045]: Running. Aug 28 11:21:00 volumio-clos-adama-rdc rtkit-daemon[2045]: Watchdog thread running. Aug 28 11:21:00 volumio-clos-adama-rdc rtkit-daemon[2045]: Canary thread running. Aug 28 11:21:00 volumio-clos-adama-rdc volumio[1135]: error: updateQueue error: null Aug 28 11:21:00 volumio-clos-adama-rdc dbus-daemon[514]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.35' (uid=0 pid=2045 comm="/usr/lib/rtkit/rtkit-daemon ") Aug 28 11:21:00 volumio-clos-adama-rdc systemd[1]: Starting Authorization Manager... Aug 28 11:21:00 volumio-clos-adama-rdc volumio[1135]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 28 11:21:00 volumio-clos-adama-rdc polkitd[2051]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 28 11:21:00 volumio-clos-adama-rdc dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 28 11:21:00 volumio-clos-adama-rdc systemd[1]: Started Authorization Manager. Aug 28 11:21:00 volumio-clos-adama-rdc pulseaudio[2044]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 28 11:21:00 volumio-clos-adama-rdc pulseaudio[2044]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Applying permissions Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Setting BT discoverable and pairable Aug 28 11:21:00 volumio-clos-adama-rdc bluetoothd[631]: Endpoint registered: sender=:1.55 path=/MediaEndpoint/A2DPSource Aug 28 11:21:00 volumio-clos-adama-rdc bluetoothd[631]: Endpoint registered: sender=:1.55 path=/MediaEndpoint/A2DPSink Aug 28 11:21:00 volumio-clos-adama-rdc pulseaudio[2044]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Aug 28 11:21:00 volumio-clos-adama-rdc kernel: Bluetooth: RFCOMM TTY layer initialized Aug 28 11:21:00 volumio-clos-adama-rdc kernel: Bluetooth: RFCOMM socket layer initialized Aug 28 11:21:00 volumio-clos-adama-rdc kernel: Bluetooth: RFCOMM ver 1.11 Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [96B blob data] Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [77B blob data] Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [77B blob data] Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [33B blob data] Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> -e power on Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Invalid command in menu main: -e Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Use "help" for a list of available commands in a menu. Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Use "menu " if you want to enter any submenu. Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Use "back" if you want to return to menu main. Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> agent on Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Agent is already registered Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> discoverable on Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> pairable on Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> agent NoInputNoOutput Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: Agent is already registered Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> default-agent Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [bluetoothctl]> quit Aug 28 11:21:00 volumio-clos-adama-rdc volumiobt[2034]: [65B blob data] Aug 28 11:21:00 volumio-clos-adama-rdc volumio[1135]: info: MRS: Getting audio outputs on start Aug 28 11:21:00 volumio-clos-adama-rdc volumio[1135]: info: MRS: Requesting all other devices output Aug 28 11:21:01 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:01 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:01 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 11:21:01 volumio-clos-adama-rdc volumio[1135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 28 11:21:01 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:21:01 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:21:02 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:02 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 28 11:21:02 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:02 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: go-librespot daemon starting... Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="app state loaded" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:02 volumio-clos-adama-rdc kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=info msg="zeroconf server listening on port 41023" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="obtained new client token: AAFYU3n72jxopmH1yiOECh+e0qNtNFcOJG0Z9Ix4oB4G7nLjNHWhr+1UsB5C//T+TiPeBnKlbIS3D/NQknFr2+8ZdwPkJswNcfW2S8yiZdoZYnAIJ2AjJ9rMXD6KS2c9uiB+3gHquTWO6hJ4ogRz3nyHMZT+cTkDi1Y8U2KWMjDo3qTOM4xsuWHzGGnsY5YBtTkVqDvEK3r9qJvGzpRSr+o5iMOrT/rxNzwIAoYKDCiME8bfqUqRCL0M" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=debug msg="completed challenge" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:02 volumio-clos-adama-rdc go-librespot[2061]: time="2026-08-28T11:21:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:02 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:02 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:03 volumio-clos-adama-rdc volumiossh-tunnel[1973]: Connection to eu6.myvolumio.org closed by remote host. Aug 28 11:21:03 volumio-clos-adama-rdc autossh[1974]: ssh exited prematurely with status 255; autossh exiting Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: Started MyVolumio SSH Tunnel. Aug 28 11:21:03 volumio-clos-adama-rdc autossh[2080]: port set to 0, monitoring disabled Aug 28 11:21:03 volumio-clos-adama-rdc autossh[2080]: starting ssh (count 1) Aug 28 11:21:03 volumio-clos-adama-rdc autossh[2080]: ssh child pid is 2083 Aug 28 11:21:03 volumio-clos-adama-rdc volumio[1135]: info: BOOT COMPLETED Aug 28 11:21:03 volumio-clos-adama-rdc volumiossh-tunnel[2079]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 11:21:03 volumio-clos-adama-rdc autossh[2080]: ssh exited prematurely with status 255; autossh exiting Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Aug 28 11:21:03 volumio-clos-adama-rdc systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Started MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2085]: port set to 0, monitoring disabled Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2085]: starting ssh (count 1) Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2085]: ssh child pid is 2088 Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: mpdhttpout --- Volumio set on pause Aug 28 11:21:04 volumio-clos-adama-rdc volumiossh-tunnel[2084]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2085]: ssh exited prematurely with status 255; autossh exiting Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Started MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2090]: port set to 0, monitoring disabled Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2090]: starting ssh (count 1) Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2090]: ssh child pid is 2093 Aug 28 11:21:04 volumio-clos-adama-rdc volumiossh-tunnel[2089]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2090]: ssh exited prematurely with status 255; autossh exiting Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:04 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Started MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2095]: port set to 0, monitoring disabled Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2095]: starting ssh (count 1) Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2095]: ssh child pid is 2098 Aug 28 11:21:04 volumio-clos-adama-rdc volumiossh-tunnel[2094]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 11:21:04 volumio-clos-adama-rdc autossh[2095]: ssh exited prematurely with status 255; autossh exiting Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Start request repeated too quickly. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 28 11:21:04 volumio-clos-adama-rdc systemd[1]: Failed to start MyVolumio SSH Tunnel. Aug 28 11:21:05 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:05 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 28 11:21:05 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: go-librespot daemon starting... Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="app state loaded" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 28 11:21:06 volumio-clos-adama-rdc sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 11:21:06 volumio-clos-adama-rdc sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: Stopping Music Player Daemon... Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: mpd.service: Succeeded. Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: Stopped Music Player Daemon. Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=info msg="zeroconf server listening on port 35729" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: Starting Music Player Daemon... Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="obtained new client token: AAHGGOYcY4mQtpQgD/XyAvIT9hIrcGkVhyHbQA2ggP9VFl3ke8T6LFRfbiFw4IkSFwfj480IZf4b/yEfaKab9+72SXAmIAHEDZtG2O6S/lSdf7k/LHV8bCRCS8guqlSiOe7ZQmVDZFeTasJjgRXm9LaJhvB9SNtBvZnNv4ZrBDZlO4SLdjK1Ch0yHklCt4fP7lkTPo2ZsvqGYoOKIqLJmFPSt11eGANoD+M3JEwbKP7BbgdY1m9lzEIZ" Aug 28 11:21:06 volumio-clos-adama-rdc sudo[2112]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Aug 28 11:21:06 volumio-clos-adama-rdc sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:21:06 volumio-clos-adama-rdc sudo[2112]: pam_unix(sudo:session): session closed for user root Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=debug msg="completed challenge" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:06 volumio-clos-adama-rdc go-librespot[2099]: time="2026-08-28T11:21:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:06 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 11:21:06 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:21:07 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:07 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:07 volumio-clos-adama-rdc mpd[2114]: Aug 28 11:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 11:21:07 volumio-clos-adama-rdc systemd[1]: Started Music Player Daemon. Aug 28 11:21:07 volumio-clos-adama-rdc sudo[2109]: pam_unix(sudo:session): session closed for user root Aug 28 11:21:07 volumio-clos-adama-rdc volumio[1135]: error: updateQueue error: null Aug 28 11:21:09 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:09 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Aug 28 11:21:09 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:09 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:09 volumio-clos-adama-rdc go-librespot[2133]: go-librespot daemon starting... Aug 28 11:21:09 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:09+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:09 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:09+02:00" level=debug msg="app state loaded" Aug 28 11:21:09 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=info msg="zeroconf server listening on port 43727" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="obtained new client token: AAEOraC18FlMZ+zhyfI1WvLZF7Sv+j8C3xLuMuZM6jUwBkN2bYbFTcqtNmeglwVBCCRho24vIZ6btGsN+Tre2Bi0Jf3ZJe8U3e+MxTcKof09ouILx4afZSQJyJjzJAtcrNfJhjIw34NE/ZOeVKetmxSAiBH6yKL2T8Km6bxiAwriReJpdoG0o5Gmux6TsUIz/qTjKzUrRtTZf2zGp7+9YAqtNNMT4PDtJKWQboOwthMIzPDI1QKpAw==" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=debug msg="completed challenge" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:10 volumio-clos-adama-rdc go-librespot[2133]: time="2026-08-28T11:21:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:10 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:10 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:10 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:10 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:13 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:13 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Aug 28 11:21:13 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:13 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:13 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:13 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: go-librespot daemon starting... Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="app state loaded" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=info msg="zeroconf server listening on port 42645" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="obtained new client token: AAG4zZNV+OGgyIfGjMP95aEi+ZoWUGxNn1m1CxyL4q80k5RGDobSpwAw/muMm1t6Bp1I7uHwsbqKPHJ9l0MwUQEduuLdi1H6vm57tUpz4F3dz8xwr2GXkUcnVepbrtiWHehOaMj7O7XHZ3mdmjEV7482lEJo1mmenfkv2fKGm/ULWh8qysYm3gEgLD312g4JwxK/AIvv6m4zi0NqucvwEdBA/rT6+sBfQkL0ZYsC3XCzpKgsyRoC6BO9" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=debug msg="completed challenge" Aug 28 11:21:13 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:13+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:14 volumio-clos-adama-rdc go-librespot[2143]: time="2026-08-28T11:21:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:14 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:14 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:16 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:16 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:17 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:17 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 28 11:21:17 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:17 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: go-librespot daemon starting... Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="app state loaded" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=info msg="zeroconf server listening on port 39481" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="obtained new client token: AAE2KnDIfYXGiL5IxCytecdAbyRsBPOWl22mFRIUkngNXQS4n7QEVQ/79DaAI6zDQH+SWZugN0zbLsRnMj7IyTj7cthvSRonZQ+r2nv9nH/TtXoavdGXIb0uMOieky6Y4WGrvsif7tn/Ehy62efvIgtge3sQorn+0MuR924y9Pk2mYxJnKyBtM2ZgFLL74lYAWUFNbbAC7RO8mPDXDAxB5tW3dyAC1tzskCSxRfjfKuoFnojJv3f7+jx" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=debug msg="completed challenge" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:17 volumio-clos-adama-rdc go-librespot[2153]: time="2026-08-28T11:21:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:17 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:17 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:19 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:19 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:20 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:20 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 28 11:21:20 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:21 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: go-librespot daemon starting... Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="app state loaded" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=info msg="zeroconf server listening on port 42285" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="obtained new client token: AAHYnqYtLClIGMs2ic1QqDN7gooiP+23B6XdQyZmweJQ/UL3WxYV+K1VnnpiF00VlkRgDHQ1n74i5hCjQSV5xCKHtUiP9VRvMdl01al4cXisLA/xa8J/c8KzctTe8TgO//PQ8JjHh8zcxaPn5EaLpHRF/tExsEgqpR181d/Wmxpoxn94WZqu4yCcKKsAhfXBHFxbNIGO0dhg368W1qnl+6+a06jIQEE21nsYJQeiVf4Q3fbfo+tP+bDm" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=debug msg="completed challenge" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:21 volumio-clos-adama-rdc go-librespot[2177]: time="2026-08-28T11:21:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:21 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:21 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:22 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:22 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:24 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:24 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 28 11:21:24 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:24 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:24 volumio-clos-adama-rdc go-librespot[2187]: go-librespot daemon starting... Aug 28 11:21:24 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:24+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:24 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:24+02:00" level=debug msg="app state loaded" Aug 28 11:21:24 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=info msg="zeroconf server listening on port 45545" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="obtained new client token: AAG05DeJVgxfoyzCk8PjJLparpInX3K6DqTrKdt6VqYQMhF/IumEPGDmL7v4gOhWneMBC5qtBqRWTvwn0oVR38PxB37za3FGKLHTtX33siXc/BvHcFyKDt2JquF/wnlErJA4pKC3egp96Fefxo2CU9iFdGRbfieuWBB213iUo7YIu/Stzyru2j14QRfZtdmoEUk+J8fOv/wkuL4CLmocMd0cYh47xx+43LRzsYzh1xg3xFDMsUU0FA==" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=debug msg="completed challenge" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:25 volumio-clos-adama-rdc go-librespot[2187]: time="2026-08-28T11:21:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:25 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:25 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:25 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:25 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:28 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:28 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 28 11:21:28 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:28 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:28 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:28 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: go-librespot daemon starting... Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=debug msg="app state loaded" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=info msg="zeroconf server listening on port 36923" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=debug msg="obtained new client token: AAFCdPgDUeixH/D15PCnKGZ3ngsdm7s11lKvkiTPPqQMb0NALGvppihXzY+ajKTNYHgHm19lFa4wjDxXiOgVX/cy4l8rUKofKvNtNpHgw62adqBrBpt9VJH3TGl/g19IOd01HEa7S2xPr7aCg5JVCBZU4IuyXaUl8AJ6vttVzY0BM05BARVN2FN1w64Ft8f8asa/roRMftdYie2ZDbzjJ8axHgZ/D5wLRriRPn1t3PPiXy7DO2hiodkW" Aug 28 11:21:28 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:28+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:29 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:29+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:29 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:29+02:00" level=debug msg="completed challenge" Aug 28 11:21:29 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:29+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:29 volumio-clos-adama-rdc go-librespot[2211]: time="2026-08-28T11:21:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:29 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:29 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:31 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:31 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:32 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:32 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 28 11:21:32 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:32 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: go-librespot daemon starting... Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=debug msg="app state loaded" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=info msg="zeroconf server listening on port 34967" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=debug msg="obtained new client token: AAG4sV1zQHOnhqM3EM3L+NgGHsZoLbAZdMusr8Q0piqv5W+4gf2PFuWwXFw65jBZ9Ij20kKWQFvEcSpdHcmYCazM06W7e3x+V0WDjOpcdKHyXmjIzgDWL8Ihjk20FzQCP3TxroZ3XMN3s8Lm2YCmeG0612bcSMneIP6/8/2RpxFhJceFJ3YQINBdcrANLjBh0Qyppm8vvbpn3KOBBBIk9EKry1up33+r3PnyqgD0CIz4WfG8qRnZdDPc" Aug 28 11:21:32 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:32+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:33 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:33+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:33 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:33+02:00" level=debug msg="completed challenge" Aug 28 11:21:33 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:33+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:33 volumio-clos-adama-rdc go-librespot[2221]: time="2026-08-28T11:21:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:33 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:33 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:34 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:34 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:36 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:36 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 28 11:21:36 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:36 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: go-librespot daemon starting... Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="app state loaded" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=info msg="zeroconf server listening on port 43515" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="obtained new client token: AAF9actARNGtoqe0vfoH2Z8Eda9Iyf/R2iVXM5n19qxRmxfA94eunrn8ljzvabbwkbVln3F4i6uDG+0wXyVfmrKuIr1I1V1GUbi3lxZ7h/m33ZoEebwpOjznyxWCq2MIAq1ze67Oe+bjiknUfMAyHVUm8M7UU8u18bIVVzJO81+lk3DhsllWSZjtXpCY4XxXW0qnhLmmMCWzI8MR2CKyHNhBki7nE4Vfvtnf3Me0f41BMQyxgrr9RtS2" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=debug msg="completed challenge" Aug 28 11:21:36 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:36+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:37 volumio-clos-adama-rdc go-librespot[2231]: time="2026-08-28T11:21:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:37 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:37 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:37 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:37 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:40 volumio-clos-adama-rdc volumiologrotate[512]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Aug 28 11:21:40 volumio-clos-adama-rdc volumiologrotate[512]: ls: cannot access 'CLOS': No such file or directory Aug 28 11:21:40 volumio-clos-adama-rdc volumiologrotate[512]: ls: cannot access 'ADAMA': No such file or directory Aug 28 11:21:40 volumio-clos-adama-rdc volumiologrotate[512]: ls: cannot access 'RDC': No such file or directory Aug 28 11:21:40 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:40 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 28 11:21:40 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:40 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: go-librespot daemon starting... Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="app state loaded" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=info msg="zeroconf server listening on port 33657" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:40 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="new websocket client" Aug 28 11:21:40 volumio-clos-adama-rdc volumio[1135]: info: Connection to go-librespot Websocket established Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="obtained new client token: AAFVrdy9KOFdw0G5stxHGx4FOxKPtZe0eyDxAwssvy/jCXeVXOiwlQtjP/aw6kcQPy6aF8q4hX8qARnyl6igA+m/fZk2Dm8i8dXugCzN9mcWSr+hCsSDLqd3kPcx3XOItr233VKU58fj9kaSLoeqlTsuSy/Iai7Q5+1+yHUx4M9aS4TH20KDHnQaNwDJlE3dFYv+eqEruC6q1QfD+ClcJhTf9FZF7EBVxsCw8HIxAEGLEqdoVNlFe1E/" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=debug msg="completed challenge" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:40 volumio-clos-adama-rdc go-librespot[2306]: time="2026-08-28T11:21:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:40 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:40 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:40 volumio-clos-adama-rdc volumio[1135]: info: Connection to go-librespot Websocket closed Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: Getting Spotify volume Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: (node:1135) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: (node:1135) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: (node:1135) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: Aligning Spotify Volume to Volumio Volume Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: Setting Spotify Volume from Volumio: 100 Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:43 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:43 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:43 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 28 11:21:43 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:44 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: go-librespot daemon starting... Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="app state loaded" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=info msg="zeroconf server listening on port 36989" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="obtained new client token: AAGiFPdOTVGH9rV9RzH8DFqkCoER7ZCk11Q0StMfkDeq4aEUyK/6OIllT1wnvDE4WHCy9OdY7s2mY6oAWyUQzoLvBhHh9ccTp/n8acNg0vXuQEgSVXohh3PNjZb9DsJCw8escCz4pnfkDOMYInqS/d+3ZD57wthHtSRgbRX52Dn/D02jerDzOUVXJOYt/5Hml5uPGq3piW4dl6UQC4gcQz9bNaYgbFGrF73RXUZKHmFYciD2CbXJLKlh" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=debug msg="completed challenge" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:44 volumio-clos-adama-rdc go-librespot[2317]: time="2026-08-28T11:21:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:44 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:44 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:45 volumio-clos-adama-rdc volumio[1135]: info: Sending Spotify command with payload to local API: /player/volume Aug 28 11:21:45 volumio-clos-adama-rdc volumio[1135]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:46 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:46 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:47 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:47 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 28 11:21:47 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:47 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: go-librespot daemon starting... Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:47+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:47+02:00" level=debug msg="app state loaded" Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:47+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:21:47 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:47+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=info msg="zeroconf server listening on port 43309" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=debug msg="obtained new client token: AAHcDxQ0fOe4ym223ce8yToz8x8WCRytkErX36u/FS13Cs8YihMThBjdPrt8llIw5lwVQvhUxWCm2h0mYYDRoa05/pbM+PXmU5FscEzY61iQj44yHNFJTgLW/Jb3mdr8sOC04lVK+25E2tDO+sfaR89+GVyD+5uMmStDPs+hw918f8CZJSYYsD9AZ2e7aSdqE6Visayaqg+GU/6f44LMcO8qra/N3gNlE/UYF+2Cn4TA/4RX7kpJUA==" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=debug msg="completed challenge" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:48 volumio-clos-adama-rdc go-librespot[2327]: time="2026-08-28T11:21:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:48 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:48 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:49 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:49 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:51 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:51 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 28 11:21:51 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:51 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: go-librespot daemon starting... Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="app state loaded" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=info msg="zeroconf server listening on port 34513" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="obtained new client token: AAEAmlGZjeQNe9zJ3U3mE8cZtwbWk8yXyFXUHqdiCcPpIHvdB0CeJTRrJnpZ9ou5qTKtEjw21dOTo5ykCnmloiDa0h38MbbvTOHtvJFtrF4banLqiaiE6Mb/xIsxUv5um5nrfGQzMcKXlS8ZifjWIxxJm1FXAyUQMVDvbTYYrMZ+LspO9rCV66KCU5ZG5ChkMH6Q1OCyO/yGDepscK+heUuPQISnQyzeox9GJVS0N8vgQrenqiE56cze" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=debug msg="completed challenge" Aug 28 11:21:51 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:51+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:52 volumio-clos-adama-rdc go-librespot[2351]: time="2026-08-28T11:21:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:52 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:52 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:52 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:52 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:55 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:55 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Aug 28 11:21:55 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:55 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: go-librespot daemon starting... Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="app state loaded" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=info msg="zeroconf server listening on port 34509" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="obtained new client token: AAEddA8hh8UAFbXxajWqO071qFHTSEeVDnFQFsn5Rht4zycCdYRT0jFHqBQqqCsltMtdARaqsyBgSJar6dDx1djsuE2j4uWQ8O6xdLiagQe4pYq9DF9yOOdZ59qfsMZH1PUgTIF/8yPb/ecD8ujdFZ5hs/OweeX/CRBaeBeX/JNwtgprJIXN7xEUO4DQYFxR530O07KBXt5yIXfVX1/AQZmeMjQiSqhacnDf2bohgQzR8LEU9UrDj2t4" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=debug msg="completed challenge" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:55 volumio-clos-adama-rdc go-librespot[2361]: time="2026-08-28T11:21:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:55 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:55 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:21:55 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:55 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:58 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:21:58 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:21:58 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:21:58 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Aug 28 11:21:58 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:21:59 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: go-librespot daemon starting... Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="app state loaded" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=info msg="zeroconf server listening on port 35259" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="obtained new client token: AAHq/IRoyKafxIP5dS4NZ3jPOc8kVI8478kYzDmObn6BmbiLYxNCKU4ResH3w9drtXc6DgL73ntPqrKlhkhL8ynSUhqM6f3/WgiOWDpHLReTF924azfeUpSLd/+xXzQk2uwFRJjCjglMV1rSfsI+FtD4jb8bubVr4UQtCjqAczUQFcciy35l5X6pJWMqYyr38lcrX/oIzxN6NhhMhlvhu/ND9uHyqE73QwFWqHsr3Yu+dsZgr8I8/G2b" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="completed keyexchange" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=debug msg="completed challenge" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:21:59 volumio-clos-adama-rdc go-librespot[2385]: time="2026-08-28T11:21:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:21:59 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:21:59 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:01 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:01 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:02 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:02 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Aug 28 11:22:02 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:02 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:02 volumio-clos-adama-rdc go-librespot[2396]: go-librespot daemon starting... Aug 28 11:22:02 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:02+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:02 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:02+02:00" level=debug msg="app state loaded" Aug 28 11:22:02 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=info msg="zeroconf server listening on port 39095" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="obtained new client token: AAFEGb7dhtVXeIrsTfAKZZ1Urin7j+htCgrvYnOHF/+ntaIykRcsgtnak+ERhFEAMTX1rpyAP8Fi7S0GO3VhAtFkP8nefAiKL4y+HqRaHk6h4nbPLZIis8V6J0TyonXQmjLzbTQBy1GdtmsCEp0SomUkAvY7KAj1nrQP2mt6UOEPSPybiVSlvcR5+3CDMlGwtPauFDsHbmXWjAiBuEblpElkgeRUgPxCnDe/4AZLzLNrflURKKwa2g==" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=debug msg="completed challenge" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:03 volumio-clos-adama-rdc go-librespot[2396]: time="2026-08-28T11:22:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:03 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:03 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:04 volumio-clos-adama-rdc sudo[2407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:22:04 volumio-clos-adama-rdc sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:22:04 volumio-clos-adama-rdc sudo[2407]: pam_unix(sudo:session): session closed for user root Aug 28 11:22:04 volumio-clos-adama-rdc sudo[2410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:22:04 volumio-clos-adama-rdc sudo[2410]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:22:04 volumio-clos-adama-rdc sudo[2410]: pam_unix(sudo:session): session closed for user root Aug 28 11:22:04 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:04 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: verbose: New Socket.io Connection to 192.168.1.142 from 192.168.1.31 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:22:05 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:22:06 volumio-clos-adama-rdc sudo[2413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 11:22:06 volumio-clos-adama-rdc sudo[2413]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:22:06 volumio-clos-adama-rdc sudo[2413]: pam_unix(sudo:session): session closed for user root Aug 28 11:22:06 volumio-clos-adama-rdc sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 11:22:06 volumio-clos-adama-rdc sudo[2416]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 11:22:06 volumio-clos-adama-rdc sudo[2416]: pam_unix(sudo:session): session closed for user root Aug 28 11:22:06 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:06 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Aug 28 11:22:06 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:06 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: go-librespot daemon starting... Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="app state loaded" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=info msg="zeroconf server listening on port 43371" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="obtained new client token: AAF5laeleorqsRVCkCam4n7gEUBqDf1Q6kia5/l2W+t7eZVss16bwgD1Tok66vdhNwWSINq8n43wJiw15BSRBx5Go2ulfXz4+npzNkhm7qZ4fSgOvA98++Y4PahCHZ2RofJMaVEU1ZKGOiVSOOgMtBbL7Q6ORrQh/g5oWxbV8YsIKJjm1ypbC3kEfzgMImQEsezCnLPEmm+MkMS48PwF3yQcjCLB7TKrp/w0yB8r9jDOFsazVT9N2FDN" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=debug msg="completed challenge" Aug 28 11:22:06 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:06+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:07 volumio-clos-adama-rdc go-librespot[2418]: time="2026-08-28T11:22:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:07 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:07 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: verbose: New Socket.io Connection to 192.168.1.142 from 192.168.1.31 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: Listing playlists Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:07 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: Received Get System Info Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: Discovery: Getting this device information Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:22:09 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 11:22:10 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:10 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Aug 28 11:22:10 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:10 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: go-librespot daemon starting... Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="app state loaded" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: Received Get System Info Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: Discovery: Getting this device information Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=info msg="zeroconf server listening on port 34475" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="obtained new client token: AAFjeW0jfMR/odxZfTN7GFeSPWaVw0hr6tIluHsEvfkKfLpivzzYBVT9hgbXbdmjFrhv5pLzv7C/RcP1HoMmP1QFnRs0ND7rh5OKt2XOM/HHiY2PTWpNGJwreqFyI3oFAtf3D02NBRIvsAY2y6+sk6HZkkgbCY/ZgJq6qB4bAKFlcl0JtWTJFsn9I1O21oOSZbDNBvtkZDWXYw5OHlffrk8VHpbjoZdrSbZlHtYe5YAoNZEOm7YdTil2" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=debug msg="completed challenge" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:10 volumio-clos-adama-rdc go-librespot[2442]: time="2026-08-28T11:22:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:10 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:10 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:10 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:13 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:13 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:13 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:13 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Aug 28 11:22:13 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:14 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: go-librespot daemon starting... Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="app state loaded" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=info msg="zeroconf server listening on port 45787" Aug 28 11:22:14 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:14 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetQueue Aug 28 11:22:14 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::getQueue Aug 28 11:22:14 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getQueue Aug 28 11:22:14 volumio-clos-adama-rdc volumio[1135]: info: Preload queue cleared Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="obtained new client token: AAGKekrjUXAkH/X3/LgKgFSVHghcNwG5+UO7UC9lEwDwlXK8lbyFx8I942HP2lN+8SaKgCP2TWebRV03QdgxnK/UChHWKUjNyYpVibUdZNuKwrN4KMLn+PhJXBe7WM0Cq+Hlw3Th/mOMK3wWSqeatZ5FnN/mUznrnlE0jIO6ylDl6y52AzZoS0mDKJjLBv5fmP0WbfJUFf0YwPFKTQ3JelWCtkfwsvSiTyx4O2oX2Jp+UXCoAsopuBxE" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=debug msg="completed challenge" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:14 volumio-clos-adama-rdc go-librespot[2452]: time="2026-08-28T11:22:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:14 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:14 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:16 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:16 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:17 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:17 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Aug 28 11:22:17 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:17 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: go-librespot daemon starting... Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:17+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:17+02:00" level=debug msg="app state loaded" Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:22:17 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=info msg="zeroconf server listening on port 45703" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=debug msg="obtained new client token: AAHZl4+y4yyAD2vf/t4jMC50Dn591yiebCC0Qfe5lenLDS7Kt+2PAT5ZAKD6FFJHDwL+0ly1rgvAIi0uQ5b6aiY+9qaGN8KSJKPE+kksEsWhvysgotf4onUTIsQ0nV2FGz7wDlbR5D9BiyMshXqbaufjFPjXvxJlvl9QXocfyYJdF9Sl5bvVqPY8PPz0L8jchQ+RY5+oRx23aJZxfqmhz4MObMCsogKoaydlPtG7e7VojLarFHaaow==" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=debug msg="completed challenge" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:18 volumio-clos-adama-rdc go-librespot[2462]: time="2026-08-28T11:22:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:18 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:18 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:19 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 11:22:19 volumio-clos-adama-rdc volumio[1135]: info: Getting webradio selection Aug 28 11:22:19 volumio-clos-adama-rdc volumio[1135]: info: Preload queue cleared Aug 28 11:22:19 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:19 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:21 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:21 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Aug 28 11:22:21 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:21 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: go-librespot daemon starting... Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="app state loaded" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=info msg="zeroconf server listening on port 35123" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="obtained new client token: AAHU6ztP7f9Cw7kwLY45PkdfqV360UJ5YmHe3Zmg3DePppXYBh/h69IPyr599D1YpD5eEPU89Jr1FPhTkGyBWTkN45xGFYvR6HrQaySw1i0Giq5ZJNMqvPW+uqZN1CeknbzE8QEdDjGMIActwUHPfPmMSivu39KUj+Q4R2c52l2NXwfXVlXnBivNykEQlKstGSpM3qc83qZh9TP63BT5hQjD3SG2zEwi/FIIeQ6uAincaeh/r4UH5ssO" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=debug msg="completed challenge" Aug 28 11:22:21 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:21+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:22 volumio-clos-adama-rdc go-librespot[2486]: time="2026-08-28T11:22:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:22 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:22 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:22 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:22 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: Received Get System Version Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: Received Get System Info Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: Discovery: Getting this device information Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioGetState Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:22:24 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 11:22:25 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 28 11:22:25 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Aug 28 11:22:25 volumio-clos-adama-rdc systemd[1]: Stopped go-librespot Daemon. Aug 28 11:22:25 volumio-clos-adama-rdc systemd[1]: Started go-librespot Daemon. Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: go-librespot daemon starting... Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=info msg="running go-librespot 0.7.1" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="app state loaded" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=info msg="zeroconf server listening on port 46577" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="obtained new client token: AAHDEUpVVdphsWTB1mhjaH0ZV2iTe/UTOk1H6GV/y36sCy4RYjGzVyyT6KzXwhLpuYY8Ev017iHvkmSGxVCSSB9C3R5sKnQ5G5+UUYefpDJoxx80djblHFYu7Q+Jmk9dM0hWO1Jilhy0qfhPW0BNQVoIItKFoaaJ/D2OmFaJUApub2d2YCSC5PdIRct1MQq7eH5leoc46GI9lYIQd3Su/mJj9a2GiuzYY4wZ75Hf5pkYmPcg8uzx9sAi" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="completed keyexchange" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=debug msg="completed challenge" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=info msg="authenticated AP" username="21*********************2i" Aug 28 11:22:25 volumio-clos-adama-rdc go-librespot[2496]: time="2026-08-28T11:22:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 28 11:22:25 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 28 11:22:25 volumio-clos-adama-rdc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 28 11:22:25 volumio-clos-adama-rdc volumio[1135]: info: Initializing connection to go-librespot Websocket Aug 28 11:22:25 volumio-clos-adama-rdc volumio[1135]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: Preload queue cleared Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::ClearQueue Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::stop Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::updateTrackBlock Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrackBlock Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::stPlaybackTimer Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreStateMachine::pushState Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CorePlayQueue::getTrack 0 Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: info: CoreCommandRouter::volumioPushState Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at /volumio/app/index.js:326:81 Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1256:12) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at Socket.emit (events.js:315:20) Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: code: 'NumberExpected' Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: } Aug 28 11:22:26 volumio-clos-adama-rdc volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 11:22:26 volumio-clos-adama-rdc sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-28 11:21 Aug 28 11:22:26 volumio-clos-adama-rdc sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"