Oct 02 10:36:00 spla-repro sudo[755]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 10:36:00 spla-repro sudo[755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro sudo[754]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro sudo[755]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:00 spla-repro sudo[754]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 10:36:00 spla-repro sudo[754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:00 spla-repro volumio[493]: info: No need to fix Spotify hosts Oct 02 10:36:00 spla-repro sudo[754]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 02 10:36:00 spla-repro volumio[493]: info: An error occurred while refreshing Spotify Token Error: Bad Request Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 02 10:36:00 spla-repro volumio[493]: info: Starting Shairport Sync Oct 02 10:36:00 spla-repro volumio[493]: info: Starting Shairport Sync Oct 02 10:36:00 spla-repro volumio[493]: info: Starting Shairport Sync Oct 02 10:36:00 spla-repro sudo[782]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:00 spla-repro sudo[784]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:00 spla-repro sudo[782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 10:36:00 spla-repro sudo[782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:00 spla-repro sudo[784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 10:36:00 spla-repro sudo[784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 02 10:36:00 spla-repro sudo[787]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:00 spla-repro sudo[787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 10:36:00 spla-repro sudo[787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Oct 02 10:36:00 spla-repro systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 10:36:00 spla-repro systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 10:36:00 spla-repro systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 10:36:00 spla-repro systemd[1]: shairport-sync.service: Consumed 3.970s CPU time. Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Oct 02 10:36:00 spla-repro sudo[791]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Oct 02 10:36:00 spla-repro sudo[791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 02 10:36:00 spla-repro sudo[791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Oct 02 10:36:00 spla-repro systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 10:36:00 spla-repro sudo[782]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:00 spla-repro sudo[784]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Oct 02 10:36:00 spla-repro volumio[493]: info: Shairport-Sync Started Oct 02 10:36:00 spla-repro volumio[493]: Error adding Membership: Error: addMembership EINVAL Oct 02 10:36:00 spla-repro systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 10:36:00 spla-repro sudo[791]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:00 spla-repro systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 10:36:00 spla-repro systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Oct 02 10:36:00 spla-repro volumio[493]: info: Shairport-Sync Started Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Oct 02 10:36:00 spla-repro volumio[493]: info: Upmpdcli Daemon Started Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Oct 02 10:36:00 spla-repro systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Oct 02 10:36:00 spla-repro sudo[787]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:00 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Oct 02 10:36:01 spla-repro volumio[493]: info: Shairport-Sync Started Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 52 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 53 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Oct 02 10:36:01 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:01 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.202 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 55 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Oct 02 10:36:01 spla-repro volumio[493]: info: Listing playlists Oct 02 10:36:01 spla-repro volumio[493]: info: Listing playlists Oct 02 10:36:01 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 02 10:36:01 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:01 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:01 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 02 10:36:01 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:01 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:01 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:01 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:01 spla-repro mpd[709]: 2026-10-02T10:36:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 10:36:01 spla-repro systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 10:36:01 spla-repro sudo[669]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:01 spla-repro sudo[658]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:01 spla-repro volumio-remote-updater[719]: [2026-10-02 10:36:01] [connect] Successful connection Oct 02 10:36:01 spla-repro volumio[493]: info: Completed starting Core Plugins Oct 02 10:36:01 spla-repro volumio[493]: info: ------------------------------------------- Oct 02 10:36:01 spla-repro volumio[493]: info: ----- MyVolumio plugins startup ---- Oct 02 10:36:01 spla-repro volumio[493]: info: ------------------------------------------- Oct 02 10:36:01 spla-repro volumio[493]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 02 10:36:01 spla-repro volumio[493]: error: MPD error: The expression evaluated to a falsy value: Oct 02 10:36:01 spla-repro volumio[493]: assert.ok(self.idling) Oct 02 10:36:01 spla-repro volumio[493]: error: The expression evaluated to a falsy value: Oct 02 10:36:01 spla-repro volumio[493]: assert.ok(self.idling) Oct 02 10:36:01 spla-repro volumio-remote-updater[719]: [2026-10-02 10:36:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790930161 101 Oct 02 10:36:01 spla-repro volumio[493]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 57 Oct 02 10:36:01 spla-repro volumio[493]: info: MPD running with PID709 Oct 02 10:36:01 spla-repro volumio[493]: ,establishing connection Oct 02 10:36:01 spla-repro volumio[493]: error: updateQueue error: null Oct 02 10:36:01 spla-repro volumio[493]: error: updateQueue error: null Oct 02 10:36:02 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 02 10:36:02 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:02 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:02 spla-repro go-librespot[820]: go-librespot daemon starting... Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=debug msg="app state loaded" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:03 spla-repro volumio[493]: info: go-librespot daemon successfully initialized Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+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]" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+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]" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=info msg="zeroconf server listening on port 38099" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=debug msg="obtained new client token: AAH1LR4/kxGSJSx//IPhWolbkOBnTqa8l+/YsMLNq/Dlyy4FuoO3yVI/YA5XDbQjWt8EtdYoE7d7myRyOTHkSAIXKwa3vFqFzYzJ/S2cNcl6XKxeEi9v9nb44dewGa9EPlXgw9TNGfOG7N6IgloydBtlqlGEXqHjsQTx/09rBWc4UAm6lpXcfM+ohdYxeyOsgPUcGd3ZJ4ZqdNlYwRFJux8ilBnxmHRwaDKsWZbAY59kBaViinVRbaWMYw==" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=debug msg="completed challenge" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:03 spla-repro go-librespot[821]: time="2026-10-02T10:36:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:03 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:03 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:04 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:04 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:06 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:06 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:06 spla-repro volumio[493]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 02 10:36:06 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 02 10:36:06 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:07 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:07 spla-repro go-librespot[833]: go-librespot daemon starting... Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=debug msg="app state loaded" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+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]" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+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]" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+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]" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=info msg="zeroconf server listening on port 46573" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=debug msg="obtained new client token: AAEV3zF3eQbrN8tWyspRl7XmI5VXZyp8Efo1pN0explGdyA5Q3bAW8MfvGNh6Xq3D2tFhIyLNPkFBY8hAoskt/2FWGYlLoxvMEyA6kAZ44yv/8D5rdSaKxFuHd/cZiGjqT4jng/34N8x+lvmgTJGCapTPxn4mEKARady3h/YeBO/TJjXCKHyjQgtb825i7g8+Gko8bG4owa1Cj13qnJmm9N0r87ygK4RnOknvTT5NmaVPirylYX9weoLIA==" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=debug msg="completed challenge" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:07 spla-repro go-librespot[834]: time="2026-10-02T10:36:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:07 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:07 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:09 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:09 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin bluetooth to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin multiroom to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin metavolumio to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin cd_controller to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 02 10:36:10 spla-repro volumio[493]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 02 10:36:10 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 02 10:36:10 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:11 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:11 spla-repro go-librespot[860]: go-librespot daemon starting... Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=debug msg="app state loaded" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:11 spla-repro volumio[493]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 02 10:36:11 spla-repro volumio[493]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 02 10:36:11 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:11 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+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]" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+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]" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+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]" Oct 02 10:36:11 spla-repro volumio[493]: info: Starting MyVolumio Remote Streaming Endpoints Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=info msg="zeroconf server listening on port 42293" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:11 spla-repro volumio[493]: info: MyVolumio login type: Token Oct 02 10:36:11 spla-repro volumio[493]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 02 10:36:11 spla-repro volumio[493]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=debug msg="obtained new client token: AAEwzQdE/X2msvRuNIago+foFyYMQ3h1cEvrRjduF2MEH91/TrnTxEML4GYlWOJSPZwLw8doaarURmmxCWv8YaL+8kbrFKw0oQVKWl/ahI3/DJ53dmvN8kRiLZyJl50dNu8S7bl+SkY9lbTS5IkdG1hBB7CBjxNIlM81dmmVs7bvK8Xz0QUTr3lQ7BfCvccKbHcjlLq6Q+qf6fhjQcGtjHk7AyFYDPVGzx9gJgOE8e26bjSe70plF4bkqQ==" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=debug msg="completed challenge" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:11 spla-repro go-librespot[861]: time="2026-10-02T10:36:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:11 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:11 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:12 spla-repro volumio[493]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 10:36:12 spla-repro volumio[493]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 10:36:12 spla-repro volumio[493]: info: Streaming services startup Oct 02 10:36:12 spla-repro volumio[493]: info: Starting Streaming Daemon Oct 02 10:36:12 spla-repro sudo[875]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:12 spla-repro volumio[493]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 10:36:12 spla-repro sudo[875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 10:36:12 spla-repro sudo[875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:12 spla-repro sudo[875]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:12 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:12 spla-repro volumio[493]: error: Cannot start Volumio Streaming Daemon Oct 02 10:36:12 spla-repro volumio[493]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 10:36:12 spla-repro volumio[493]: sudo: unable to resolve host spla-repro: System error Oct 02 10:36:12 spla-repro volumio[493]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 10:36:12 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:13 spla-repro volumio[493]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 10:36:14 spla-repro volumio[493]: info: MyVolumio token set successfully Oct 02 10:36:14 spla-repro volumio[493]: info: MYVOLUMIO: Adding device Oct 02 10:36:14 spla-repro volumio[493]: info: MYVOLUMIO: Evaluating Server Oct 02 10:36:14 spla-repro volumio[493]: info: MyVolumio status changed Oct 02 10:36:14 spla-repro volumio[493]: info: Streaming services startup Oct 02 10:36:14 spla-repro volumio[493]: info: Starting Streaming Daemon Oct 02 10:36:14 spla-repro volumio[493]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 10:36:14 spla-repro volumio[493]: info: Removing audio output: Oct 02 10:36:14 spla-repro volumio[493]: info: Stoppping Tunnel 1 Oct 02 10:36:14 spla-repro sudo[904]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:14 spla-repro sudo[904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 10:36:14 spla-repro sudo[904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:14 spla-repro sudo[904]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:14 spla-repro sudo[907]: volumio : unable to resolve host spla-repro: System error Oct 02 10:36:14 spla-repro sudo[907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 02 10:36:14 spla-repro sudo[907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 10:36:14 spla-repro volumio[493]: error: Cannot start Volumio Streaming Daemon Oct 02 10:36:14 spla-repro volumio[493]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 10:36:14 spla-repro volumio[493]: sudo: unable to resolve host spla-repro: System error Oct 02 10:36:14 spla-repro volumio[493]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 10:36:14 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:14 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 10:36:14 spla-repro sudo[907]: pam_unix(sudo:session): session closed for user root Oct 02 10:36:14 spla-repro volumio[493]: info: Remote SSH Stopped Oct 02 10:36:14 spla-repro volumio[493]: info: Setting Geolocation for MyVolumio to eu4 Oct 02 10:36:14 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:14 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:14 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:14 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 02 10:36:14 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:14 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:14 spla-repro go-librespot[911]: go-librespot daemon starting... Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=debug msg="app state loaded" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:15 spla-repro volumio[493]: info: Successfully Added MyVolumio device Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+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]" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+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]" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+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]" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=info msg="zeroconf server listening on port 37761" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=debug msg="obtained new client token: AAH+gg/euFqEm+7wD5pwvcCweIZU+N/Ck3R7NItPnRCNrExa8QhubfEp1TZPC1yY2hux+ntbn6BM4n+ZYw8aO+w8TM9d1pu+ONDGQfDb+zWaJ2Zk9lrNHJWFuuc/YVhCDHBjXLGKEk6YvGgNPeliQiKx7vD4q20Qq0ZG2/IBWkuqbXxjrAgiHYM+uizlM0myxmtvhVOWzgiuFevfPDPVNlkYe+uxw830lKLPmLzhlx00XXQx9Au+X5QB0Q==" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=debug msg="completed challenge" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:15 spla-repro go-librespot[912]: time="2026-10-02T10:36:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:15 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:15 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:15 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:15 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:15 spla-repro volumio[493]: info: Updating MyVolumio device info Oct 02 10:36:15 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:15 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:15 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:16 spla-repro volumio[493]: info: Successfully Updated MyVolumio device Oct 02 10:36:16 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 10:36:16 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 10:36:16 spla-repro systemd[1]: welcome.service: Deactivated successfully. Oct 02 10:36:16 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 10:36:16 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 10:36:16 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 10:36:16 spla-repro welcome[939]: Resolved ip:[1] 192.168.200.203 Oct 02 10:36:16 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 10:36:16 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 10:36:16 spla-repro volumio[493]: info: Received Get System Info Oct 02 10:36:16 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 10:36:16 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 10:36:16 spla-repro volumio[493]: info: Discovery: Getting this device information Oct 02 10:36:16 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:16 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:16 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 10:36:16 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 10:36:16 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 10:36:17 spla-repro volumio5-onboarding[10922]: time=2026-10-02T10:36:17.854+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 10:36:18 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:18 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:18 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 02 10:36:18 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:18 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:18 spla-repro go-librespot[945]: go-librespot daemon starting... Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=debug msg="app state loaded" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+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]" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+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]" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+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]" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=info msg="zeroconf server listening on port 46557" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=debug msg="obtained new client token: AAE2fkonOm8Xs3WdLgml9fnwhhR7xHtSYAi0DT2u87s0TpzcF3tKdN2ZDGBEzr0BLm3zhMAE++B30GnX2skCroB7/Z5/AQ3bdoaQY36oqP0fLyBd7n8LbPqFrJ7Nj5qpWJdN8FAjGYTFYVHUqFU9L/PrQdPVEYQJe6I0u5Ac+hdbIdUqAt9XoqCg6FEK9SinX36FPLf4TzYRrA3chUiX2aovegawH6O3ReVk223QVme6fuMoV9569BeuFA==" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=debug msg="completed challenge" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:19 spla-repro go-librespot[947]: time="2026-10-02T10:36:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:19 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:19 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:21 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:21 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:22 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 02 10:36:22 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:23 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:23 spla-repro go-librespot[970]: go-librespot daemon starting... Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=debug msg="app state loaded" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+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]" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+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]" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+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]" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=info msg="zeroconf server listening on port 33677" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=debug msg="obtained new client token: AAEVsPHY0acnuqRR97cFz6l14WD5l/nEMsPmU/b0ExSPBGMjlMrOdAkGMP6a13XKFnsJunaO8aYpTPgky7tOqbG3e0gZTky4zI4LSgJ8cwY0USPKluzNUsH8dDkBUvug75bjr0Z+EHkR4Xn3bKZuPCt0LuZ/4sM3XA6XCtCI8UE9pJOmHQTY/hr3cPjyCKENy30D5fxWaFcLWMq9dPTDvCu7gEJ8F313Pzn+hNP8lrxC+wr22vtC9vvTFA==" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=debug msg="completed challenge" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:23 spla-repro go-librespot[971]: time="2026-10-02T10:36:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:23 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:23 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:24 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:24 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:24 spla-repro volumio[493]: info: Listing playlists Oct 02 10:36:24 spla-repro volumio[493]: info: Listing playlists Oct 02 10:36:24 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:24 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:26 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 10:36:26 spla-repro volumio[493]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 58 Oct 02 10:36:26 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:26 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:26 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 10:36:26 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:26 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 10:36:26 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 02 10:36:26 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:27 spla-repro volumio-remote-updater[719]: Test mode disabled Oct 02 10:36:27 spla-repro volumio-remote-updater[719]: Alpha mode disabled Oct 02 10:36:27 spla-repro volumio-remote-updater[719]: Alpha legacy test mode disabled Oct 02 10:36:26 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:27 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 02 10:36:27 spla-repro go-librespot[984]: go-librespot daemon starting... Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=debug msg="app state loaded" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:27 spla-repro volumio[493]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 10:36:27 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+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]" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+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]" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+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]" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=info msg="zeroconf server listening on port 45929" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=debug msg="obtained new client token: AAFSINW1cer0Gj1HTuWO/WnTNmmwP/iUBPfrh896SNvq/XQ4zObofFAGHoDCKM495wocPOS0ypNOS2zrzU7V4JT5enX4xDM8PcLb88K5fR4XW8Fk6Gi34RyKfIq1tBUWFFzkCvZT9SOTe72wVEZ6WHzqn88fr03pwLkS2kCI6s/b2/w3lZwT59ZyyPfcyY06uw1niGuwhUPLBtj7c/tDHK4oESVhdPA6rXZqYqIZ2LjODY4mfyCMqOtuWA==" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=debug msg="completed challenge" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:27 spla-repro go-librespot[986]: time="2026-10-02T10:36:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:27 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:27 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:27 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:27 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:30 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:30 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:30 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 02 10:36:30 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:31 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:31 spla-repro go-librespot[1019]: go-librespot daemon starting... Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=debug msg="app state loaded" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+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]" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+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]" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+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]" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=info msg="zeroconf server listening on port 35249" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 10:36:31 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=debug msg="obtained new client token: AAF5GBCn4lD4jJ83eN+3qky2jE8l2M0Fzajirof7ItdDlPnvQRn9tzlHrEm3eUDQJUMbj2oGxfUWF6uJSu3dgKy7dijWG+Esf+Nt9j65TONMcpprxPdAYfL7PQ9gKgHri9UUaA1gO2cR2PQz9aKcBmw8BDdQ5tt/1Twce9yAu78LncPMizbXpwBST3AlHHj4CYqKnZ0gtgf4ms8ftJgWw4wolEMDndJZo1Ek7hyUUFfzG1npjt+nMkLV0g==" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=debug msg="completed challenge" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:31 spla-repro go-librespot[1020]: time="2026-10-02T10:36:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:31 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:31 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:32 spla-repro volumio[493]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 02 10:36:32 spla-repro volumio[493]: info: Completed starting MyVolumio Plugin Oct 02 10:36:32 spla-repro volumio[493]: [Metrics] CommandRouter: 45s 974.07ms Oct 02 10:36:32 spla-repro volumio[493]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 10:36:32 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:36:32 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:36:32 spla-repro volumio[493]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:36:32 spla-repro volumio[493]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:36:33 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 10:36:33 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 10:36:33 spla-repro volumio[493]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 10:36:33 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:33 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:34 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:34 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:34 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 02 10:36:34 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:35 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:35 spla-repro go-librespot[1035]: go-librespot daemon starting... Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=debug msg="app state loaded" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+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]" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+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]" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+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]" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=info msg="zeroconf server listening on port 37431" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=debug msg="obtained new client token: AAFC3DQQtYWfiBLjOw+uPR1jrkRSUyPpn2i+rh4ykmM3NPmaFT2dyvrRO6JwwCvxgZez4XLBg6dej6FxQU/J+4Vtb52sWyt8vupaRFa+ROEcNicFb7p9z98fbmK9eE2/lefd0p1MTUoilU274BZymFS8tolIW3IZR3xS1vK9a95klce7JpWCOugTz+qVK1EFDsHNEBuIEpe2nPC+fLTMGd6cbFMjpoktDKK+7vxlioo30AS07YvUYZslKg==" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=debug msg="completed challenge" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:35 spla-repro go-librespot[1036]: time="2026-10-02T10:36:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:35 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:35 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:36 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:36 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:38 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 02 10:36:38 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:39 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:39 spla-repro go-librespot[1045]: go-librespot daemon starting... Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=debug msg="app state loaded" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+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]" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+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]" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+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]" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=info msg="zeroconf server listening on port 38779" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=debug msg="obtained new client token: AAHSmy34Dceu2mvfQxrR0fZa/uLgtlo7nuuWo40IDscEsYx3veyhjQGAqtnJkZadzoYfdFOP5BiX/wC5y73Kpv7zb8M/qV0IhC4+SfEV0HXp6Y6eQOO4/G8qYQmnW3ZBvX4RaRSMulyrd8Yzl1d83kiTqfwdOrWono4kzxWQuXcakwdFAo16A/vbAZTvhhEJGDhlK8lOL9+p4ojG4bE6KMK+DWmn7A2bWvfi6es9JUOGf8g1m3evnvvifw==" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=debug msg="completed challenge" Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:39 spla-repro volumio[493]: info: BOOT COMPLETED Oct 02 10:36:39 spla-repro go-librespot[1046]: time="2026-10-02T10:36:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:39 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:39 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:39 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:39 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:42 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:42 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:42 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 02 10:36:42 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:43 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:43 spla-repro go-librespot[1069]: go-librespot daemon starting... Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=debug msg="app state loaded" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+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]" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+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]" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+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]" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=info msg="zeroconf server listening on port 33677" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=debug msg="obtained new client token: AAH51h0puZXURTBMZ22mzJAF189+AfeB6YRILZBKIkP+qaQg+SZvGwJZ6kqkV1maSSCm/CYK05njNhIxdAUQmTGf3bq1ggeBuyo7LsrHEAXYje90uuFkT9wJWuha3DQVYTssthGBQCk8m4rdWF56ClKb+2T+VFagnEkmYS9fxaHxgx6KUnn5FGSlUKXI0yWMbt1wgkEHx1cJdI5SUG6+y8peHt3ccMCr1rhD9rUzjKUTHpT/OipsDqRhIA==" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+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" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=debug msg="completed challenge" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:43 spla-repro go-librespot[1070]: time="2026-10-02T10:36:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:43 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:43 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:44 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:44 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:44 spla-repro volumio[493]: info: Listing playlists Oct 02 10:36:44 spla-repro volumio[493]: info: Listing playlists Oct 02 10:36:45 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:45 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:46 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 02 10:36:46 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:47 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:47 spla-repro go-librespot[1079]: go-librespot daemon starting... Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=debug msg="app state loaded" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36: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-gae2.spotify.com:80]" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+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]" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+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]" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=info msg="zeroconf server listening on port 44913" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=debug msg="obtained new client token: AAF9O8c2annSW8QxWSzDLtytNPV3IzOs9kM2JW4HqY4d4KbJR4p8MTnDCXtBdsrHRZWtMzu/QvKsfBM1pS92C6oXjnXJj0btbEE2moXKvx/Lism/DqkZgcKGTGtrguWVvafp+5s25ufEAKwvWpuYnz2DS9fVzaJZrxgsWEJ/9R0xK/BZmsD57LJnaG5GCXcNS99Vi/kaXjZq3plAf4nNQb9OFfLQxfQGEEBUu0AMtVpcm6ku6JcS1BgdFg==" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=debug msg="completed challenge" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:47 spla-repro go-librespot[1080]: time="2026-10-02T10:36:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:47 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:47 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:48 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:48 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:50 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 02 10:36:50 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:51 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:51 spla-repro go-librespot[1107]: go-librespot daemon starting... Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=debug msg="app state loaded" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36: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]" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36: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]" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36: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]" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=info msg="zeroconf server listening on port 43843" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=debug msg="obtained new client token: AAGFa4as4zeMPcFPtjzQ1hEIJzZjR1yBYqLoSrHtxVwBG3+nq3mfDoyCgReYgMKLmpgzS2Qj1EqqzWuKrI8Kq09701qbxnSy/Gw3d2uI1IHHFqv0cKPShib8YimeCKac3FXiNasH+OuHAat5+DP/dvc+XVpNLcz8ItaE+CQi0Y+gyzIIkXF9cPoKgLSK2r4K38vrShv1/ylpNxBdr1R2YjiUuNwK+BCxQ6fBLUiMkmFMQFisqdA+YzphWA==" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=debug msg="completed challenge" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:51 spla-repro go-librespot[1108]: time="2026-10-02T10:36:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:51 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:51 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:51 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:51 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:54 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:36:54 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:36:54 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:54 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:54 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 02 10:36:54 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:54 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:54 spla-repro go-librespot[1117]: go-librespot daemon starting... Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=debug msg="app state loaded" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+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]" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+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]" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=info msg="zeroconf server listening on port 39317" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=debug msg="obtained new client token: AAEss5ye0ld/TWlzeT9SP6r3BvEU7xfr1HdSPVCxkUfB+KnC3Ky3a+dfX65DoZkGRtzlE6VTX+cmAFQ+lPkcPrY9/yxseCE/DGVtQhmNkTQ3kZv5IRlfarDWE9MkD7CkGRhL6gD5GaY+RItq4HIdT/+UYeYFFS24jyBlag8ViVq2V96B43kyKSHFEoE+FcVZRmol0SJpPtlUGp2Uy4U7SBT8XYBifPwiVKkXdTZdafHLM9zEz+l4Qpjdgw==" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=debug msg="completed challenge" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:55 spla-repro go-librespot[1118]: time="2026-10-02T10:36:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:55 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:55 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:36:57 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:36:57 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:36:58 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 02 10:36:58 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:59 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:36:59 spla-repro go-librespot[1127]: go-librespot daemon starting... Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=debug msg="app state loaded" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36: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-gew4.spotify.com:80]" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+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]" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+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]" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=info msg="zeroconf server listening on port 33751" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=debug msg="obtained new client token: AAEhMHX+peFNbhldr1Z4bz+aJbb732eJKr6scL+dRxYKg1ZgNsTzLZtJqdTzW7K+XQ9FdvQFRVO3CwqpvnMsaZeVOb6v98vKXwEI5wcGjGFbE84ElhLmELv5UvQ/rp7R+bdaDMVoVV84x8sPNwiZ9pmWvzdDvPESHZs8zxlXMLuwtOuTChn9Cud1zs5IBZcqUrDw5VPzI52uJ3nrBKoQaHbeOxeTSU+7suCenz6jud0/q7dAcDYVFJeBAQ==" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=debug msg="completed keyexchange" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=debug msg="completed challenge" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:36:59 spla-repro go-librespot[1128]: time="2026-10-02T10:36:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:36:59 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:36:59 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:00 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:00 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:02 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 02 10:37:02 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:03 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:03 spla-repro go-librespot[1156]: go-librespot daemon starting... Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=debug msg="app state loaded" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+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]" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+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]" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=info msg="zeroconf server listening on port 44793" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=debug msg="obtained new client token: AAFES323ju88U4taHgMTF6K+3UHf5T0k4vpZoKS0lq23sFpGemGKs1k1XuO4wrPZeOxL9sHMPNyDH308vQLvKv/gAeO5jiM3Kfz0vuCqJ/K4oaj43A96c4ymdDsmM54hjqZ4P8J+fE3vseZppY52zHuX4NLaAT8Pj2W2KAFotcd3k7l1VoH/XyPDarEBNriNGfnVEPqi7UKf+l5I2TkhOp8bpb8e5PaG5Ltnku+dny8+YFqg/PTDwFP7rA==" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37: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" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=debug msg="completed challenge" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:03 spla-repro go-librespot[1157]: time="2026-10-02T10:37:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:03 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:03 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:03 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:03 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:04 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:37:04 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:37:04 spla-repro volumio[493]: info: Listing playlists Oct 02 10:37:04 spla-repro volumio[493]: info: Listing playlists Oct 02 10:37:06 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:06 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:06 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 02 10:37:06 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:06 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:06 spla-repro go-librespot[1169]: go-librespot daemon starting... Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=debug msg="app state loaded" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+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]" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+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]" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+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]" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=info msg="zeroconf server listening on port 34555" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=debug msg="obtained new client token: AAH+9i0rcLcMRqLc1Qub+gA/SuzgiD7uqrukE418R+TMusouZzp8TGCQwmoZmlVAg509wnrb74GI+d5wQWocRKiBEw+/RMjLI6Tj51oCvkLqFsRTJ6sKoVB7/d5Z8RS3GDqflM5DbMfKwX2t/cEBzU4JQ3LJlodDd1hBgPlaBa263PROjL7i6yuripV/t8pBj59Ks/JK0CCHLgly0/yyQsZF26cmcDBkJ/FYMmpkKOjmFmOgjv/NoHcI/g==" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=debug msg="completed challenge" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:07 spla-repro go-librespot[1170]: time="2026-10-02T10:37:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:07 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:07 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:09 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:09 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:10 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 02 10:37:10 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:11 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:11 spla-repro go-librespot[1195]: go-librespot daemon starting... Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=debug msg="app state loaded" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+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]" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+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]" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+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]" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=info msg="zeroconf server listening on port 40425" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=debug msg="obtained new client token: AAHZ6OxTlKPX8h5P4jBKfr1oHru1+QZ/3hTVrIfFJv+/W0R+rRTe+t0P405J6X2ns8gKEajJ9OcfC6q7HfBCgzwbUkRPahRYEhKfcQggubpgJW+1y0hWoFeXVPdpkzgjG9qk1/JDdCxI4eJKf1EIJRjLKUm8sVR2WnaeBHaOQJ6c9RPnNRNln5vapMkjHwNIY82tz9MOl2Hw6M4r+jEmjVsRQXX2VoBYTduCtgv3KTd5+XILAzlufey4Eg==" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=debug msg="completed challenge" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:11 spla-repro go-librespot[1196]: time="2026-10-02T10:37:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:11 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:11 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:12 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:12 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:14 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:37:14 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:37:14 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 02 10:37:14 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:15 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:15 spla-repro go-librespot[1205]: go-librespot daemon starting... Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=debug msg="app state loaded" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+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]" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+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]" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+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]" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=info msg="zeroconf server listening on port 40837" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=debug msg="obtained new client token: AAG+9yxnemscki5VdWIuKaefXaT0XiA9iOQ13TJ4lEzNHdssp5rxQ7KCoeMle8V/SwJ1raZHIhvntqReI28Vq++HTfJLpcCmNKs30v2M9wFjUgwjGIgKsU8HJSb8b19aOEtaaKg3X+eVvatJtc1ZWFZ+Sc5fkoFvhnUfF49xrHp0+wcu6+IsdSunsmL1m8Pft7ozT3FcsOBQevHs3gSGMj4NddWVGZulFyd9oDy3QtUI0fzvILaVKo6ruA==" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=debug msg="completed challenge" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:15 spla-repro go-librespot[1206]: time="2026-10-02T10:37:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:15 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:15 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:15 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:15 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:18 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:18 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:18 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 02 10:37:18 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:18 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:18 spla-repro go-librespot[1216]: go-librespot daemon starting... Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=debug msg="app state loaded" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+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]" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+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]" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+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]" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=info msg="zeroconf server listening on port 39965" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=debug msg="obtained new client token: AAGWU2Xfw00Q2EbxfBDn52GvO+15WiPIEljGDGJSP5SQRzjSqq7k/xJSAelXs6mXQJcB8IlTgJ9ClxXCVPvWYaABq/qOzuEVy0EIwls0lLdFnQwOp48giC3SlAb6g4Jdh0dEsz5ULbA+CGspK9pGisHySrzAlC1UPozqgvNVThL5MilWhClsz67bKcNLnwCmlYXU0yIQYdZ2LYI6AflJcz67CbLC+7yn3vEIhU/Kmz0XhuXulebnQQH6ng==" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=debug msg="completed challenge" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:19 spla-repro go-librespot[1217]: time="2026-10-02T10:37:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:19 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:19 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:21 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:21 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:22 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 02 10:37:22 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:22 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:22 spla-repro go-librespot[1241]: go-librespot daemon starting... Oct 02 10:37:22 spla-repro go-librespot[1242]: time="2026-10-02T10:37:22+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:22 spla-repro go-librespot[1242]: time="2026-10-02T10:37:22+02:00" level=debug msg="app state loaded" Oct 02 10:37:22 spla-repro go-librespot[1242]: time="2026-10-02T10:37:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+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]" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+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]" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+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]" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=info msg="zeroconf server listening on port 44115" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=debug msg="obtained new client token: AAE7nu1VzvF3H8p75jHQU3MNJ5IxHfT+H3C/agfNbiypU47khQZSmZKjB0cUhPy7kGVGjYvddQecuHnzPraHSJrE6l0IHiTyclMEtDzo9auShnRYhmwsp1ve64SzPVmVBUDGd1gA+JSMWJxtZpBJs0RaNrC+NjTLrAxginsVK5kHQ8NHllmockJGXqK79UeTbWQv0r5DGZm2yC7RfXbd1DA0KA457LTQjsedu7J2c+NPYgals5NdNOo=" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=debug msg="completed challenge" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:23 spla-repro go-librespot[1242]: time="2026-10-02T10:37:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:23 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:23 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:24 spla-repro volumio[493]: info: CoreCommandRouter::volumioGetState Oct 02 10:37:24 spla-repro volumio[493]: info: CorePlayQueue::getTrack 0 Oct 02 10:37:24 spla-repro volumio[493]: info: Listing playlists Oct 02 10:37:24 spla-repro volumio[493]: info: Listing playlists Oct 02 10:37:24 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:24 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:26 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Oct 02 10:37:26 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:26 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:26 spla-repro go-librespot[1253]: go-librespot daemon starting... Oct 02 10:37:26 spla-repro go-librespot[1254]: time="2026-10-02T10:37:26+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:26 spla-repro go-librespot[1254]: time="2026-10-02T10:37:26+02:00" level=debug msg="app state loaded" Oct 02 10:37:26 spla-repro go-librespot[1254]: time="2026-10-02T10:37:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+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]" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+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]" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+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]" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=info msg="zeroconf server listening on port 36247" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=debug msg="obtained new client token: AAEPgb6UwjXr8p26OMjvdAUfyORowvS/kIhtkiqgPgNQmySvepFfNns46OcFpZvIC0DK1uVuhPKOjQlonnLURf5dnoQAmbchGSDAOLPYFJNqfiCYVfkPjDipkgsBhNTgr0DUxTZDDwYUfBQ1bZcAFBuzzCudoZSyMqsJ4n/Ajs0ClJLOufnLHBHSvnZ298eUCHGieCSf9vlhLGdEn5+VHEZSeE3lWc8P4WnhWL6vTv5xAsaNkGyYlrw=" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=debug msg="completed challenge" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:27 spla-repro go-librespot[1254]: time="2026-10-02T10:37:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:27 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:27 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:27 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:27 spla-repro volumio[493]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:30 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Oct 02 10:37:30 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:30 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:30 spla-repro go-librespot[1277]: go-librespot daemon starting... Oct 02 10:37:30 spla-repro go-librespot[1278]: time="2026-10-02T10:37:30+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:30 spla-repro go-librespot[1278]: time="2026-10-02T10:37:30+02:00" level=debug msg="app state loaded" Oct 02 10:37:30 spla-repro go-librespot[1278]: time="2026-10-02T10:37:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:30 spla-repro volumio[493]: info: Initializing connection to go-librespot Websocket Oct 02 10:37:30 spla-repro go-librespot[1278]: time="2026-10-02T10:37:30+02:00" level=debug msg="new websocket client" Oct 02 10:37:30 spla-repro volumio[493]: info: Connection to go-librespot Websocket established Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+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]" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+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]" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+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]" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=info msg="zeroconf server listening on port 41973" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=debug msg="obtained new client token: AAGGw4yds8sOrk6FST+s7N6ibYXF2OSF5FkpeZH8+jg/8kBJnvDwNJ4Hi25SeS76yhtcUrSJR1ElTD123H5chSzUFhs7TQu8Bp5TnoiwPasbzPRYzvSEvQhXYptidEOazUArdzKSoSM+HSuWsTIafDOQJ6X79lwxXodA7oOlfClG3uJ25Uv2pzxEI/VCyg6o5FKcaosfgc9SpXFOeYGl2R07xM2GcDdPrzk+fC1BevE1QuN2JHsptuM=" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=debug msg="completed keyexchange" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=debug msg="completed challenge" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 10:37:31 spla-repro go-librespot[1278]: time="2026-10-02T10:37:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 10:37:31 spla-repro volumio[493]: info: Connection to go-librespot Websocket closed Oct 02 10:37:31 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 10:37:31 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 10:37:33 spla-repro volumio[493]: info: Getting Spotify volume Oct 02 10:37:34 spla-repro volumio[493]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 10:37:34 spla-repro volumio[493]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 10:37:34 spla-repro volumio[493]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 02 10:37:34 spla-repro volumio[493]: errno: -111, Oct 02 10:37:34 spla-repro volumio[493]: code: 'ECONNREFUSED', Oct 02 10:37:34 spla-repro volumio[493]: syscall: 'connect', Oct 02 10:37:34 spla-repro volumio[493]: address: '127.0.0.1', Oct 02 10:37:34 spla-repro volumio[493]: port: 9879, Oct 02 10:37:34 spla-repro volumio[493]: response: undefined Oct 02 10:37:34 spla-repro volumio[493]: } Oct 02 10:37:34 spla-repro volumio[493]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 10:37:34 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Oct 02 10:37:34 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:34 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 10:37:34 spla-repro go-librespot[1301]: go-librespot daemon starting... Oct 02 10:37:34 spla-repro go-librespot[1302]: time="2026-10-02T10:37:34+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 10:37:34 spla-repro go-librespot[1302]: time="2026-10-02T10:37:34+02:00" level=debug msg="app state loaded" Oct 02 10:37:34 spla-repro sudo[1311]: volumio : unable to resolve host spla-repro: System error Oct 02 10:37:34 spla-repro go-librespot[1302]: time="2026-10-02T10:37:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 10:37:34 spla-repro sudo[1311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 10:36' Oct 02 10:37:34 spla-repro sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"