Oct 14 20:52:01 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22027. Oct 14 20:52:01 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:01 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:01 volumio-pi go-librespot[22603]: go-librespot daemon starting... Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="app state loaded" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=info msg="zeroconf server listening on port 37087" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="obtained new client token: AAHKEwxYvl6am+zn2lre98pTXgrDpXPmZddUwHW0BR8BGQFHptotwVCU5Jm2QPjDRP+OzH3uRgtz8JtE2PlhumKEaNdDkg9TZpDNBnvnHqwcqyU6XetI6lS0Mmh2UaP59CovOqbRfUYDKEH4IXVYbyvrPqEzehcP5SIe+MfDmgxHYksyqbevxujZwujlTESx9JvQFNSEGiMkyUl0ClhfsexJKkgG6IYElc26zI5oKhH1VZMhvYM0rQAb" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=debug msg="completed challenge" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:01 volumio-pi go-librespot[22604]: time="2026-10-14T20:52:01+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:01 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:01 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:02 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:02 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:05 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22028. Oct 14 20:52:05 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:05 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:05 volumio-pi go-librespot[22625]: go-librespot daemon starting... Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="app state loaded" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=info msg="zeroconf server listening on port 42579" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="obtained new client token: AAHve7RuQHaX/1fUU0S5EVo3B3+FJLkQiTO1UpOj1XQ4lglt4QCgUdtTLZWy+GWa02DAk15QF8tMwKiVZwsP5LpD3CWFTr/4yRYcG6xJRDUW1WO1hGAknB9Q4I7ULNBYpxJS9U5leVWQAfPofGFRH4+NM88/QlVrXIucYCVoQ8U/oC9+oFchFZlw7aZdRda/9RvyoJAb4jMMlUHLd7is/llrVvyqpfgklhHAjZQztuUTqtaqIQoomoav" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:05 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="new websocket client" Oct 14 20:52:05 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket established Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=debug msg="completed challenge" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:05 volumio-pi go-librespot[22626]: time="2026-10-14T20:52:05+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:05 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket closed Oct 14 20:52:05 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:05 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:08 volumio-pi volumio[1466]: info: Getting Spotify volume Oct 14 20:52:08 volumio-pi volumio[1466]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:08 volumio-pi volumio[1466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 14 20:52:08 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetState Oct 14 20:52:08 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:52:08 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:08 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:08 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22029. Oct 14 20:52:08 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:08 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:08 volumio-pi go-librespot[22659]: go-librespot daemon starting... Oct 14 20:52:08 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:08+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:08 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:08 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:08+02:00" level=debug msg="app state loaded" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=info msg="zeroconf server listening on port 37265" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="obtained new client token: AAG++Bt5wGTx0Cugn23/SS85QOo5TvcEVbm3kkHFzOTQXfW+c/6cplcEtj3pjOAzFJFxv8QF87gPTj7jAGB1QPVt7xiVjOpFFwbOtXK0M+39WH4SBz6givRlUo5EuxixErtOxyj5NepTFcZQhQR14KF0yr6lLjbE3HZVJtaqzCNV2kexodoXmUcwSbcsb5/dQlJvolpiVRgyk0ysK4mrcY0ZYoSG1HRlA5UwD+MBasA2iH1VEnKQfA==" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=debug msg="completed challenge" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:09 volumio-pi go-librespot[22660]: time="2026-10-14T20:52:09+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:09 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:09 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:11 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:11 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:12 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22030. Oct 14 20:52:12 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:12 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:12 volumio-pi go-librespot[22677]: go-librespot daemon starting... Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=debug msg="app state loaded" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=info msg="zeroconf server listening on port 44583" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:12 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:12+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:13 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:13+02:00" level=debug msg="obtained new client token: AAHnVIO7y8D6EP73U0KJl4fYbPPYHeM113Tt5s9Ds5OYdyNozGz9pFHbNZv83yVIA/SEjJqt9Sv5jmqBiVLrBcuEkzpte77WMxHS4AAkwGf4WtW3YAUgoh3r+t9+JTgdMIqLqGiCy3U08ZJLMg0ZLOjKN5Dns2GwyNYyOcQV+iKpKFutq25Ekmf154CnF8cjnIDLsuiATU4g/hobAxbJOb54MDf7C1XBAV4Ji6Z5ImNnga+y36q44u8t" Oct 14 20:52:13 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:13 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:13+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:13 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:13+02:00" level=debug msg="completed challenge" Oct 14 20:52:13 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:13+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:13 volumio-pi go-librespot[22678]: time="2026-10-14T20:52:13+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:13 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:13 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:14 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:14 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:16 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22031. Oct 14 20:52:16 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:16 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:16 volumio-pi go-librespot[22699]: go-librespot daemon starting... Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="app state loaded" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=info msg="zeroconf server listening on port 43025" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="obtained new client token: AAGTwz3ibigiqH2LRffVNiRoCA7DC3LcHFfGmACDuTXavU5dZiV/vIH2hytBcIeOkWv+R9j/wP1VBITJUsnbOjTFWimXYFZ11XHkjSomi+CXJTHp49Rv6XMrkRbb/8j86uW2f0l45szoeEngiIaGXzaqWUNYZLtOtU4wOndXR1cqb+skBzWGCedneI12fohi2TwubQ86DKON4pa35swFAC0BD/cujsv/mpnRIAy4LVR/xbZdQ5W8hUdp" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=debug msg="completed challenge" Oct 14 20:52:16 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:16+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:17 volumio-pi go-librespot[22700]: time="2026-10-14T20:52:17+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:17 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:17 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:17 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:17 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:20 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22032. Oct 14 20:52:20 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:20 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:20 volumio-pi go-librespot[22736]: go-librespot daemon starting... Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="app state loaded" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=info msg="zeroconf server listening on port 38357" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="obtained new client token: AAGbc30YK6AMcr2YDKNURRgo/ae8v+oKUgrTuiPSux5ceOJodfWzv5VxsMmU4a8rB78GWZgmT5lQntFL5xFT9GkSCthDYhMnRiSOCrTzLg72HFHBg9vqxBcZ5cPLyVUQNEld5tasMmXQM3pBahUpttavN92IRLQ2fkvISeq4FWAn2QeCfHrnMwKm6K11oOMR8Fu1GCsTn7qpd/DHKXgrUwS4QND0D6mRy1DYp0OJxenwz5dcxCgjD0R7" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="completed challenge" Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:20 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=debug msg="new websocket client" Oct 14 20:52:20 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket established Oct 14 20:52:20 volumio-pi go-librespot[22737]: time="2026-10-14T20:52:20+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:20 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket closed Oct 14 20:52:20 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:20 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:23 volumio-pi volumio[1466]: info: Getting Spotify volume Oct 14 20:52:23 volumio-pi volumio[1466]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:23 volumio-pi volumio[1466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 14 20:52:23 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetState Oct 14 20:52:23 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:52:23 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:23 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:23 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22033. Oct 14 20:52:23 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:23 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:23 volumio-pi go-librespot[22756]: go-librespot daemon starting... Oct 14 20:52:23 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:23+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:23 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:23 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:23+02:00" level=debug msg="app state loaded" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=info msg="zeroconf server listening on port 45225" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="obtained new client token: AAGIGYgOHPa8sPjkPnWr9+ANAzpufLBiHOpUSboq6Qe5Nm8axwWII7usUBJuqvU5TWpySF6Ff4RGbQtlEMyoMypCFOeH5lvYxpH68v/jJmbfIhBsO2m/zfkoEmannzOo9Yh4rgw1w+jqj52p+imgUDksvoswaTDRbSB4g89qXLrjPu6lNortWXcGOmGyICeK8RnEsfoprSXmakHz71PqF1Krb2UNVpb5Imtjupi/8mp71upWODSYnA==" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=debug msg="completed challenge" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:24 volumio-pi go-librespot[22758]: time="2026-10-14T20:52:24+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:24 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:24 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:26 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:26 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:27 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22034. Oct 14 20:52:27 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:27 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:27 volumio-pi go-librespot[22775]: go-librespot daemon starting... Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=debug msg="app state loaded" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=info msg="zeroconf server listening on port 43231" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:27 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:27+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:28 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:28+02:00" level=debug msg="obtained new client token: AAEcdsUO4JoGybgf7vpXipIVW2yDLE04uEFfO/ywkgx6rAJqzaWr3Nm+rC925f7NQKBjs24s+ACCuZuopwLEFpuhRggV5ARE+AEI31QVi051yqo8cW5L48C4g9DiKa+/yc0sYTwBUqJ3ZgNdaox9r3SJF+bqSpkJK5ch/Drn/MX2jBo+jsZ+sBdhOVlPXbYG9fgcsgvOpD+kawVWbj9b1slhQBTkK+KOXuzXIPEJD4to/9u4YHcTuQ==" Oct 14 20:52:28 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:28 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:28+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:28 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:28+02:00" level=debug msg="completed challenge" Oct 14 20:52:28 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:28+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:28 volumio-pi go-librespot[22776]: time="2026-10-14T20:52:28+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:28 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:28 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:29 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:29 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:31 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22035. Oct 14 20:52:31 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:31 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:31 volumio-pi go-librespot[22810]: go-librespot daemon starting... Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=debug msg="app state loaded" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=info msg="zeroconf server listening on port 40177" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:31 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:31+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:32 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:32+02:00" level=debug msg="obtained new client token: AAFEP9U6WZcc7CE0SpCkg36SXwAfDp4nxyTm67Yy7gdNhTNDtlbqWhhvXG9t2qjrKF3xhru6SFhDQ+xff1gi3AEe0dP3Fkw/7wIdY/gKYGIWuR76H2fw8vw8BfFBu8UE7YzmoMEalYg/JV/aCe/gLeRF5orB7eUbUXqJi4UtnspSNbMrGG8uj12vC6kFwqslrRNRceONuxErq+aeQ1eplSM1u6MRwpro/g73g0GLTUnXKQpQU7vMrBAQ" Oct 14 20:52:32 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:32 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:32+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:32 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:32+02:00" level=debug msg="completed challenge" Oct 14 20:52:32 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:32+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:32 volumio-pi go-librespot[22811]: time="2026-10-14T20:52:32+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:32 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:32 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:32 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:32 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:35 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22036. Oct 14 20:52:35 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:35 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:35 volumio-pi go-librespot[22833]: go-librespot daemon starting... Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="app state loaded" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=info msg="zeroconf server listening on port 36497" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="obtained new client token: AAE3x6yNTzfc+Haezrm/moVQJAT02xkv3IwyXpH5kdi7nqp4AimKkw+2U2HL8rg1pwV/6Os0nFCxSnKAnWOUKhU0OAzx95P0uEx30XzwMHrDlbNsNsNpJJuDSrJLOtXT1+nmU6QPiW+KOIbLf6xciqSClv4XOkOvu5rgDNy68JaX1oH1oS7VznVmTPhdcVCqVO6Zj7hh5htTAmtt+1kks03QZ+lfPJgAiZMQYsj68UYbmRg63AYW9UXV" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:35 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="new websocket client" Oct 14 20:52:35 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket established Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=debug msg="completed challenge" Oct 14 20:52:35 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:35+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:36 volumio-pi go-librespot[22834]: time="2026-10-14T20:52:36+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:36 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket closed Oct 14 20:52:36 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:36 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:38 volumio-pi volumio[1466]: info: Getting Spotify volume Oct 14 20:52:38 volumio-pi volumio[1466]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:38 volumio-pi volumio[1466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 14 20:52:38 volumio-pi volumio[1466]: verbose: New Socket.io Connection to 10.0.0.14 from 10.0.0.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 14 20:52:38 volumio-pi volumio[1466]: verbose: New Socket.io Connection to 10.0.0.14 from 10.0.0.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 14 20:52:38 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetState Oct 14 20:52:38 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:52:38 volumio-pi volumio[1466]: verbose: New Socket.io Connection to 10.0.0.14 from 10.0.0.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 14 20:52:39 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:39 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:39 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22037. Oct 14 20:52:39 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:39 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:39 volumio-pi go-librespot[22867]: go-librespot daemon starting... Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="app state loaded" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=info msg="zeroconf server listening on port 38791" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="obtained new client token: AAEd3ORHegeexy+WuHX7BtTWOK0TA2V93b45HPxjyX3nItrtM0+rEaBxreo/bH8roltQEMOHI5eqXCbILKwo3uWWo6ngOvoFggo7ZDf/a1TRJAGZx866jSNi+gwf1V29TaTLwcHa3hnWhGLDm+Xz/a2oAqH5XlO3qXWjHf4Z+v9EtYxNEAIyL2+hfvFdx4NyGarsVKV8vLFAK9mvgudkwkQh+J/auG9gN1bdE1iHdEFeOhtkS6ju0a2w" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=debug msg="completed challenge" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:39 volumio-pi go-librespot[22868]: time="2026-10-14T20:52:39+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:39 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:39 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:41 volumio-pi volumio[1466]: verbose: New Socket.io Connection to 10.0.0.14 from 10.0.0.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 14 20:52:41 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetState Oct 14 20:52:41 volumio-pi volumio[1466]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 20:52:41 volumio-pi volumio[1466]: info: Listing playlists Oct 14 20:52:41 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 20:52:41 volumio-pi volumio[1466]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 20:52:41 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetQueue Oct 14 20:52:41 volumio-pi volumio[1466]: info: CoreStateMachine::getQueue Oct 14 20:52:41 volumio-pi volumio[1466]: info: CorePlayQueue::getQueue Oct 14 20:52:42 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:42 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:42 volumio-pi volumio[1466]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Oct 14 20:52:42 volumio-pi volumio[1466]: info: [jellyfin-browse] browseUri: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5 Oct 14 20:52:42 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d79964cfec7849fabe0b313b6d9aa071","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Oct 14 20:52:42 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.21:8096/UserViews?userId=d79964cfec7849fabe0b313b6d9aa071 Oct 14 20:52:42 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d79964cfec7849fabe0b313b6d9aa071","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":100,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Oct 14 20:52:42 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22038. Oct 14 20:52:42 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:42 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:42 volumio-pi go-librespot[22887]: go-librespot daemon starting... Oct 14 20:52:42 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:42+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:42 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:42 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:42+02:00" level=debug msg="app state loaded" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=info msg="zeroconf server listening on port 39399" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="obtained new client token: AAGMQJneNM2EDsauUx6e3dKiQLbnDjdOD2/biwmKnOMRhmIg7IjvxLSsbdLOMEpBXNjxELbxpA301By2c3cesOQ89ldwzxmNKt/2tVyPVIy4xu2JambwMvmGy9MG3SVn0BBgWNqEZ5m1Sv/I7ZCkcq5263UaA13U4o7p8A4QLZJmciEka2gzz39JhcyUTcBk8tRoNAkbyQplLwEd833EoCJLYHYfKXiKZpGZOgSwFRwjIW1+S/wOVQ==" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=debug msg="completed challenge" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:43 volumio-pi go-librespot[22888]: time="2026-10-14T20:52:43+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:43 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:43 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:44 volumio-pi volumio[1466]: error: [now-playing] API endpoint unsplash/getRandomPhoto returned error: SyntaxError: Unexpected token '<', ") Oct 14 20:52:44 volumio-pi volumio[1466]: at parseJSONFromBytes (node:internal/deps/undici/undici:6662:19) Oct 14 20:52:44 volumio-pi volumio[1466]: at successSteps (node:internal/deps/undici/undici:6636:27) Oct 14 20:52:44 volumio-pi volumio[1466]: at node:internal/deps/undici/undici:1236:60 Oct 14 20:52:44 volumio-pi volumio[1466]: at node:internal/process/task_queues:140:7 Oct 14 20:52:44 volumio-pi volumio[1466]: at AsyncResource.runInAsyncScope (node:async_hooks:206:9) Oct 14 20:52:44 volumio-pi volumio[1466]: at AsyncResource.runMicrotask (node:internal/process/task_queues:137:8) Oct 14 20:52:44 volumio-pi volumio[1466]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 14 20:52:45 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:45 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:46 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22039. Oct 14 20:52:46 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:46 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:46 volumio-pi go-librespot[22908]: go-librespot daemon starting... Oct 14 20:52:46 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:46+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:46 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:46 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:46+02:00" level=debug msg="app state loaded" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=info msg="zeroconf server listening on port 44171" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="obtained new client token: AAG7w/TyyjEylRYcphna6WrwzIeeMHWW7g7P2rwHIk5h/VkR+AiGf7Og0Cu0vkrkXev4TQgmHuqs6LEKUEVmTVbZLOulPHcZWYqPqKg9AzGaMAl/INKVRAKqG9PusbvYhw1PmFQY5yOAKTLLj0V5SWBJOev1dkxhtjfu0RG7msz4Dfo+1m86euZT0/qyDW1k8x8USKqwV0fyc0E2XQCjkXapQEUahq8yWwVm130mD1rt5SJ/43ZeYg==" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=debug msg="completed challenge" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:47 volumio-pi go-librespot[22911]: time="2026-10-14T20:52:47+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:47 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:47 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:48 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:48 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:50 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.21:8096/Items?userId=d79964cfec7849fabe0b313b6d9aa071&startIndex=0&limit=100&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Oct 14 20:52:50 volumio-pi volumio[1466]: info: Preload queue cleared Oct 14 20:52:50 volumio-pi volumio[1466]: info: Preload queue cleared Oct 14 20:52:50 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22040. Oct 14 20:52:50 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:50 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:50 volumio-pi go-librespot[22942]: go-librespot daemon starting... Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=debug msg="app state loaded" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=info msg="zeroconf server listening on port 35637" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:50 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:50+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=debug msg="obtained new client token: AAFy/mEkbk8jnxdTxIqAM2IHf6bgCAfl3So29oKvlz0i14IEHbPjm+fmJszJl/RD0Rk7XTZbKfkL4/mXQZO3Y51rQkbpipju7j4FJUF0RutdX/Lku6T+I4zUic3B25WAusNFH+meJpUjBAUs5KiqCPrqMNRscQk6bjiwSeXkfBfv/yyCkS3gWFh3K+/GfmS4vkWsvDJvoIiAITcmZAYB4mwk6/m91eYmwM/HDWrORn1hdcX7itJ/FjnM" Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:51 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=debug msg="new websocket client" Oct 14 20:52:51 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket established Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=debug msg="completed challenge" Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:51 volumio-pi go-librespot[22943]: time="2026-10-14T20:52:51+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:51 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket closed Oct 14 20:52:51 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:51 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:54 volumio-pi volumio[1466]: info: Getting Spotify volume Oct 14 20:52:54 volumio-pi volumio[1466]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:54 volumio-pi volumio[1466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 14 20:52:54 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetState Oct 14 20:52:54 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:52:54 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:54 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:54 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22041. Oct 14 20:52:54 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:54 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:54 volumio-pi go-librespot[22963]: go-librespot daemon starting... Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="app state loaded" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=info msg="zeroconf server listening on port 40275" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="obtained new client token: AAEub8CHPotnEvEDXLVs2vArQ98uOJdWA9dJ1fNWk+6k+d32Je8IK19sOBToZAQmaTvsyH50zpsq4+Y9YugyF0C49Zx4+UI4DJJjJY4wAOHps49P8d4t6j8XJFQ3QRFqGk2tocA+fGXp1gpVSaB32rvt3ImiBGSUvaR2+MHa86Py2Tg3iLoJKPEazvHUA2pYI1bIKg2RzvISk+5QJF/DnL5FY2Mgv50SxGly+CabLyXOMjlZB5EGEbin" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=debug msg="completed challenge" Oct 14 20:52:54 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:54+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:55 volumio-pi go-librespot[22964]: time="2026-10-14T20:52:55+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:55 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:55 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:56 volumio-pi volumio[1466]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Oct 14 20:52:56 volumio-pi volumio[1466]: info: [jellyfin-browse] browseUri: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69 Oct 14 20:52:56 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d79964cfec7849fabe0b313b6d9aa071","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"c38bfeec87dbc401616cebeaf47c7b69","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Oct 14 20:52:57 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:52:57 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:52:58 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22042. Oct 14 20:52:58 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:58 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:52:58 volumio-pi go-librespot[22984]: go-librespot daemon starting... Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="app state loaded" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=info msg="zeroconf server listening on port 33415" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="obtained new client token: AAFPGp4LDVyPiU9IBzt5oGC+1d5qQr+59ODKNff0xBkwY/+ub8Nx3Xte4o5lWDTb12PAcaqZ7iVkTi/yu8ScLI8PuJ4e7Rb5ZDpWLfTnyUOve1KbPGUETsVpurb3KO3SVugpao/riIanOteCoQYqKXzuMbEy6wrrnnoBchL+oKGrwA7/YKfKGGrJ/RfHOjyvusHkSggfMrRgkzefsuMTIPPB1FK2iKUZsTW8BPUbFT3o6eYcwKsDby9k" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="completed keyexchange" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=debug msg="completed challenge" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:52:58 volumio-pi go-librespot[22985]: time="2026-10-14T20:52:58+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:52:58 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:52:58 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:52:59 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.21:8096/Items?userId=d79964cfec7849fabe0b313b6d9aa071&startIndex=0&recursive=true&sortOrder=Ascending&parentId=c38bfeec87dbc401616cebeaf47c7b69&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Oct 14 20:53:00 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:53:00 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:53:02 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22043. Oct 14 20:53:02 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:53:02 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:53:02 volumio-pi go-librespot[23020]: go-librespot daemon starting... Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="app state loaded" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=info msg="zeroconf server listening on port 36485" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="obtained new client token: AAEQlnvvqTruvrOKnybm/3K+g2y/QcyzjMkq6x8NGt3TvYjyn1n6D+MQDOH0yHs+XY+DgQWQ379D1VWAP86MKSngNeSma+p5yF+LDTEAuei0LAhVld0obK+l6XB9s9P3Xmu04H+rA9Xr5LAb+0ly76JqZPkS92pziTCnBDhCd3liRkrttBisxXDcynsq+8smHsmYBLXwayEq8pgl6Iy2ayH7flUm+8u5tD63LSBwnCIbt5iPKYhnvSZl" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="completed keyexchange" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=debug msg="completed challenge" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:53:02 volumio-pi go-librespot[23021]: time="2026-10-14T20:53:02+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:53:02 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:53:02 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:53:03 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:53:03 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:53:03 volumio-pi nmbd[1332]: [2026/10/14 20:53:03.947605, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 14 20:53:03 volumio-pi nmbd[1332]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.14 for name WORKGROUP<1d>. Oct 14 20:53:03 volumio-pi nmbd[1332]: This response was from IP 10.0.0.22, reporting an IP address of 10.0.0.22. Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preload queue cleared Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=da403eb64273a3db6f5d32fdfe2d0d59 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=ea9bdd30f82ea6ec95b0ad595ecfd5da Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=9d046b00afe12935d2f1e3f0a7d24f03 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=5760256ace469bbac76331f52e4b12f7 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=008674725c069cdbd760205e9a380c4d Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=58bf238fe0d4b445cdbd6143475a1f6f Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=437ce1ac5853da52be559ee2e59982f0 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=1b082d0f7ec5ab313b08605af49c2619 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=802be068b2ce6e26407d945f92a3aa7f Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=c9a4302d0fa04ca325926d0b16f00332 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=713b1aede4ba6a01eb3e5ad27cdcdc05 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=40355735a4157c2c083fb1dfdfc34651 Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preloading song: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69/song@songId=6e0b8491a590ae8fe1a4c7df106ee43e Oct 14 20:53:04 volumio-pi volumio[1466]: info: Preload queue cleared Oct 14 20:53:05 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22044. Oct 14 20:53:05 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:53:05 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:53:05 volumio-pi go-librespot[23041]: go-librespot daemon starting... Oct 14 20:53:05 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:05+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:53:05 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:53:05 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:05+02:00" level=debug msg="app state loaded" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=info msg="zeroconf server listening on port 39341" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="obtained new client token: AAFcI0XkIYEaOqT7K5XdDlhrSburWXtauzflEJHiBB+qFIybS8AzY3JGMLORVzc3JaoS5gwfUzTG1uUW1WJ0qqTB9p6Cu3qwKkKsjpPoRaRl/nhttYMZKEPaup3YjvLqTx1tuiqwx8nqOtVYDnNua1UZga1Fuyw9SEG2V7VKXq4L2DD+jGiiKuFLIa2DoQmhyoav3AcWnIrYQ0Gk0d8RpQ+uHZvxgicA6leDJoR0Pvb3oSeYBSF2mQ==" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 20:53:06 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="new websocket client" Oct 14 20:53:06 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket established Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="completed keyexchange" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=debug msg="completed challenge" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=info msg="authenticated AP" username="il***er" Oct 14 20:53:06 volumio-pi go-librespot[23042]: time="2026-10-14T20:53:06+02:00" level=fatal msg="daemon exited with error" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 20:53:06 volumio-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 20:53:06 volumio-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 20:53:06 volumio-pi volumio[1466]: info: Connection to go-librespot Websocket closed Oct 14 20:53:06 volumio-pi volumio[1466]: info: Preload queue cleared Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::ClearQueue Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::stPlaybackTimer Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::updateTrackBlock Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrackBlock Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::serviceStop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::serviceStop Oct 14 20:53:06 volumio-pi volumio[1466]: info: [1792003986617] ControllerWebradio::stop Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::clearPlayQueue Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::saveQueue Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushQueue Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::addQueueItems Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::addQueueItems Oct 14 20:53:06 volumio-pi volumio[1466]: info: Preload queue cleared Oct 14 20:53:06 volumio-pi volumio[1466]: info: Adding Item to queue: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69 Oct 14 20:53:06 volumio-pi volumio[1466]: info: Exploding uri jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69 in service jellyfin Oct 14 20:53:06 volumio-pi volumio[1466]: info: [jellyfin-browse] explodeUri: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/songs@albumId=c38bfeec87dbc401616cebeaf47c7b69 Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"d79964cfec7849fabe0b313b6d9aa071","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"c38bfeec87dbc401616cebeaf47c7b69","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: info: Oct 14 20:53:06 volumio-pi volumio[1466]: ---------------------------- MPD announces state update: player Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand stop took 19 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::getState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand status Oct 14 20:53:06 volumio-pi volumio[1466]: info: Oct 14 20:53:06 volumio-pi volumio[1466]: ---------------------------- MPD announces state update: player Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::getState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand status Oct 14 20:53:06 volumio-pi volumio[1466]: info: Oct 14 20:53:06 volumio-pi volumio[1466]: ---------------------------- MPD announces state update: player Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::getState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand status Oct 14 20:53:06 volumio-pi volumio[1466]: info: Oct 14 20:53:06 volumio-pi volumio[1466]: ---------------------------- MPD announces state update: player Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::getState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand status Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand status took 4 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand status took 4 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand status took 3 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand status took 3 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseState Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseTrackInfo Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseTrackInfo Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseTrackInfo Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: ControllerMpd::parseTrackInfo Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::servicePushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Martin Sturtzer - Galactic Travellers","artist":"Deep Space One: Deep ambient electronic and space music. [SomaFM]","album":null,"uri":"http://ice2.somafm.com/deepspaceone-128-mp3","trackType":""} Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: CURRENT POSITION 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState stateService stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState currentStatus stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: No code Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::servicePushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Martin Sturtzer - Galactic Travellers","artist":"Deep Space One: Deep ambient electronic and space music. [SomaFM]","album":null,"uri":"http://ice2.somafm.com/deepspaceone-128-mp3","trackType":""} Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: CURRENT POSITION 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState stateService stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState currentStatus stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: No code Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::servicePushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Martin Sturtzer - Galactic Travellers","artist":"Deep Space One: Deep ambient electronic and space music. [SomaFM]","album":null,"uri":"http://ice2.somafm.com/deepspaceone-128-mp3","trackType":""} Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: CURRENT POSITION 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState stateService stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState currentStatus stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: No code Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: ControllerMpd::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::servicePushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Martin Sturtzer - Galactic Travellers","artist":"Deep Space One: Deep ambient electronic and space music. [SomaFM]","album":null,"uri":"http://ice2.somafm.com/deepspaceone-128-mp3","trackType":""} Oct 14 20:53:06 volumio-pi volumio[1466]: verbose: CURRENT POSITION 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState stateService stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::syncState currentStatus stop Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: No code Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreStateMachine::pushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:06 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushState Oct 14 20:53:06 volumio-pi volumio[1466]: info: ------------------------------ 26ms Oct 14 20:53:06 volumio-pi volumio[1466]: info: ------------------------------ 25ms Oct 14 20:53:06 volumio-pi volumio[1466]: info: ------------------------------ 25ms Oct 14 20:53:06 volumio-pi volumio[1466]: info: ------------------------------ 25ms Oct 14 20:53:06 volumio-pi volumio[1466]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Volumio is not playing Oct 14 20:53:06 volumio-pi volumio[1466]: info: FusionDsp - Clipped samples monitor stopped Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:06 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:07 volumio-pi volumio[1466]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 14 20:53:09 volumio-pi volumio[1466]: info: Getting Spotify volume Oct 14 20:53:09 volumio-pi volumio[1466]: error: Failed to get Spotify volume from local API: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:53:09 volumio-pi volumio[1466]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 14 20:53:09 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioGetState Oct 14 20:53:09 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:09 volumio-pi volumio[1466]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 14 20:53:09 volumio-pi volumio[1466]: info: Initializing connection to go-librespot Websocket Oct 14 20:53:09 volumio-pi volumio[1466]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 20:53:09 volumio-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22045. Oct 14 20:53:09 volumio-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:53:09 volumio-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 20:53:09 volumio-pi go-librespot[23077]: go-librespot daemon starting... Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=info msg="running go-librespot 0.9.1" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=debug msg="app state loaded" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=info msg="zeroconf server listening on port 38773" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 14 20:53:09 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:09+02:00" level=info msg="loading previously persisted zeroconf credentials" username="il***er" Oct 14 20:53:10 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:10+02:00" level=debug msg="obtained new client token: AAGDuWIAL5MW8AFRq+CskxaFCfFtR+kot6k4k4mHu7zrBQnyF+jzKFs+DV2taUPyC+lUqFZnJAcsDiFPIj5QeErSaEzjCqyGyVI4RCHCou/4aaMX+OqA/E9xVV+34j8WPGfFUbgag/5gTO2qGdbly4UIa7CCzBOVNLWgPn+cCpBtO1jd3DtgOFga7kD5m+vGUd3CiBjLjyTsDDy9giMiMuXUimF/GpG8ZYjz7aHsoCg4e6T1MTF5qwLF" Oct 14 20:53:10 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:10+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 14 20:53:10 volumio-pi go-librespot[23078]: time="2026-10-14T20:53:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 14 20:53:10 volumio-pi volumio[1466]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.21:8096/Items?userId=d79964cfec7849fabe0b313b6d9aa071&startIndex=0&recursive=true&sortOrder=Ascending&parentId=c38bfeec87dbc401616cebeaf47c7b69&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPushQueue Oct 14 20:53:10 volumio-pi volumio[1466]: info: CorePlayQueue::saveQueue Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::updateTrackBlock Oct 14 20:53:10 volumio-pi volumio[1466]: info: CorePlayQueue::getTrackBlock Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreCommandRouter::volumioPlay Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::play index 0 Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::stop Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::play index undefined Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:53:10 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:10 volumio-pi volumio[1466]: info: CoreStateMachine::startPlaybackTimer Oct 14 20:53:10 volumio-pi volumio[1466]: info: CorePlayQueue::getTrack 0 Oct 14 20:53:10 volumio-pi volumio[1466]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Nebboteket@bc92790a996a4305a3266243feb53cb5/song@songId=da403eb64273a3db6f5d32fdfe2d0d59 Oct 14 20:53:10 volumio-pi volumio[1466]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 20:53:10 volumio-pi volumio[1466]: Error: No media streams found for song 01 1-2-3 Oct 14 20:53:10 volumio-pi volumio[1466]: at PlayController._PlayController_getStreamUrl (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:241:15) Oct 14 20:53:10 volumio-pi volumio[1466]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:78:234) Oct 14 20:53:10 volumio-pi volumio[1466]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 14 20:53:10 volumio-pi volumio[1466]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 20:53:10 volumio-pi sudo[23103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 20:52' Oct 14 20:53:10 volumio-pi sudo[23103]: 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"