Oct 02 08:41:00 spla-repro volumio[13681]: verbose: New Socket.io Connection to 192.168.200.203:3000 from 192.168.200.201 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 59 Oct 02 08:41:00 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 02 08:41:00 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:00 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+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 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+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 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=info msg="zeroconf server listening on port 43801" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=debug msg="obtained new client token: AAEUGY6+EgwuaRAp4lPHwZ7ilInRpia+YRuK+odAyxZlP86PpstZDpwDRPzkZ+6F8PebGkxp03ZZBtYYaTg/LY5cRlCnGm1aExRKKIzjxJnMODkrN16HWs+YEzUc2vCZ9Ya+L5XLUp7uDc1K1rTuYTEz3i2hhUzhg2yT8Rd4oANGwhfYwL4uUhFhK0elHiZznuVBDT2X5LRairz9Fr8K19dXgbv3d+qpLlKUyB02qUm/b/BTNaaMLmkVhA==" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=debug msg="completed challenge" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:00 spla-repro go-librespot[13995]: time="2026-10-02T08:41:00+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 08:41:00 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:00 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:02 spla-repro mpd[13888]: exception: No database Oct 02 08:41:02 spla-repro mpd[13888]: exception: No database Oct 02 08:41:02 spla-repro mpd[13888]: exception: No database Oct 02 08:41:02 spla-repro volumio[13681]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 02 08:41:02 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:02 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:02 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:02 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:03 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 02 08:41:03 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:03 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:03 spla-repro go-librespot[14018]: go-librespot daemon starting... Oct 02 08:41:03 spla-repro go-librespot[14019]: time="2026-10-02T08:41:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:03 spla-repro go-librespot[14019]: time="2026-10-02T08:41:03+02:00" level=debug msg="app state loaded" Oct 02 08:41:03 spla-repro go-librespot[14019]: time="2026-10-02T08:41:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+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 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+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 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+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 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=info msg="zeroconf server listening on port 42921" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=debug msg="obtained new client token: AAGXX5VRFChhH6IkYjgZMggka7luzpUphq5vjnlwiFYVpX2yEtvEWHQ+GzOdQ0KTF0q1Jiqqu9icZvQMA1sxhFkC8sY5ptk8vkH4f/E8c0q5t6j5/LigmFQj27r8TaCmJTVyUKVDKL2EF/wHPYh9NK7BAhed3vNdNU50IijHWJkgf++OS+QUsuaMhH7N8bm2NpcP95/d9I6a9L+ZS3exmqs8yWSqvLvqSamJrwJ9EUXRS0TeruwuTYM=" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+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 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=debug msg="completed challenge" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:04 spla-repro go-librespot[14019]: time="2026-10-02T08:41:04+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 08:41:04 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:04 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin bluetooth to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin multiroom to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin metavolumio to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin cd_controller to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 02 08:41:05 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 02 08:41:07 spla-repro volumio[13681]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 02 08:41:07 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 02 08:41:07 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:07 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:07 spla-repro volumio[13681]: info: Starting MyVolumio Remote Streaming Endpoints Oct 02 08:41:07 spla-repro volumio[13681]: info: MyVolumio login type: Token Oct 02 08:41:07 spla-repro volumio[13681]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 02 08:41:07 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 02 08:41:07 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Oct 02 08:41:07 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:07 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:07 spla-repro go-librespot[14029]: go-librespot daemon starting... Oct 02 08:41:07 spla-repro go-librespot[14030]: time="2026-10-02T08:41:07+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:07 spla-repro go-librespot[14030]: time="2026-10-02T08:41:07+02:00" level=debug msg="app state loaded" Oct 02 08:41:07 spla-repro go-librespot[14030]: time="2026-10-02T08:41:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+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 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+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 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+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 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=info msg="zeroconf server listening on port 33115" Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=debug msg="obtained new client token: AAExXLpLJLl7EPybA/jZhzuTtuxnFgaFxzCMFpBxDzd3i52t2y29/O/cVJmJ/9xp8/0cF5PvhsqIucigvEl7bwO+FYH86F9ycxv1DtqsGg4TiuQJPeCMYKCcLtEyo/2Q/NSNZELy+DuPFKvOaOFOi7fdJZz94jOzjqfAz5tPgOM37/exHqsHv1bNgb6crXeyjah6vqZwtrZucQGxkM7mJvzYyY7LIKswND9ZL1JuhkMex0jMJFo7YedI2Q==" Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+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 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=debug msg="completed challenge" Oct 02 08:41:08 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 08:41:08 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:08 spla-repro volumio[13681]: info: Streaming services startup Oct 02 08:41:08 spla-repro volumio[13681]: info: Starting Streaming Daemon Oct 02 08:41:08 spla-repro volumio[13681]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 08:41:08 spla-repro sudo[14040]: volumio : unable to resolve host spla-repro: System error Oct 02 08:41:08 spla-repro sudo[14040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 08:41:08 spla-repro sudo[14040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:41:08 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:08 spla-repro sudo[14040]: pam_unix(sudo:session): session closed for user root Oct 02 08:41:08 spla-repro go-librespot[14030]: time="2026-10-02T08:41:08+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 08:41:08 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:08 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:08 spla-repro volumio[13681]: error: Cannot start Volumio Streaming Daemon Oct 02 08:41:08 spla-repro volumio[13681]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 08:41:08 spla-repro volumio[13681]: sudo: unable to resolve host spla-repro: System error Oct 02 08:41:08 spla-repro volumio[13681]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 08:41:08 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: socket hang up Oct 02 08:41:09 spla-repro volumio[13681]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 08:41:09 spla-repro volumio[13681]: info: MyVolumio token set successfully Oct 02 08:41:09 spla-repro volumio[13681]: info: MYVOLUMIO: Adding device Oct 02 08:41:09 spla-repro volumio[13681]: info: MYVOLUMIO: Evaluating Server Oct 02 08:41:10 spla-repro volumio[13681]: info: MyVolumio status changed Oct 02 08:41:10 spla-repro volumio[13681]: info: Streaming services startup Oct 02 08:41:10 spla-repro volumio[13681]: info: Starting Streaming Daemon Oct 02 08:41:10 spla-repro volumio[13681]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 08:41:10 spla-repro volumio[13681]: info: Removing audio output: Oct 02 08:41:10 spla-repro volumio[13681]: info: Stoppping Tunnel 1 Oct 02 08:41:10 spla-repro sudo[14068]: volumio : unable to resolve host spla-repro: System error Oct 02 08:41:10 spla-repro sudo[14068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 08:41:10 spla-repro sudo[14068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:41:10 spla-repro sudo[14068]: pam_unix(sudo:session): session closed for user root Oct 02 08:41:10 spla-repro sudo[14071]: volumio : unable to resolve host spla-repro: System error Oct 02 08:41:10 spla-repro volumio[13681]: error: Cannot start Volumio Streaming Daemon Oct 02 08:41:10 spla-repro volumio[13681]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 08:41:10 spla-repro volumio[13681]: sudo: unable to resolve host spla-repro: System error Oct 02 08:41:10 spla-repro volumio[13681]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 08:41:10 spla-repro sudo[14071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 02 08:41:10 spla-repro sudo[14071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:41:10 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 08:41:10 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 08:41:10 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 08:41:10 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 08:41:10 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 08:41:10 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 08:41:10 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 08:41:10 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 08:41:10 spla-repro sudo[14071]: pam_unix(sudo:session): session closed for user root Oct 02 08:41:10 spla-repro volumio[13681]: info: Remote SSH Stopped Oct 02 08:41:10 spla-repro volumio[13681]: info: Setting Geolocation for MyVolumio to eu7 Oct 02 08:41:10 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:10 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:10 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:10 spla-repro volumio[13681]: info: Successfully Added MyVolumio device Oct 02 08:41:11 spla-repro volumio[13681]: info: Updating MyVolumio device info Oct 02 08:41:11 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:11 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:11 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:11 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:11 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:11 spla-repro volumio[13681]: info: Successfully Updated MyVolumio device Oct 02 08:41:11 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 02 08:41:11 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:12 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:12 spla-repro go-librespot[14073]: go-librespot daemon starting... Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=debug msg="app state loaded" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+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 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+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 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+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 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=info msg="zeroconf server listening on port 39885" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=debug msg="obtained new client token: AAF1cW94rF/WQfDkTBlj6qeo7roHo4TUPUF/6/Yb5WnJX8HzKK1oI3W46zUnEUkLhylbEef46v3cVK8lYrKoWQo5b2ZRcGI5QWX0WqbWbMIdH/pRIFaT7q3L8OqIKCCR7FvNBffqeLcrkDwBH/gNDfqn8Li7uYUUw1RWmhpAC6GJeevFzuW5bmflpX9BJmwRfkH9e6Ku/pz8NMzsWBvzsUqZOHag6sXGq3/oyGNjOpxmQ/e/zi9jnZ7iIQ==" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=debug msg="completed challenge" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:12 spla-repro go-librespot[14078]: time="2026-10-02T08:41:12+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 08:41:12 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:12 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:12 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:12 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:12 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:41:12 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:41:14 spla-repro dhcpcd[808]: eth0: failed to renew DHCP, rebinding Oct 02 08:41:14 spla-repro dhcpcd[808]: eth0: leased 192.168.200.203 for 300 seconds Oct 02 08:41:14 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 08:41:14 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 08:41:14 spla-repro systemd[1]: welcome.service: Deactivated successfully. Oct 02 08:41:14 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 08:41:14 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 08:41:14 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 08:41:14 spla-repro welcome[14115]: Resolved ip:[1] 192.168.200.203 Oct 02 08:41:14 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 08:41:14 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 08:41:14 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:14 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:14 spla-repro volumio[13681]: info: Received Get System Info Oct 02 08:41:14 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 08:41:14 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 08:41:14 spla-repro volumio[13681]: info: Discovery: Getting this device information Oct 02 08:41:14 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:14 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:14 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 08:41:14 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 08:41:14 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 08:41:15 spla-repro systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 08:41:15 spla-repro systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 08:41:15 spla-repro systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 08:41:15 spla-repro systemd[1]: welcome.service: Deactivated successfully. Oct 02 08:41:15 spla-repro systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 08:41:15 spla-repro systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 08:41:15 spla-repro systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 08:41:15 spla-repro welcome[14146]: Resolved ip:[1] 192.168.200.203 Oct 02 08:41:15 spla-repro systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 08:41:15 spla-repro systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 08:41:15 spla-repro volumio[13681]: info: Received Get System Info Oct 02 08:41:15 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 08:41:15 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 08:41:15 spla-repro volumio[13681]: info: Discovery: Getting this device information Oct 02 08:41:15 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:15 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:15 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 08:41:15 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 08:41:15 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 08:41:15 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 02 08:41:15 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:15 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:16 spla-repro go-librespot[14160]: go-librespot daemon starting... Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=debug msg="app state loaded" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+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 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+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 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+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 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=info msg="zeroconf server listening on port 45983" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=debug msg="obtained new client token: AAE1S3zd2559xLmkpGss1RFvdpmpOUvhpwjTv5AOu11D0dvbF+DayIQhbDo3o6ry5qIiBnU1oiAd+jW6ue79X/MzwNh4y0PJgl94b/AjTX0SkSFhRKotp6ZPCc4PoPz4b+auZYxxOkl2po6g8gGvc0b0uFFWzRJtMX6ZvastNs4KDFhOo4zpSC6wfpgxjLCaPzUic5xsTmfxEDaTjPLkRuLzAT6u/4UJbL+9ehVE2g1KtvOIl0D1c8sM+w==" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=debug msg="completed challenge" Oct 02 08:41:16 spla-repro volumio5-onboarding[10922]: time=2026-10-02T08:41:16.727+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:16 spla-repro systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 08:41:16 spla-repro systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 08:41:16 spla-repro systemd[1]: setdatetime-helper.service: Consumed 1.417s CPU time. Oct 02 08:41:16 spla-repro go-librespot[14161]: time="2026-10-02T08:41:16+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 08:41:16 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:16 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:17 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:17 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:19 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 02 08:41:19 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:19 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:20 spla-repro go-librespot[14180]: go-librespot daemon starting... Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=debug msg="app state loaded" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+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 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+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 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=info msg="zeroconf server listening on port 35793" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=debug msg="obtained new client token: AAENb+HTrjDaaCcbGdhPynvNoiMAXoO5BsQwKcZxJl95k2yX3VLo4z7tfMJlVWw0fS5OHf5JwI5keelzVWPD0Xu9UTQJ3H2WdbuyW2E9pVcqlSfJBPkt+nOcFMwE+vmFHiGiCk9iPqfl02Umouw1oNq9pCuWJdkLDzus9my/JsQp25KhzhG0YX6iKEpbCDFGuwZAsqUC9bEEASpjZrlbN2zP5B3P2aW92KKy+dm3tRGFZe6yldY9pTsrkA==" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=debug msg="completed challenge" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:20 spla-repro go-librespot[14181]: time="2026-10-02T08:41:20+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 08:41:20 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:20 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:20 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:20 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:21 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 08:41:21 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:21 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 08:41:21 spla-repro volumio-remote-updater[719]: Test mode disabled Oct 02 08:41:21 spla-repro volumio-remote-updater[719]: Alpha mode disabled Oct 02 08:41:21 spla-repro volumio-remote-updater[719]: Alpha legacy test mode disabled Oct 02 08:41:21 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 02 08:41:22 spla-repro volumio[13681]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 08:41:22 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 08:41:22 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 08:41:22 spla-repro volumio[13681]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 60 Oct 02 08:41:22 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:22 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:22 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:22 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:23 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:23 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:23 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 02 08:41:23 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:23 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:23 spla-repro go-librespot[14208]: go-librespot daemon starting... Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=debug msg="app state loaded" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41: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-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+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 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+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 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=info msg="zeroconf server listening on port 33049" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=debug msg="obtained new client token: AAFIfP4BgJ1uBXDo+j5hZebkiqVnKKztE9JfP5D0QTyTBbLaba4UzV4jSgL2cWp6dcjfuFJu62INDSNWq56LNGeKGGnLBzNced1coLq2UIyfLWK3TB6GeN2D1eIn/cQwRjeGjgjCb3+pfX0v6QIX3pyQAfPOvkvmqdB27DDDLDV+P/7QqyHUwvR+ZmaWrTdlRGNV6eAI3/f4AJFTz+xzIiC6gT0+W8L+OI27f7Y6ltKMh+p1QwTUP6VFyw==" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=debug msg="completed challenge" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:24 spla-repro go-librespot[14209]: time="2026-10-02T08:41:24+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 08:41:24 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:24 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:26 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:26 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 08:41:27 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 08:41:27 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 02 08:41:27 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:27 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:28 spla-repro go-librespot[14218]: go-librespot daemon starting... Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="app state loaded" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=info msg="zeroconf server listening on port 36827" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="obtained new client token: AAF03XwrZL9X7/Qmk/aJjIu3T2GMMe+LnRHHdUmSJU3Vq3SiY90gvXX8nXKW1HtiJFDnE3oTZJJPSavGWdybMc6wMI3eE59zzhwN/2x7ST/RPcvRpj/1ODba9Ex2rsS0dmPzr39bDsrkvyFOQ4dxxWzWdzfRjrQN3idB2cla31x5LUSUI9yrp/yzS0uC4klW1PG0gkQEfhSBUGcJsKcKwcZbcqk8g4SAe4iZGVxcjYZMUD6p69BZTONvEA==" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=debug msg="completed challenge" Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:28 spla-repro volumio[13681]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 02 08:41:28 spla-repro volumio[13681]: info: Completed starting MyVolumio Plugin Oct 02 08:41:28 spla-repro volumio[13681]: [Metrics] CommandRouter: 50s 57.02ms Oct 02 08:41:28 spla-repro volumio[13681]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 08:41:28 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 08:41:28 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 08:41:28 spla-repro volumio[13681]: info: CoreCommandRouter::Close All Modals sent Oct 02 08:41:28 spla-repro volumio[13681]: info: CoreCommandRouter::Close All Modals sent Oct 02 08:41:28 spla-repro go-librespot[14219]: time="2026-10-02T08:41:28+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 08:41:28 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:28 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:29 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 08:41:29 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 08:41:29 spla-repro volumio[13681]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 08:41:29 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:29 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:31 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 02 08:41:31 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:31 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:31 spla-repro go-librespot[14229]: go-librespot daemon starting... Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=debug msg="app state loaded" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+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 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+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 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=info msg="zeroconf server listening on port 43985" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=debug msg="obtained new client token: AAHXCVDrmWzBJx1RIoCO1hKMQoVxA9l9CMCIdjQoNStYUtC34MTSIAFhX9HAs59d82UIgd2jdhFWt0LLTBtlGxg0qC22YdnrIwhWQVcLwDx1xE6bW4vfRYMI61H4fwfPK+jXvD8VsQ6UOIBIMD5QSx6weKh8ZJsUSdGl8NHgK1HDHCskgHOq/nApB8weV3UmcDr7YmH9i6oU9aJD9XVvOvtY+P3Yg0YxpBtyrD6QmxoT4KIuxekDPb66XQ==" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=debug msg="completed challenge" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:32 spla-repro go-librespot[14230]: time="2026-10-02T08:41:32+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 08:41:32 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:32 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:32 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:32 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:32 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:32 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:32 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:41:32 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:41:35 spla-repro volumio[13681]: info: BOOT COMPLETED Oct 02 08:41:35 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:35 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:35 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 02 08:41:35 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:35 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:35 spla-repro go-librespot[14255]: go-librespot daemon starting... Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=debug msg="app state loaded" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+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 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+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 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=info msg="zeroconf server listening on port 43637" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=debug msg="obtained new client token: AAH5ldxGs0lIQiV2GsSFP4n7mR/Y+YsOUQ2RitmC1vx6ROM1C0f/56RjYtiTe3iVGaMbwvG1GnXUKkHnXT26HLe6MvC+/TXZWPPx2kHBJECwTFZhcu6G2xk7yvRWAiasP8Z7saPChj1i9hhuTvkdipSQ3B7XNEbODASu40U8Srb2XZnJAEOg6SPhtg+eotbtnMhC1P8PCO7gt7Px6ZLR2e2dTQsEMlYQONt9mXEH3DTDIba/uw6DYbJF9g==" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=debug msg="completed challenge" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:36 spla-repro go-librespot[14256]: time="2026-10-02T08:41:36+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 08:41:36 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:36 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:38 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:38 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:39 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 02 08:41:39 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:40 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:40 spla-repro go-librespot[14265]: go-librespot daemon starting... Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=debug msg="app state loaded" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+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 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+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 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=info msg="zeroconf server listening on port 36411" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=debug msg="obtained new client token: AAE+8WWiaYg7GW04xKqCzCUTj5dre7rh3EvR4x7DZOzFrqEd9/7civZtk4l73iNr9EgmTJ5pD7c8eBrknIDfg5I81tA0jlFmvSffx3NK6Ir9m9pJd0GZgf1i6Sb4FJwxYV/JDMlxewS/Y0lPk5HBN88IJRoY+TZhhvl1YC8ZEuk8hWh7qkpEfobNtJNWmP0nHeithkY87RlI9bctSbkb+SeUF7Q9mZ67DCzINJHplwvMa+APgUzPBA2EfA==" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=debug msg="completed challenge" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:40 spla-repro go-librespot[14266]: time="2026-10-02T08:41:40+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 08:41:40 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:40 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:41 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:41 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:42 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:42 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:43 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 02 08:41:43 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:43 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:43 spla-repro go-librespot[14290]: go-librespot daemon starting... Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="app state loaded" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=info msg="zeroconf server listening on port 41171" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="obtained new client token: AAGmgR6C3S8DJ+sbpXg+OIqMVpYui2Ju2EjXsN+d1Tb/KQz0hC/wmUMf8gEOoNh2HoS48KLOvXXdrJxl7mwZWIoo+YO1hrbncogMX+0RkJ9XFEsV5Dox8K7Ta0gW9K9+x0ZsPjX+GfyPzF6aY341ATjnO8LF4o3QKf7XXR+DEV4xxly0CE9vtiUdIGiyCyn34kxIorzFuUVDQ+n+7fqd2OdXbaVW0fyxrDIrs68QZHKBkyhWAJfG8VA16w==" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+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 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=debug msg="completed challenge" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:44 spla-repro go-librespot[14291]: time="2026-10-02T08:41:44+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 08:41:44 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:44 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:44 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:44 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:47 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:47 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:47 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 02 08:41:47 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:48 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:48 spla-repro go-librespot[14301]: go-librespot daemon starting... Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=debug msg="app state loaded" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+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 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+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 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+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 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=info msg="zeroconf server listening on port 34327" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=debug msg="obtained new client token: AAFiFxcIXbQdj9XtARyc5MK6HUPZcjRMqv1l1MWoYnML1QWooomPRqZHDHFet8vLwDivF1G3ScOXjQZrmFRCTIuo3CzpImSuWhljyh2GVSdKyxuxC9duvp4CvEhXmfnN19OL2a2ZrZUE7oZIRUKckKCE4zqETh5XYP52HaxcdcGtbR0tYsQxdO5JozjoVD/xuX22mePia/ddmqnzW48tDcZqzOdYTxHRBaqfN9/mJ4Rt2fL9cXLY2tuw6Q==" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=debug msg="completed challenge" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:48 spla-repro go-librespot[14302]: time="2026-10-02T08:41:48+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 08:41:48 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:48 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:50 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:50 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:51 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 02 08:41:51 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:52 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:52 spla-repro go-librespot[14313]: go-librespot daemon starting... Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=debug msg="app state loaded" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+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 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+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 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+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 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=info msg="zeroconf server listening on port 36587" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=debug msg="obtained new client token: AAFH7ZRQcSOty9mu4GM0FEt0qpeQHMjKVlf/0z8bUpVniG/dA643c67dmf7mRkncHw10hR8S5kWnzztl9WKS9k2qJdlR1IDHBn67g6hdqZaoJOk21MirlwhaZYQqi4EiMOjK0ay9yd04MPBPrOZUOjuEgVtFQmpjyuQ+7bbwDv58PCmvQjIeQab6m9eV08xT+DXMLtXsemrtniOM3jztULCuKMTt9vFVVnkexfPcS0RLhNh92q+1+E6FXw==" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=debug msg="completed challenge" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:52 spla-repro go-librespot[14314]: time="2026-10-02T08:41:52+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 08:41:52 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:52 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:52 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:41:52 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:41:52 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:41:52 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:41:53 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:53 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:55 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 02 08:41:55 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:56 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:41:56 spla-repro go-librespot[14337]: go-librespot daemon starting... Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=debug msg="app state loaded" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+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 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+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 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+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 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=info msg="zeroconf server listening on port 34189" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=debug msg="obtained new client token: AAGRoT3HqKDVmK+BntrBRuuPRctyHhjZJKIW7FRKqWDcBciis2UMRQIvkTflj9GGfqspkblxl/nCRlA8e9/qrt0AojzUSnG5fLe0G+5DNwsrEKHN9YElluP4HpSphwRqytZRwyiMT7eYa9iopt+EyK9AZAmDvwCnYV9P+Ch2xPMWQ093UYrOARb59YmPeu8W57k8Y4AJMLv8oETkvTST7gGy2j4v8fEOWZ7XSTsr4T9mzVlPxX12bbvUBw==" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=debug msg="completed keyexchange" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=debug msg="completed challenge" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:41:56 spla-repro go-librespot[14338]: time="2026-10-02T08:41:56+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 08:41:56 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:41:56 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:41:56 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:56 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:59 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:41:59 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:41:59 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 02 08:41:59 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:00 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:00 spla-repro go-librespot[14348]: go-librespot daemon starting... Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=debug msg="app state loaded" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42: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-gae2.spotify.com:80]" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+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 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+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 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=info msg="zeroconf server listening on port 38243" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=debug msg="obtained new client token: AAFD+nHnHHxWeTrIodKtAsq95o244Bd65/uMEs2lQh3/n5YZeGrIBhkQTLTQXcvKFsodi24oGqDoqK5p3WnYU5n2a6kzQR4XR7Eor3bM+ai4/hun9qMlrOum2+CtVxX+4vIjd0UmQkkgCw/GN5UHn3zaD8J5t2X/mYVPMH0BXbrXpJhnCPAGZfiZEzjV09rykBoH7GtQZLDANwRLICu1jml0CM8diuC2S+E6hFuE6w825z9NpEtPvBrE4g==" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=debug msg="completed challenge" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:42:00 spla-repro go-librespot[14349]: time="2026-10-02T08:42:00+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 08:42:00 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:42:00 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:42:02 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:02 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:02 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:42:02 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:42:03 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 02 08:42:03 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:04 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:04 spla-repro go-librespot[14372]: go-librespot daemon starting... Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=debug msg="app state loaded" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+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 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+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 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+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 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=info msg="zeroconf server listening on port 35935" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=debug msg="obtained new client token: AAF6wH9PQFqCYwJ7bzRfHY4yTg9iKIfRw3E8t3DptqGJMkFpv3eKpnkcKJ9cZhFr+x9/+R4/y7M4ywOF/HnWowdvYuQZ1wA9hfjqZ2Tou4Aw1/1dpS6bY29qU6VRftvKvvS82EjnKAJBl+A4fLPj09arVH9yvvLjMPjcHUNuQg0GjLIRQkLET+AI/Koh+rHHmRwhUfAKlaw4uSRac7tOCm3tsgWLUk3f5hhaTdVa9xVrkpxqbVm1ngSIsw==" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=debug msg="completed challenge" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:42:04 spla-repro go-librespot[14373]: time="2026-10-02T08:42:04+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 08:42:04 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:42:04 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:42:05 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:05 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:07 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 02 08:42:07 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:08 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:08 spla-repro go-librespot[14384]: go-librespot daemon starting... Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=debug msg="app state loaded" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+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 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+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 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+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 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=info msg="zeroconf server listening on port 35807" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=debug msg="obtained new client token: AAFbNdbzzAt/F5A3TTYba+s1mCKr056OkNoc3WyF1lhfalrd0rQd+f1wa6ddIe7BeCwDc3GNwC56pfZWq9Yi0ZNZBYasGdo4XFlIZg60XTf+jo9/o9m73lpKCdSQO+d8uwEHSoQUV+E1Mr9HXVVgeB/0dvf1D/DZeOyrft1fDUr1Ckdyt3n2BZKdFmtezvuO92pi9s6bc/3GMRDfHntDHXuwiKpUcie4vDof6jYmvSoiZjcBhW0JyPQCmA==" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=debug msg="completed challenge" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:42:08 spla-repro go-librespot[14385]: time="2026-10-02T08:42:08+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 08:42:08 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:42:08 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:42:08 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:08 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:11 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:11 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:11 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 02 08:42:11 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:11 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:11 spla-repro go-librespot[14395]: go-librespot daemon starting... Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=debug msg="app state loaded" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+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 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+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 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+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 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=info msg="zeroconf server listening on port 46023" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=debug msg="obtained new client token: AAEvAvlg/QgNkz5tEC51Ao7B87mQwbdgrSRYWy317mlyRIc4VlnR91vfCc7Ay1QGeJWNbwKFsk3jYyKCDLjAEWB7TGIN6q9duSAgUMXArmdPR+u8m8xg+nZQeXGEnw4BObGf196nzyFri0LjOjaaXJ2BmOFHH+WS3UD8St1aK89Uh8y6W2f/uzsbX933/3XRkoMkHRg1LndfMxdik8+g0Zlkf19nDRPezZpntc34q1/PrCswvWOuI/Paog==" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=debug msg="completed challenge" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:42:12 spla-repro go-librespot[14396]: time="2026-10-02T08:42:12+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 08:42:12 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:42:12 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:42:12 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:42:12 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:42:12 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:42:12 spla-repro volumio[13681]: info: Listing playlists Oct 02 08:42:14 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:14 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:15 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 02 08:42:15 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:16 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:16 spla-repro go-librespot[14420]: go-librespot daemon starting... Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=debug msg="app state loaded" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+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 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+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 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+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 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=info msg="zeroconf server listening on port 34823" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=debug msg="obtained new client token: AAHxHnS3fIjrVxvmKtqgeIlOCK5FWe2UY5V7vnCzw0G1ZTLd3opukO0C6140oAu0XMgeicHhabJNk93112G7k2hDinym/sCaxDwLTa7oFn2cQOxxVidl3/ZIjxgVbMEbgWWHXD49WNx5ji61dGBcC7IwTOAdQmpoMpw2VNURkCL4FbXhhm2PX5g3c9Esgsv3kLFz9RKl+chDuxdJ4rdtfKf5u8Ab5cGiqAlDfHSdhokOjWGK5Kpy5SJCjA==" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=debug msg="completed challenge" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:42:16 spla-repro go-librespot[14421]: time="2026-10-02T08:42:16+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 08:42:16 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:42:16 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:42:17 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:17 spla-repro volumio[13681]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:19 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 02 08:42:19 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:19 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:19 spla-repro go-librespot[14430]: go-librespot daemon starting... Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=debug msg="app state loaded" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+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 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+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 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=info msg="zeroconf server listening on port 34123" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=debug msg="obtained new client token: AAHzNe7xe38gM3TiRlZp54YmgaE5ZNckMVcIitPYAZwTmysPfRVWBH2+ANDwxBst5Rv3MWsjxRHzQYpX1YdpQ7Akiv0JrjyT/NJzgBqdbUSfImhEh9if1fMD9QjTMdF9nkQ2duBAHFzpC9Ss948xv/EGWjpjJ2lAjCG26I5ZUJ6ErVlqlwrGfP9Z8Dtt54DeH4E0Z9pfT8TFroByCL7WgvGpErFa1FAfXznEeKhDV/T6H4MEQx9T//0vjg==" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=debug msg="completed challenge" Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=info msg="authenticated AP" username="4b*********************lb" Oct 02 08:42:20 spla-repro volumio[13681]: info: Initializing connection to go-librespot Websocket Oct 02 08:42:20 spla-repro go-librespot[14431]: time="2026-10-02T08:42:20+02:00" level=debug msg="new websocket client" Oct 02 08:42:20 spla-repro volumio[13681]: info: Connection to go-librespot Websocket established Oct 02 08:42:21 spla-repro go-librespot[14431]: time="2026-10-02T08:42: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 08:42:21 spla-repro volumio[13681]: info: Connection to go-librespot Websocket closed Oct 02 08:42:21 spla-repro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:42:21 spla-repro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:42:22 spla-repro volumio[13681]: info: CoreCommandRouter::volumioGetState Oct 02 08:42:22 spla-repro volumio[13681]: info: CorePlayQueue::getTrack 0 Oct 02 08:42:23 spla-repro volumio[13681]: info: Getting Spotify volume Oct 02 08:42:23 spla-repro volumio[13681]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 08:42:23 spla-repro volumio[13681]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:42:23 spla-repro volumio[13681]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 02 08:42:23 spla-repro volumio[13681]: errno: -111, Oct 02 08:42:23 spla-repro volumio[13681]: code: 'ECONNREFUSED', Oct 02 08:42:23 spla-repro volumio[13681]: syscall: 'connect', Oct 02 08:42:23 spla-repro volumio[13681]: address: '127.0.0.1', Oct 02 08:42:23 spla-repro volumio[13681]: port: 9879, Oct 02 08:42:23 spla-repro volumio[13681]: response: undefined Oct 02 08:42:23 spla-repro volumio[13681]: } Oct 02 08:42:23 spla-repro volumio[13681]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 08:42:24 spla-repro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 02 08:42:24 spla-repro systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:24 spla-repro systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:42:24 spla-repro go-librespot[14466]: go-librespot daemon starting... Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=debug msg="app state loaded" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42: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-gew4.spotify.com:80]" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+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 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+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 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=info msg="zeroconf server listening on port 34819" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=debug msg="obtained new client token: AAHfDvM/V1IJOMpo0tppRx22Zd5edf2tZoCC0FDj/yLUspO7l4iZ/NTLwpHZ2oHqftWRoZ2CClteY8e0JOhXjHsLOSXgi6DVI2z65BSNcCd7ryDpWDkgA1N2lY8TRD7hqsFudqb+xZqRYpg4ioOgXPgDmmJl6U4ChFnN2k7RL97yldKuEXmdpUUhZ1BDamxMAFVXkx5xYnE++IIfHmKXvJe86ndmDNWDm5+WFl4Ob+l2ZS9zZsCxmA07TQ==" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 02 08:42:24 spla-repro sudo[14478]: volumio : unable to resolve host spla-repro: System error Oct 02 08:42:24 spla-repro sudo[14478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 08:41' Oct 02 08:42:24 spla-repro sudo[14478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=debug msg="completed keyexchange" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=debug msg="completed challenge" Oct 02 08:42:24 spla-repro go-librespot[14467]: time="2026-10-02T08:42:24+02:00" level=info msg="authenticated AP" username="4b*********************lb" 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"