Oct 02 12:28:00 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 02 12:28:00 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:00 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:00 spla-repro go-librespot[1470]: go-librespot daemon starting... Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=debug msg="app state loaded" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=info msg="zeroconf server listening on port 43657" Oct 02 12:28:00 spla-repro go-librespot[1471]: time="2026-10-02T12:28:00+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:01 spla-repro go-librespot[1471]: time="2026-10-02T12:28:01+02:00" level=debug msg="obtained new client token: AAGYGK7aiHkrOLKj6nmF8EY6mApZ4pvCMmv6rj3vw7vzRK5MKFCBSQ1s6vbyZHsXD8a7AP0+2VlyhCstcY5lx6VMo51tuVSRJ8eHZIeaz0UQgmNr9Er9zrHZYkJ8xVbIMyWNxtVQsLicfjAGEN6u1l6/9BZ101y2uD5EDsoH15hl70W14qyXh7pct0/ANCfdh3V6uvEGMTWAhET/hOx29UnJ04sTf5wes8VtckaEDWOpcCoMzmqp1AU=" Oct 02 12:28:01 spla-repro go-librespot[1471]: time="2026-10-02T12:28:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:01 spla-repro go-librespot[1471]: time="2026-10-02T12:28:01+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:01 spla-repro go-librespot[1471]: time="2026-10-02T12:28:01+02:00" level=debug msg="completed challenge" Oct 02 12:28:01 spla-repro volumio[1122]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 12:28:01 spla-repro volumio[1122]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 12:28:01 spla-repro go-librespot[1471]: time="2026-10-02T12:28:01+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:01 spla-repro volumio[1122]: info: Streaming services startup Oct 02 12:28:01 spla-repro volumio[1122]: info: Starting Streaming Daemon Oct 02 12:28:01 spla-repro sudo[1482]: volumio : unable to resolve host spla-repro: System error Oct 02 12:28:01 spla-repro volumio[1122]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 12:28:01 spla-repro sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 12:28:01 spla-repro sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 12:28:01 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:01 spla-repro sudo[1482]: pam_unix(sudo:session): session closed for user root Oct 02 12:28:01 spla-repro go-librespot[1471]: time="2026-10-02T12:28:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:01 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:01 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:01 spla-repro volumio[1122]: error: Cannot start Volumio Streaming Daemon Oct 02 12:28:01 spla-repro volumio[1122]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 12:28:01 spla-repro volumio[1122]: sudo: unable to resolve host spla-repro: System error Oct 02 12:28:01 spla-repro volumio[1122]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 12:28:01 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:02 spla-repro volumio[1122]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 12:28:02 spla-repro volumio[1122]: info: MyVolumio token set successfully Oct 02 12:28:02 spla-repro volumio[1122]: info: MYVOLUMIO: Adding device Oct 02 12:28:02 spla-repro volumio[1122]: info: MYVOLUMIO: Evaluating Server Oct 02 12:28:02 spla-repro volumio[1122]: info: MyVolumio status changed Oct 02 12:28:02 spla-repro volumio[1122]: info: Streaming services startup Oct 02 12:28:02 spla-repro volumio[1122]: info: Starting Streaming Daemon Oct 02 12:28:02 spla-repro volumio[1122]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 12:28:02 spla-repro volumio[1122]: info: Removing audio output: Oct 02 12:28:02 spla-repro volumio[1122]: info: Stoppping Tunnel 1 Oct 02 12:28:02 spla-repro sudo[1512]: volumio : unable to resolve host spla-repro: System error Oct 02 12:28:02 spla-repro sudo[1512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 12:28:02 spla-repro sudo[1512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 12:28:03 spla-repro sudo[1512]: pam_unix(sudo:session): session closed for user root Oct 02 12:28:03 spla-repro sudo[1517]: volumio : unable to resolve host spla-repro: System error Oct 02 12:28:03 spla-repro sudo[1517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 02 12:28:03 spla-repro volumio[1122]: error: Cannot start Volumio Streaming Daemon Oct 02 12:28:03 spla-repro volumio[1122]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 12:28:03 spla-repro volumio[1122]: sudo: unable to resolve host spla-repro: System error Oct 02 12:28:03 spla-repro volumio[1122]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 12:28:03 spla-repro sudo[1517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 12:28:03 spla-repro sudo[1517]: pam_unix(sudo:session): session closed for user root Oct 02 12:28:03 spla-repro volumio[1122]: info: Remote SSH Stopped Oct 02 12:28:03 spla-repro volumio[1122]: info: Setting Geolocation for MyVolumio to eu6 Oct 02 12:28:03 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:03 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:03 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:03 spla-repro volumio[1122]: info: Successfully Added MyVolumio device Oct 02 12:28:04 spla-repro volumio[1122]: info: Updating MyVolumio device info Oct 02 12:28:04 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:04 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:04 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:04 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:04 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:04 spla-repro volumio[1122]: info: Successfully Updated MyVolumio device Oct 02 12:28:04 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 02 12:28:04 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:04 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:04 spla-repro go-librespot[1521]: go-librespot daemon starting... Oct 02 12:28:04 spla-repro go-librespot[1522]: time="2026-10-02T12:28:04+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:04 spla-repro go-librespot[1522]: time="2026-10-02T12:28:04+02:00" level=debug msg="app state loaded" Oct 02 12:28:04 spla-repro go-librespot[1522]: time="2026-10-02T12:28:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=info msg="zeroconf server listening on port 35243" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="obtained new client token: AAHSpfQuyt1w85FcRJQpcIbPdHXP6SOa3ZHqJi7OzN/l9sRaw29mqwHdKbMVn7VN7HkJLr+EDpTjDQ9IVjbdfLfE97PNVspgxDbMlTAG8fAw5y7ghX3Yl0tRv1zVi8QVmJpY2/DqTkyD5Mb5Yr5Aj9O5xk0hHv0ku3Vu+J7lGuRWbNvl6r23fQlHMM2DNwuIng5Xpozps7LnjmU9LDDOxTTiKQFkkCFv5lLjmbNo3tBoLQtlpckevbH9pA==" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=debug msg="completed challenge" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:05 spla-repro go-librespot[1522]: time="2026-10-02T12:28:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:05 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:05 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:06 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:06 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:07 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:07 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:08 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 02 12:28:08 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:08 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:08 spla-repro go-librespot[1548]: go-librespot daemon starting... Oct 02 12:28:08 spla-repro go-librespot[1549]: time="2026-10-02T12:28:08+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:08 spla-repro go-librespot[1549]: time="2026-10-02T12:28:08+02:00" level=debug msg="app state loaded" Oct 02 12:28:08 spla-repro go-librespot[1549]: time="2026-10-02T12:28:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=info msg="zeroconf server listening on port 37023" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="obtained new client token: AAGVF6oa1Y9E5saGRR7HJPdtLOXM7+LfWcPFwMo46Hub+EQ965nnPNTvSjLewsfTA9kd7BodpRDIC2wax8qhIEehDvi5bXazDekZqsofGYuTvMzOqrmrq5QikS9f6PXJIUmhzi5QgQ48uQXZeiV36rMNiCsDdMGIa4Ud5SpY+XBKk3pKIo6W6LArvR4SVq/iKdWliFT5UHn3GXvsHfcjM3tUZMRvwTBx05JOSoYUrZ63yatGbkNGDqk=" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=debug msg="completed challenge" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:09 spla-repro go-librespot[1549]: time="2026-10-02T12:28:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:09 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:09 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:10 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:10 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:12 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 02 12:28:12 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:12 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:12 spla-repro go-librespot[1559]: go-librespot daemon starting... Oct 02 12:28:12 spla-repro go-librespot[1560]: time="2026-10-02T12:28:12+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:12 spla-repro go-librespot[1560]: time="2026-10-02T12:28:12+02:00" level=debug msg="app state loaded" Oct 02 12:28:12 spla-repro go-librespot[1560]: time="2026-10-02T12:28:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=info msg="zeroconf server listening on port 42393" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="obtained new client token: AAErYwNxRglnjM/YqD5Bu1duTURi46y0lJXYBnOQchF8rnSnvkTkq0yRxlqaEnWBXgtRutX2Pbtd7l23nyBMvdJml14gb4yyz9AWBA+qoviODOdvQ5pBSFVFsKXkgzESwKmftjH4jzSwVnOZ0ZHLG0cboGWmxJhFugLBmPMGkGnelLvIEV9WAV3GVAC3b4DO8NolPe/J5Ly6ReJ7MmBvU4BwOpdQrfw988Czr+KJ13oDsY8EzklbOVc=" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=debug msg="completed challenge" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:13 spla-repro go-librespot[1560]: time="2026-10-02T12:28:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:13 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:13 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:13 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:13 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 12:28:14 spla-repro volumio-remote-updater[719]: Test mode disabled Oct 02 12:28:14 spla-repro volumio-remote-updater[719]: Alpha mode disabled Oct 02 12:28:14 spla-repro volumio-remote-updater[719]: Alpha legacy test mode disabled Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 02 12:28:14 spla-repro volumio[1122]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 12:28:14 spla-repro volumio[1122]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 58 Oct 02 12:28:14 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:14 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:16 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:16 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:16 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 02 12:28:16 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:16 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:16 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:16 spla-repro go-librespot[1587]: go-librespot daemon starting... Oct 02 12:28:16 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:16 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:28:16 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:28:16 spla-repro go-librespot[1588]: time="2026-10-02T12:28:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:16 spla-repro go-librespot[1588]: time="2026-10-02T12:28:16+02:00" level=debug msg="app state loaded" Oct 02 12:28:16 spla-repro go-librespot[1588]: time="2026-10-02T12:28:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=info msg="zeroconf server listening on port 38027" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="obtained new client token: AAFvHOWMM3Dik1nmSJhGUvbpflnLGiLXLMkqJJM7o3/th2ZxdA4Mko5MUiGzMD8Y/mHCMFL0pOj0EWOlOFskfrKZtvw4usWTg4HDSHRejD4byVZvdCJw6ReqN7pCU46gotf4xXnoOJ2ypcGL7n+EYW0dqiWaqb88ibxbDvuIMCAox1hsMNXdz5iJykXELRzwB2hQyiaaOCCwdSZ73BR3FWyEeRubaAowUm9aosjLK0gGRlKvFPBtWI4=" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=debug msg="completed challenge" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:17 spla-repro go-librespot[1588]: time="2026-10-02T12:28:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:17 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:17 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:19 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:19 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 12:28:19 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 12:28:20 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 02 12:28:20 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:20 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:20 spla-repro go-librespot[1599]: go-librespot daemon starting... Oct 02 12:28:20 spla-repro go-librespot[1600]: time="2026-10-02T12:28:20+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:20 spla-repro go-librespot[1600]: time="2026-10-02T12:28:20+02:00" level=debug msg="app state loaded" Oct 02 12:28:20 spla-repro go-librespot[1600]: time="2026-10-02T12:28:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=info msg="zeroconf server listening on port 41133" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="obtained new client token: AAFGqsd88NOlE/N5t6he2t8k5byx6xHneFD9NAnsAjJyJ8GDN5n/tVEP7yt99UDchVg6NewJhZcydMIrXh81O4LBLgbDVkYf0MtFm1S1lA5PxnB655TMOd1wbaJVrhNvjRyUcjhyw+Etavru8Oe+7YKz8WM3DO0OBeIO7uREpQUgwszXZF/ynJVL6QAss43BMcsiwAeo4dXpwk6r+knWkaqpzjioX/gXqcZImuOHqWP1AwxrxKhiCnE=" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:21 spla-repro volumio[1122]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 02 12:28:21 spla-repro volumio[1122]: info: Completed starting MyVolumio Plugin Oct 02 12:28:21 spla-repro volumio[1122]: [Metrics] CommandRouter: 49s 976.77ms Oct 02 12:28:21 spla-repro volumio[1122]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 12:28:21 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 12:28:21 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 12:28:21 spla-repro volumio[1122]: info: CoreCommandRouter::Close All Modals sent Oct 02 12:28:21 spla-repro volumio[1122]: info: CoreCommandRouter::Close All Modals sent Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=debug msg="completed challenge" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:21 spla-repro go-librespot[1600]: time="2026-10-02T12:28:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:21 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:21 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:22 spla-repro dhcpcd[808]: eth0: failed to renew DHCP, rebinding Oct 02 12:28:22 spla-repro dhcpcd[808]: eth0: leased 192.168.200.203 for 300 seconds Oct 02 12:28:22 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 12:28:22 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 12:28:22 spla-repro systemd[1]: welcome.service: Deactivated successfully. Oct 02 12:28:22 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 12:28:22 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 12:28:22 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 12:28:22 spla-repro welcome[1628]: Resolved ip:[1] 192.168.200.203 Oct 02 12:28:22 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 12:28:22 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 12:28:22 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:22 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:22 spla-repro volumio[1122]: info: Received Get System Info Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 12:28:22 spla-repro volumio[1122]: info: Discovery: Getting this device information Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:22 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 12:28:22 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 12:28:23 spla-repro volumio5-onboarding[2097]: time=2026-10-02T12:28:23.487+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 12:28:24 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 02 12:28:24 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:24 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:24 spla-repro go-librespot[1633]: go-librespot daemon starting... Oct 02 12:28:24 spla-repro go-librespot[1634]: time="2026-10-02T12:28:24+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:24 spla-repro go-librespot[1634]: time="2026-10-02T12:28:24+02:00" level=debug msg="app state loaded" Oct 02 12:28:24 spla-repro go-librespot[1634]: time="2026-10-02T12:28:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=info msg="zeroconf server listening on port 38531" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="obtained new client token: AAGEez3sRsMawgYOHpetCh9gswbPRbKtRtH9bz0+jeZu1GZCvFKGSmukG1pm8D58RzoIaY0a5GiAFKGPx/YrEbsWg1XwtBydttAHGNEs/uug7OxNkf0DOqx03XAjI3ndEAPk4sF0oCWWrVUO2/ytfTYXpHQrDWeL4oFVdxm0VgyqhbRTn2wzcuGGuYbcvLhAMnbMbq8PdudexBwmPAbryepNRlNslber42MjOj0Sl/9HQhlzo9RGic4=" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=debug msg="completed challenge" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:25 spla-repro go-librespot[1634]: time="2026-10-02T12:28:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:25 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:25 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:25 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:25 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:26 spla-repro nmbd[1030]: [2026/10/02 12:28:26.495375, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 02 12:28:26 spla-repro nmbd[1030]: register_name_response: Answer name <00> differs from question name <00>. Oct 02 12:28:26 spla-repro nmbd[1030]: [2026/10/02 12:28:26.497299, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 02 12:28:26 spla-repro nmbd[1030]: register_name_response: Answer name <03> differs from question name <03>. Oct 02 12:28:26 spla-repro nmbd[1030]: [2026/10/02 12:28:26.497629, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 02 12:28:26 spla-repro nmbd[1030]: register_name_response: Answer name <20> differs from question name <20>. Oct 02 12:28:26 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:26 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:28 spla-repro volumio[1122]: info: BOOT COMPLETED Oct 02 12:28:28 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:28 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:28 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 02 12:28:28 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:28 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:28 spla-repro go-librespot[1659]: go-librespot daemon starting... Oct 02 12:28:28 spla-repro go-librespot[1660]: time="2026-10-02T12:28:28+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:28 spla-repro go-librespot[1660]: time="2026-10-02T12:28:28+02:00" level=debug msg="app state loaded" Oct 02 12:28:28 spla-repro go-librespot[1660]: time="2026-10-02T12:28:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=info msg="zeroconf server listening on port 33261" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="obtained new client token: AAETfG2GbPYj7YJ0ule+9iJjACjmWBNfQDsb4z8g1/6NIzNzC0dAnJC+eODWdWzZugeLNE7u3raG7JtCuThlzCI2i8YUSkH5Cah4zW3pcHfloA4P3CDFWp5Yhcpwr7db/YtA02T5s+rhRl9/QUodoKwp3CJIuoUkhaMEuc/M+Z2+eLGHcPjl+wfvVnqd6+kCYGqHyC9UPWfWqRjgRJGVGFqKEIVzvsFhytJo0fNLtl/u7bMjbGwOXoY=" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=debug msg="completed challenge" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:29 spla-repro go-librespot[1660]: time="2026-10-02T12:28:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:29 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:29 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:31 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:31 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:32 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 02 12:28:32 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:32 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:32 spla-repro go-librespot[1669]: go-librespot daemon starting... Oct 02 12:28:32 spla-repro go-librespot[1670]: time="2026-10-02T12:28:32+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:32 spla-repro go-librespot[1670]: time="2026-10-02T12:28:32+02:00" level=debug msg="app state loaded" Oct 02 12:28:32 spla-repro go-librespot[1670]: time="2026-10-02T12:28:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=info msg="zeroconf server listening on port 39895" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="obtained new client token: AAHcaVxNP9aGzd9iSFY1E+o4r31pYgWPnKrvXkusl64vfpJzYODmxUmyycEv1oPKNYKzd4SqDcRCc5srZLbEPOCSQVbL8Y67tNv/VHANS99G/mwWA1PjXJJ6H7u6RHlljAa1r9CW6kO7BV2SzQGLttjSwiv++r9oWb8uAsZeuiksC+x8/BAr+v+oZw1wbfbuokyyH1WsHyP92AIY0KwZAk87kMCnPztn7gDEGYrx+sZ/BO0j3kOOpFI=" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=debug msg="completed challenge" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:33 spla-repro go-librespot[1670]: time="2026-10-02T12:28:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:33 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:33 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:34 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:34 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:36 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 02 12:28:36 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:36 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:36 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:36 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:36 spla-repro go-librespot[1693]: go-librespot daemon starting... Oct 02 12:28:36 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:28:36 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:28:36 spla-repro go-librespot[1694]: time="2026-10-02T12:28:36+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:36 spla-repro go-librespot[1694]: time="2026-10-02T12:28:36+02:00" level=debug msg="app state loaded" Oct 02 12:28:36 spla-repro go-librespot[1694]: time="2026-10-02T12:28:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=info msg="zeroconf server listening on port 45181" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="obtained new client token: AAFmkBDYzggqOXk7geyYiw4NatVrZNDc6SChiTTTwDmFAq0B0wEJ2Q3fGyF52EGElNTyCoJfUmq7nZ+ZG/Nc7ZFh//aal3QZU/h0vImoMzV1ASH+xa08Z5DMGpkElpEHmas9GDXewl3Ae/jrQh9JeSXB/QKWPyOrOkJNZZpzg1wIVPdKdKq9JFmF2vWC00DcjyHkgmAP9mmOwqlK0j7Xdlt4Cr4oDpxN/aBT/IoFBKJw5QHtNY7OtlE=" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=debug msg="completed challenge" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:37 spla-repro go-librespot[1694]: time="2026-10-02T12:28:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:37 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:37 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:37 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:37 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:40 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:40 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:40 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 02 12:28:40 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:40 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:40 spla-repro go-librespot[1703]: go-librespot daemon starting... Oct 02 12:28:40 spla-repro go-librespot[1704]: time="2026-10-02T12:28:40+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:40 spla-repro go-librespot[1704]: time="2026-10-02T12:28:40+02:00" level=debug msg="app state loaded" Oct 02 12:28:40 spla-repro go-librespot[1704]: time="2026-10-02T12:28:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=info msg="zeroconf server listening on port 40179" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="obtained new client token: AAHJTvq3xfIdjpHPll+r5W1oNTJF1ipbd8tGKYeG2hBACHZ+q4vEKcola4lrnqq1HT1JqIpDa0qja/rV8n9wTsaZEuAsrx1LdAMasZMdJ4GWhG4b21Fac9uxc1JTpBQOVOMRkwPF0r4BZe2cjCvLgyADBxkFoCb4qTVoQO/Mq+EdS2gdQbE/Yuj4u/VUtFPHR81yVvhCNL77fdPB6Gt6IL2bm+7EBoz/C+lNpCD3y+DaKXiDQHZNG8lLIg==" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=debug msg="completed challenge" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:41 spla-repro go-librespot[1704]: time="2026-10-02T12:28:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:41 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:41 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:43 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:43 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:44 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 02 12:28:44 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:44 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:44 spla-repro go-librespot[1713]: go-librespot daemon starting... Oct 02 12:28:44 spla-repro go-librespot[1714]: time="2026-10-02T12:28:44+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:44 spla-repro go-librespot[1714]: time="2026-10-02T12:28:44+02:00" level=debug msg="app state loaded" Oct 02 12:28:44 spla-repro go-librespot[1714]: time="2026-10-02T12:28:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=info msg="zeroconf server listening on port 37471" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="obtained new client token: AAHtVmDyEfSMWWjL0JsgNRLaZFT/sZP9jK0PHooGdj26x8cOb/pyqOJj3u0LKLu+lSZKTnC2eDqI684Helz7lYctq7qBssKSW3nGD3zWYA7hCNlN2rpCi3AivzUzRMpWoqKP71r1MuScjDK0ej3ZInIW1u6HXFYfHaaiPv/IHjGojvVP7IsdIV/FLtEcxtSfNwHxL4BwO5Pi6STG5Sbd9dogHzrYUo3XEsr2r/d54gFpEPC9kbMBYsg=" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=debug msg="completed challenge" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:45 spla-repro go-librespot[1714]: time="2026-10-02T12:28:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:45 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:45 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:46 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:46 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:46 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:46 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:47 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 12:28:47 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 12:28:47 spla-repro systemd[1]: welcome.service: Deactivated successfully. Oct 02 12:28:47 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 12:28:47 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 12:28:47 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 12:28:47 spla-repro welcome[1755]: Resolved ip:[1] 192.168.200.203 Oct 02 12:28:47 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 12:28:47 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 12:28:47 spla-repro volumio[1122]: info: Received Get System Info Oct 02 12:28:47 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 12:28:47 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 12:28:47 spla-repro volumio[1122]: info: Discovery: Getting this device information Oct 02 12:28:47 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:47 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:47 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 12:28:47 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 12:28:47 spla-repro volumio[1122]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 12:28:48 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 02 12:28:48 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:48 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:48 spla-repro go-librespot[1760]: go-librespot daemon starting... Oct 02 12:28:48 spla-repro go-librespot[1761]: time="2026-10-02T12:28:48+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:48 spla-repro go-librespot[1761]: time="2026-10-02T12:28:48+02:00" level=debug msg="app state loaded" Oct 02 12:28:48 spla-repro go-librespot[1761]: time="2026-10-02T12:28:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:48 spla-repro volumio5-onboarding[2097]: time=2026-10-02T12:28:48.900+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=info msg="zeroconf server listening on port 42671" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="obtained new client token: AAFvMjwVCD4I4CG6yptPRHW+UB/tfAiGYxWS41zr8UcxM1hbwEyCyzZdT0cp+4FwUcDHwFzzXxnyEZ3j3q6T+5IvyKr3Q3xG2uetRNK6N2Ti7Rjz0tfDf8sDXfoMVIYM7nqMpIRbc/1xvro8yzl94QXZJjG6VeRw0yZID8JJ0jX5RBHWpAENTMZheBxTeMSrQAiolMGeArfeHljubkcAap7mXZw9uEt8YV/3/3uwzevqZHJWGIxaRQg=" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=debug msg="completed challenge" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:49 spla-repro go-librespot[1761]: time="2026-10-02T12:28:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:49 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:49 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:49 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:49 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:52 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:52 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:52 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 02 12:28:52 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:52 spla-repro go-librespot[1771]: go-librespot daemon starting... Oct 02 12:28:52 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:52 spla-repro go-librespot[1772]: time="2026-10-02T12:28:52+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:52 spla-repro go-librespot[1772]: time="2026-10-02T12:28:52+02:00" level=debug msg="app state loaded" Oct 02 12:28:52 spla-repro go-librespot[1772]: time="2026-10-02T12:28:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=info msg="zeroconf server listening on port 39203" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="obtained new client token: AAHuA/sw5lHQPrVMj+ljhsSu92jL4t932n1n6gpwu7RHZq9WJ0uGLhg6yT3utVKEhzBj5mzZB93UVoCqpEcYXhPOVCZvGC9yGvA7Vz3yq3ciLD6BZQ56hWRbnELMwHBNxsUTwl1aCdUAmGjD0efCfFaYV2HMTnyDUfgqP6cYJMi2haotdxr402Z5KsOgD/qLECeUyWg90HeO4KTqCl4EEmhhzUYgBJ9syYbJb7/f3zJh5ueDJ+zNZkY=" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=debug msg="completed challenge" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:53 spla-repro go-librespot[1772]: time="2026-10-02T12:28:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:53 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:53 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:55 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:55 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:28:56 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 02 12:28:56 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:56 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:28:56 spla-repro go-librespot[1795]: go-librespot daemon starting... Oct 02 12:28:56 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:28:56 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:28:56 spla-repro go-librespot[1796]: time="2026-10-02T12:28:56+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:28:56 spla-repro go-librespot[1796]: time="2026-10-02T12:28:56+02:00" level=debug msg="app state loaded" Oct 02 12:28:56 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:28:56 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:28:56 spla-repro go-librespot[1796]: time="2026-10-02T12:28:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=info msg="zeroconf server listening on port 40237" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="obtained new client token: AAFOsnRdpr17md117HlRT94v6kpTwi3nYXIASPFVz9AqtJfjQ9S6EX8u9xA2XZIEWchPDI2Tb8DfjJzo7QmXEEsHrbBlw+rBF8qa1VHLjPrCsHUpICiq88wIIxqqRo7TlZx/8P7UZyXQixYHCi4pQBEmqi/cF2Q4pONqItkYaNHykgrGu2IRoLYK1NxMkeQ4BoQGh9c4tRONsa/MMLSZLGcViDk/41VcNsOWN7cxUBL5N4R9JmJZZ6U=" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="completed keyexchange" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=debug msg="completed challenge" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:28:57 spla-repro go-librespot[1796]: time="2026-10-02T12:28:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:28:57 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:28:57 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:28:58 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:28:58 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:00 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Oct 02 12:29:00 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:00 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:00 spla-repro go-librespot[1805]: go-librespot daemon starting... Oct 02 12:29:00 spla-repro go-librespot[1806]: time="2026-10-02T12:29:00+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:00 spla-repro go-librespot[1806]: time="2026-10-02T12:29:00+02:00" level=debug msg="app state loaded" Oct 02 12:29:00 spla-repro go-librespot[1806]: time="2026-10-02T12:29:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=info msg="zeroconf server listening on port 36223" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="obtained new client token: AAHUeggsoOvYLNn3g7I81ym4XAp7zs7k/bTcUauK1uS/ruDLZftrQGjmcWQVbgYMwHbELanNnhBikYd32ptinIBVkUU+xOHbN8adfvWmdeWGazXviP39Lj/1W3QqtlZZFp69PEpiJVzCsMHY0uMirq3vqnTaZyV/zJr9WDvuHoOljLMuHf8ogU9DoNpEwYQ42SHcs8it0U7R4MZcaCSbIuDl9agtBss/DC8cYnsn7ZS5KjS9EuCr8iY=" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=debug msg="completed challenge" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:01 spla-repro go-librespot[1806]: time="2026-10-02T12:29:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:01 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:01 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:01 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:01 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:04 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:04 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:04 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Oct 02 12:29:04 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:04 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:04 spla-repro go-librespot[1815]: go-librespot daemon starting... Oct 02 12:29:04 spla-repro go-librespot[1816]: time="2026-10-02T12:29:04+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:04 spla-repro go-librespot[1816]: time="2026-10-02T12:29:04+02:00" level=debug msg="app state loaded" Oct 02 12:29:04 spla-repro go-librespot[1816]: time="2026-10-02T12:29:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=info msg="zeroconf server listening on port 40409" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="obtained new client token: AAHnZ+YPrN2IXKGl/w2A5dvgOrah7j6qWRgGm69lV7TkOsJpUfxmeerM4yn8DPyXWwZWD8Y1kMC7BiWevwPkjCUAp+nyBJg6dRVDHly28gAgAvOU3pKnn3bhgfqbsY00HcnC+z2wnxtHwvZqLmtcGtcfFaW9akmwOkW7hBult8FW+KhcfqgeNstQTD1+glqnVWxAlANDk2Uw//jVEXKZTBKYntpNUmy7PVNfvSXCX7ShS4s84ylscik=" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=debug msg="completed challenge" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:05 spla-repro go-librespot[1816]: time="2026-10-02T12:29:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:05 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:05 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:06 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:29:06 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:29:07 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:07 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:08 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Oct 02 12:29:08 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:08 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:08 spla-repro go-librespot[1839]: go-librespot daemon starting... Oct 02 12:29:08 spla-repro go-librespot[1840]: time="2026-10-02T12:29:08+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:08 spla-repro go-librespot[1840]: time="2026-10-02T12:29:08+02:00" level=debug msg="app state loaded" Oct 02 12:29:08 spla-repro go-librespot[1840]: time="2026-10-02T12:29:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=info msg="zeroconf server listening on port 37961" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="obtained new client token: AAE2ZOLEsUXywAYeBi3aH0fX92fEpE9KWN3i6VOCgYdlGzVooa7lvWRRMsyLwsen+1FmkihnO8H9GuCQwypWNQJlEcsIrj3B7q70N6oznUq7GI/OkKWAANlcpixA1i13yl7dz9SxJIRMeys3g+5TTw5TQK1AGwREoAriqgE0HHWgfLCLTXbm3Z5hGVbVnWvVYKHtXRbINog+M1wVlUdUppVoQQjLIjM5Uf4fpSeE0ue419dz5ZVXGDo=" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=debug msg="completed challenge" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:09 spla-repro go-librespot[1840]: time="2026-10-02T12:29:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:09 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:09 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:10 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:10 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:12 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Oct 02 12:29:12 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:12 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:12 spla-repro go-librespot[1850]: go-librespot daemon starting... Oct 02 12:29:12 spla-repro go-librespot[1851]: time="2026-10-02T12:29:12+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:12 spla-repro go-librespot[1851]: time="2026-10-02T12:29:12+02:00" level=debug msg="app state loaded" Oct 02 12:29:12 spla-repro go-librespot[1851]: time="2026-10-02T12:29:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=info msg="zeroconf server listening on port 46331" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="obtained new client token: AAEazI0AFVjBaDkLxCeCXkVRCF2Z81khUb9mVulqo/XsdR0nqXxYub1uoOxfQgLgJxvClWQcoV273T3Py8zGIcUxAWDNIUZvjtNxIOBFct7IAPJ9B2fXPokCf3xpv5jb8g7MLROHibJLgk4tHXuWGkXNrSbMQIsm3T3ZoNQQI3AS+qWevzy1zquPMv82X5eIkNHrAmc9IB0yLYZkkohbvcI7ddToZ8ex+xDFlUwhAAdQYJj1fFrPpK4=" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=debug msg="completed challenge" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:13 spla-repro go-librespot[1851]: time="2026-10-02T12:29:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:13 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:13 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:13 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:13 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:16 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:16 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:16 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Oct 02 12:29:16 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:16 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:16 spla-repro go-librespot[1874]: go-librespot daemon starting... Oct 02 12:29:16 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:29:16 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:29:16 spla-repro go-librespot[1875]: time="2026-10-02T12:29:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:16 spla-repro go-librespot[1875]: time="2026-10-02T12:29:16+02:00" level=debug msg="app state loaded" Oct 02 12:29:16 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:29:16 spla-repro volumio[1122]: info: Listing playlists Oct 02 12:29:16 spla-repro go-librespot[1875]: time="2026-10-02T12:29:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=info msg="zeroconf server listening on port 46201" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="obtained new client token: AAFqVOlGqQFKKa/kCUgJME+PmK04TdckQR+0ZZr8YG4XI+zmNsz5lg1bNHK2Cl0P10kqC9X9uGDkUU/HoNdPm+mA/rVo5pq355f4gAESJvo7paEfgjLDGEQYgGpHVi0xDTUh6uEz2c7EWaE31y1/Iy0svYlhNb+sIBPE752DWwtxx2sYE8gicytj+kWIsl/4jGM8b49oXqw0V7dxj57brKeuGSGOK7wtLWzwF4STI+lt9rSREZ7i2GI=" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=debug msg="completed challenge" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:17 spla-repro go-librespot[1875]: time="2026-10-02T12:29:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:17 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:17 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:19 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:19 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:20 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Oct 02 12:29:20 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:20 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:20 spla-repro go-librespot[1884]: go-librespot daemon starting... Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=debug msg="app state loaded" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=info msg="zeroconf server listening on port 33519" Oct 02 12:29:20 spla-repro go-librespot[1885]: time="2026-10-02T12:29:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:21 spla-repro go-librespot[1885]: time="2026-10-02T12:29:21+02:00" level=debug msg="obtained new client token: AAEAlTs+vYRLnxfEkDkWUWbOjsAsqXhtgwC0yiJft6NvJTaVUfeTUHNgjLIyOtry4mgk33tDA0YhP7MNiK4FTTValZMsG+GLD1PJyih4z227hwGEpgg8TCllslCmD6v6wihtFCYOQm0ENRWA07EY+WnbdIkTGS8MKgxyf+jLADkotjt0QbwP7ebKqLPPrzwdBvSQ0DppVVnjAXfX2OgYt4WPCuZkQFoBl/nMrHRSv9JkgMeM2s8Ak78=" Oct 02 12:29:21 spla-repro go-librespot[1885]: time="2026-10-02T12:29:21+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:21 spla-repro go-librespot[1885]: time="2026-10-02T12:29:21+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:21 spla-repro go-librespot[1885]: time="2026-10-02T12:29:21+02:00" level=debug msg="completed challenge" Oct 02 12:29:21 spla-repro go-librespot[1885]: time="2026-10-02T12:29:21+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:21 spla-repro go-librespot[1885]: time="2026-10-02T12:29:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:21 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:21 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:22 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:22 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:24 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Oct 02 12:29:24 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:24 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:24 spla-repro go-librespot[1895]: go-librespot daemon starting... Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=debug msg="app state loaded" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=info msg="zeroconf server listening on port 45019" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=debug msg="obtained new client token: AAENmK7H1Yqy/SQQxcxCGFkRHaQ/H0DlTskfEilHVe9Wur/uxPwgjYRtTfBct98v8bfcMqZtd0R5bWItBCqdHFt752bGELDT4CgmkrgnrpoQGVGi+E6RaTxVED5gpF+aUzQyczI2g5yQHePimwed/laZztfQLa1/QNZ8iIvAe/A99Pcj1FTivd8P5Ysb39QDG6CP1JaDVZ5PV+hVnvIYEsAgpy8cfiLjVsOjmJE2g/FgX0Qtnigv34y1PA==" Oct 02 12:29:24 spla-repro go-librespot[1896]: time="2026-10-02T12:29:24+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:25 spla-repro go-librespot[1896]: time="2026-10-02T12:29:25+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:25 spla-repro go-librespot[1896]: time="2026-10-02T12:29:25+02:00" level=debug msg="completed challenge" Oct 02 12:29:25 spla-repro go-librespot[1896]: time="2026-10-02T12:29:25+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:25 spla-repro go-librespot[1896]: time="2026-10-02T12:29:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:25 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:25 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:25 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:25 spla-repro volumio[1122]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:26 spla-repro volumio[1122]: info: CoreCommandRouter::volumioGetState Oct 02 12:29:26 spla-repro volumio[1122]: info: CorePlayQueue::getTrack 0 Oct 02 12:29:28 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Oct 02 12:29:28 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:28 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:28 spla-repro go-librespot[1920]: go-librespot daemon starting... Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=debug msg="app state loaded" Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 12:29:28 spla-repro volumio[1122]: info: Initializing connection to go-librespot Websocket Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=debug msg="new websocket client" Oct 02 12:29:28 spla-repro volumio[1122]: info: Connection to go-librespot Websocket established Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=info msg="zeroconf server listening on port 37141" Oct 02 12:29:28 spla-repro go-librespot[1921]: time="2026-10-02T12:29:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 12:29:29 spla-repro go-librespot[1921]: time="2026-10-02T12:29:29+02:00" level=debug msg="obtained new client token: AAEz9j2LpkjI0o2ukyNwBPEa2p+i2j2okgSgKZz86T42jXZpWLfDEYqg0hbc9t7sjccjAKhT7DMPlVEZJKHEbkcOgyCPpm7oka7wJTPn1BgZtI491V7gxs6l28dEQm7U45kWmUV/T5wN57YLlarVMm/snwKjUE+qs/rxonz3Jzjigh83l+/HBk4KHZV1AZZbnOTrGsGJ5AObfZ61VOq4j43nbQ6wNHVHIBkboqkdw3DGqAPQlP+Dfcevww==" Oct 02 12:29:29 spla-repro go-librespot[1921]: time="2026-10-02T12:29:29+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 12:29:29 spla-repro go-librespot[1921]: time="2026-10-02T12:29:29+02:00" level=debug msg="completed keyexchange" Oct 02 12:29:29 spla-repro go-librespot[1921]: time="2026-10-02T12:29:29+02:00" level=debug msg="completed challenge" Oct 02 12:29:29 spla-repro go-librespot[1921]: time="2026-10-02T12:29:29+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 12:29:29 spla-repro go-librespot[1921]: time="2026-10-02T12:29:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 12:29:29 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 12:29:29 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 12:29:29 spla-repro volumio[1122]: info: Connection to go-librespot Websocket closed Oct 02 12:29:31 spla-repro volumio[1122]: info: Getting Spotify volume Oct 02 12:29:31 spla-repro volumio[1122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 12:29:31 spla-repro volumio[1122]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 12:29:31 spla-repro volumio[1122]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 02 12:29:31 spla-repro volumio[1122]: errno: -111, Oct 02 12:29:31 spla-repro volumio[1122]: code: 'ECONNREFUSED', Oct 02 12:29:31 spla-repro volumio[1122]: syscall: 'connect', Oct 02 12:29:31 spla-repro volumio[1122]: address: '127.0.0.1', Oct 02 12:29:31 spla-repro volumio[1122]: port: 9879, Oct 02 12:29:31 spla-repro volumio[1122]: response: undefined Oct 02 12:29:31 spla-repro volumio[1122]: } Oct 02 12:29:31 spla-repro volumio[1122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 12:29:32 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Oct 02 12:29:32 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:32 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 12:29:32 spla-repro go-librespot[1942]: go-librespot daemon starting... Oct 02 12:29:32 spla-repro sudo[1946]: volumio : unable to resolve host spla-repro: System error Oct 02 12:29:32 spla-repro go-librespot[1945]: time="2026-10-02T12:29:32+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 12:29:32 spla-repro go-librespot[1945]: time="2026-10-02T12:29:32+02:00" level=debug msg="app state loaded" Oct 02 12:29:32 spla-repro sudo[1946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 12:28' Oct 02 12:29:32 spla-repro sudo[1946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 12:29:32 spla-repro go-librespot[1945]: time="2026-10-02T12:29:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" 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"