Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin multiroom to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin qobuzconnect to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 05 00:54:00 volumio volumio[27250]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 05 00:54:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Mar 05 00:54:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:00 volumio go-librespot[27672]: go-librespot daemon starting...
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=debug msg="app state loaded"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=info msg="zeroconf server listening on port 40935"
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=debug msg="obtained new client token: AABKYtv1zrHWqsxq7iKcLnZL6beFn+OO9n6QaaN5xPEJtT7U1VNfn/yfnnn8Li9xXLKt0kUrrXzym6NuD1KTvbTCaS7GzjXBXoQ3BddFfUaaGQvkH1SgXfqSgpSDY3bamN/K0wsbm7TahPGV9V+Z419BArmZt71SB8v3agZm0+6S0kK88ZcsFLeYAkIJYOv4MyBIgCiTwnp9JyJtjmaNOzq1SVllXzKKnYM48amFTdi87UXJVeIKu/pYaA=="
Mar 05 00:54:00 volumio go-librespot[27673]: time="2026-03-05T00:54:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:01 volumio go-librespot[27673]: time="2026-03-05T00:54:01+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:01 volumio go-librespot[27673]: time="2026-03-05T00:54:01+07:00" level=debug msg="completed challenge"
Mar 05 00:54:01 volumio go-librespot[27673]: time="2026-03-05T00:54:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:01 volumio volumio[27250]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 05 00:54:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:01 volumio volumio[27250]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 05 00:54:01 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:01 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:01 volumio volumio[27250]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 05 00:54:01 volumio volumio[27250]: info: MyVolumio login type: Token
Mar 05 00:54:01 volumio volumio[27250]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 05 00:54:01 volumio volumio[27250]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Mar 05 00:54:02 volumio volumio[27250]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 05 00:54:02 volumio volumio[27250]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 05 00:54:02 volumio volumio[27250]: info: Streaming services startup
Mar 05 00:54:02 volumio volumio[27250]: info: Starting Streaming Daemon
Mar 05 00:54:02 volumio sudo[27682]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 05 00:54:02 volumio sudo[27682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 05 00:54:02 volumio volumio[27250]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 05 00:54:02 volumio sudo[27682]: pam_unix(sudo:session): session closed for user root
Mar 05 00:54:02 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:02 volumio volumio[27250]: error: Cannot start Volumio Streaming Daemon
Mar 05 00:54:02 volumio volumio[27250]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 05 00:54:02 volumio volumio[27250]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 05 00:54:02 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:02 volumio volumio[27250]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Mar 05 00:54:03 volumio volumio[27250]: info: MyVolumio token set successfully
Mar 05 00:54:03 volumio volumio[27250]: info: MYVOLUMIO: Adding device
Mar 05 00:54:03 volumio volumio[27250]: info: MYVOLUMIO: Evaluating Server
Mar 05 00:54:03 volumio volumio[27250]: info: MyVolumio status changed
Mar 05 00:54:03 volumio volumio[27250]: info: Streaming services startup
Mar 05 00:54:03 volumio volumio[27250]: info: Starting Streaming Daemon
Mar 05 00:54:03 volumio volumio[27250]: info: Removing browser output: myVolumio user plan is not superstar
Mar 05 00:54:03 volumio volumio[27250]: info: Removing audio output:
Mar 05 00:54:03 volumio volumio[27250]: info: Stoppping Tunnel 1
Mar 05 00:54:04 volumio sudo[27710]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 05 00:54:04 volumio sudo[27710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 05 00:54:04 volumio sudo[27712]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Mar 05 00:54:04 volumio sudo[27712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 05 00:54:04 volumio 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.
Mar 05 00:54:04 volumio 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.
Mar 05 00:54:04 volumio sudo[27710]: pam_unix(sudo:session): session closed for user root
Mar 05 00:54:04 volumio 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.
Mar 05 00:54:04 volumio 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.
Mar 05 00:54:04 volumio volumio[27250]: error: Cannot start Volumio Streaming Daemon
Mar 05 00:54:04 volumio volumio[27250]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 05 00:54:04 volumio volumio[27250]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 05 00:54:04 volumio 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.
Mar 05 00:54:04 volumio 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.
Mar 05 00:54:04 volumio sudo[27712]: pam_unix(sudo:session): session closed for user root
Mar 05 00:54:04 volumio volumio[27250]: info: Remote SSH Stopped
Mar 05 00:54:04 volumio volumio[27250]: info: Setting Geolocation for MyVolumio to as1
Mar 05 00:54:04 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:04 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:04 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Mar 05 00:54:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:04 volumio go-librespot[27715]: go-librespot daemon starting...
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=debug msg="app state loaded"
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=info msg="zeroconf server listening on port 43679"
Mar 05 00:54:04 volumio volumio[27250]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=debug msg="obtained new client token: AABD7Mmv2N4EhtLwwpEF8R0vJVKwTCycE+oFEBIhAfNIOMCqC0/0S1XvNmfaybnya7zcvrmnXXnrx2ZQCrglica0KdEEXgsTylZoxdZMGCIXmPRj1kmNKxou2DD1x87TKcUdEh0SGGC/WK1ucavdkPV/qUUwE824P6yXrD4ZFPDyJWkE2dgZkRouZRC1tJeW34z7kySWe3jMN2C+mJcK93FhGl9X0uxHjLC3h+JQVeCMo35gRodgDg6ftA=="
Mar 05 00:54:04 volumio go-librespot[27716]: time="2026-03-05T00:54:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:05 volumio go-librespot[27716]: time="2026-03-05T00:54:05+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:05 volumio go-librespot[27716]: time="2026-03-05T00:54:05+07:00" level=debug msg="completed challenge"
Mar 05 00:54:05 volumio go-librespot[27716]: time="2026-03-05T00:54:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:05 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:05 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:05 volumio volumio[27250]: info: Updating MyVolumio device info
Mar 05 00:54:05 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:05 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:05 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:05 volumio volumio[27250]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 05 00:54:08 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:08 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Mar 05 00:54:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:08 volumio go-librespot[27725]: go-librespot daemon starting...
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=debug msg="app state loaded"
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=info msg="zeroconf server listening on port 41181"
Mar 05 00:54:08 volumio volumio[27250]: info: MYVOLUMIO: Adding device
Mar 05 00:54:08 volumio volumio[27250]: info: MYVOLUMIO: Evaluating Server
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=debug msg="obtained new client token: AABl4VBRuPVupx9LX+3I8SqNCMqwiTW7MbaIEYYp2aXEMKbLrsRhn1tm0lmWZ1rjajxaNQyrtjA3uffbKS01FNqoeTbf9fHxwbb0nc/b4vjeRRZUzE/O8nIO/69QSuuFNci7R+ORreom9xYmzGvLC5Ocv/9s2gwQ5S0wt4CYG+vagCk+rD+KgBtB/9JA1bkNXQ0+xiyGfR/6KMHY1OvXGukKaUsVkxPBMjy7f99O18gsXJ6S57BlEUSkzA=="
Mar 05 00:54:08 volumio go-librespot[27726]: time="2026-03-05T00:54:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:09 volumio go-librespot[27726]: time="2026-03-05T00:54:09+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:09 volumio go-librespot[27726]: time="2026-03-05T00:54:09+07:00" level=debug msg="completed challenge"
Mar 05 00:54:09 volumio go-librespot[27726]: time="2026-03-05T00:54:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:11 volumio volumio[27250]: info: Setting Geolocation for MyVolumio to as1
Mar 05 00:54:11 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:11 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:11 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:11 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:11 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:11 volumio volumio[27250]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 05 00:54:12 volumio volumio[27250]: info: Updating MyVolumio device info
Mar 05 00:54:12 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:12 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:12 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Mar 05 00:54:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:12 volumio go-librespot[27816]: go-librespot daemon starting...
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="app state loaded"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:12 volumio volumio[27250]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=info msg="zeroconf server listening on port 44525"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="obtained new client token: AAAQARowimNSov2yPr3R3WfgGa0a8RoVFoUYjIfSaFD4Tld32dZIX7p/rFHOEZ3qGegJVJ/iJ1daxx+fjiEiDSnbbipFCck0Q5ZE0Ci1YyE3tjrBOM5pC7hzlpXK83yU9cBWSmP72LYGKztu/MLRGipfV+rkHxECfo2/r0flJ9WALL55ecBQCVxfG50LKRKZTBm2YucQuuuYxbdOVVle/Q63AKZZfyUyDYAKvPxX3oxaPYkru+34ydy6IQ=="
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:12 volumio go-librespot[27817]: time="2026-03-05T00:54:12+07:00" level=debug msg="completed challenge"
Mar 05 00:54:13 volumio go-librespot[27817]: time="2026-03-05T00:54:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:14 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:14 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:16 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 05 00:54:16 volumio volumio[27250]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Mar 05 00:54:16 volumio volumio[27250]: info: CoreCommandRouter::volumioGetState
Mar 05 00:54:16 volumio volumio[27250]: info: CorePlayQueue::getTrack 0
Mar 05 00:54:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Mar 05 00:54:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:16 volumio go-librespot[27824]: go-librespot daemon starting...
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="app state loaded"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=info msg="zeroconf server listening on port 39835"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="obtained new client token: AADooaYJIl7qqdbP3TsMdz+7I5IfPR5jPqe1ALwJCVzp54A2tVxNvQK26Z56f6+/bzDDOfUSwska90mF8eR6DjvsHJFIOY5NgxduLashTXCgfrOVKfPGs7ALaK5AZcE49djdjlpunvYlhZpCzpiPRQRwZN9lGQzKBGufz/E2e0wYW+yuzEngDESwj/KNXxbSfvVTK7mGxQLgtmIJF0Qs5cS6Zw1SRQHXCBMwq9M6Q3DBbMJ/n0o8Pk0GTQ=="
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:16 volumio go-librespot[27825]: time="2026-03-05T00:54:16+07:00" level=debug msg="completed challenge"
Mar 05 00:54:17 volumio go-librespot[27825]: time="2026-03-05T00:54:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:17 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:17 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:17 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 05 00:54:17 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:17 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 05 00:54:17 volumio volumio-remote-updater[675]: Test mode disabled
Mar 05 00:54:17 volumio volumio-remote-updater[675]: Alpha mode disabled
Mar 05 00:54:17 volumio volumio-remote-updater[675]: Alpha legacy test mode disabled
Mar 05 00:54:17 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 05 00:54:18 volumio volumio[27250]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for CPU utilization spike
\nFix for triggerhappy input permission
\n
\nNEW ADDITIONS
\n\nAdd BLE onboarding plugin
\nEnable vc4-kms-v3d for all compatible RPi models
\nUpdate Tidal SDK
\n\n","title":"Update v4.096","updateavailable":true}
Mar 05 00:54:18 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 05 00:54:20 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:20 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Mar 05 00:54:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:20 volumio go-librespot[27852]: go-librespot daemon starting...
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="app state loaded"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=info msg="zeroconf server listening on port 44017"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="obtained new client token: AACN8Dd2LTLRr+5pyCBaBNBQVkPVdu7o6rW8ssamRrg0V1RQB/n9cytZiSEFdCyjh+y6noiDPpngoJuCfl2qz3jq2d9EZ6FUFqNyUqf1MD3yYHZzvC1ChlTICspFRX6UD7G4cHwceoex9lVC+2WolNL2LOeeRXP/quXdI5IOldvk6uZg4cl8DLb91ND259MthXN00BVM2FKuq3P+mpIP0jKjLrkoIB/ulKTie13LXefsvvZ+rZpMXyWe8A=="
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:20 volumio go-librespot[27853]: time="2026-03-05T00:54:20+07:00" level=debug msg="completed challenge"
Mar 05 00:54:21 volumio go-librespot[27853]: time="2026-03-05T00:54:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 05 00:54:21 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 05 00:54:22 volumio volumio[27250]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Mar 05 00:54:22 volumio volumio[27250]: info: Completed starting MyVolumio Plugin
Mar 05 00:54:22 volumio volumio[27250]:  [Metrics]  CommandRouter:  44s 505.04ms 
Mar 05 00:54:22 volumio volumio[27250]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 05 00:54:22 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 00:54:22 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 05 00:54:22 volumio volumio[27250]: info: CoreCommandRouter::Close All Modals sent
Mar 05 00:54:22 volumio volumio[27250]: info: CoreCommandRouter::Close All Modals sent
Mar 05 00:54:23 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 05 00:54:23 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 05 00:54:23 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 05 00:54:23 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:23 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Mar 05 00:54:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:24 volumio go-librespot[27865]: go-librespot daemon starting...
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=debug msg="app state loaded"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=info msg="zeroconf server listening on port 36645"
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=debug msg="obtained new client token: AAAzi/clyeDAEpd4M56E1//+uUkP4lx7HS809nrO1LvHzUgwGOzicsFCUb6+cOB6rBVpgtVxYQCFKG7pHbD6uC/NDakjbwndO6riKeNUPS6d0YwYyROC4cDQDlwxhF0MY8TxYirVLuusMGxykWXUb4rua+xj6ybQ92BSr7Yz5QJ4CLGTEzyK3u4ccQMP9GR/WmoHDzZTndGZDtFaXYXCMdOOQmSEAfFK0XUtvWEwM6exzaVlVDKZNZSaIg=="
Mar 05 00:54:24 volumio go-librespot[27866]: time="2026-03-05T00:54:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:25 volumio go-librespot[27866]: time="2026-03-05T00:54:25+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:25 volumio go-librespot[27866]: time="2026-03-05T00:54:25+07:00" level=debug msg="completed challenge"
Mar 05 00:54:25 volumio go-librespot[27866]: time="2026-03-05T00:54:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:26 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:26 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Mar 05 00:54:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:28 volumio go-librespot[27916]: go-librespot daemon starting...
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=debug msg="app state loaded"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=info msg="zeroconf server listening on port 35811"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=debug msg="obtained new client token: AABUVR+12+i4oPT2NK3n2TvtXnU2ZF35dxoUDS4FPyEOMhGYntH9BbMQO5sURhm9qn+ICabLxRtz5cMO5kVAEqY+9toZcHNPtQRx0Y2DR1jYpPiX9Joupfsdkj4AVNo2wmvsUohgh36mTOBSFU0NEYBnJoPlalY/oOi2WBD3pzFwskCVZ5Sg9cL0APFbx8jYNJtRdtzbOFyPyIaU2xSh/UUjj5E0i56cA4cTjj16Y4Q9qA0QNk+yVbe/zw=="
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused"
Mar 05 00:54:28 volumio go-librespot[27917]: time="2026-03-05T00:54:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:443"
Mar 05 00:54:29 volumio go-librespot[27917]: time="2026-03-05T00:54:29+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:29 volumio go-librespot[27917]: time="2026-03-05T00:54:29+07:00" level=debug msg="completed challenge"
Mar 05 00:54:29 volumio volumio[27250]: info: BOOT COMPLETED
Mar 05 00:54:29 volumio go-librespot[27917]: time="2026-03-05T00:54:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:29 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:29 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:32 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:32 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Mar 05 00:54:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:32 volumio go-librespot[27938]: go-librespot daemon starting...
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=debug msg="app state loaded"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=info msg="zeroconf server listening on port 35155"
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=debug msg="obtained new client token: AABj8uqZWEcys3gUJvzOCg6iml1qN0cuNDbvTRoc530DJNQtsZ4XxMBD0pwZoNyc0AqhgZPPof4yH1SRhrtiwhjbKcscaOfQ5rVTQwblmrV96ONW7599Lh0tPWtPrnNsvLnEsGK4ymU1AH3UBta0DBcYJmYPyUnB84KU7/oqtURNdcUSPGvwot72yOQvzpioOvJ0/c70BjbOG0o4fpTLLPtV87bFgIKhM2Z8QOP3vMX90NsOgS7g9b6E+A=="
Mar 05 00:54:32 volumio go-librespot[27939]: time="2026-03-05T00:54:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:33 volumio go-librespot[27939]: time="2026-03-05T00:54:33+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:33 volumio go-librespot[27939]: time="2026-03-05T00:54:33+07:00" level=debug msg="completed challenge"
Mar 05 00:54:33 volumio go-librespot[27939]: time="2026-03-05T00:54:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:35 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:35 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Mar 05 00:54:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:36 volumio go-librespot[27948]: go-librespot daemon starting...
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="app state loaded"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=info msg="zeroconf server listening on port 43151"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="obtained new client token: AACPpzpjsQQkiH1AovnzKEFJHids5d0x1ZSllP3kP1R2GwKCuCY1hLZElrWVQG+SZlH+FYBxHHNHo3i33rEB5CIZQhG6kE/yiCXYmm3b0XrEizosG/x+dMLHNLjXG97+OcQI73IpO3bzCKgVuOJrOl+f2TpRBjLGh+hk2JLFdHpOnrGKV9DHKa3T51J78pJcICD4WMipQ0rAiTg8k9T+mmni5V4m/wBP9M0xtoO68w6Uvyp5BTqi5DvNUw=="
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:36 volumio go-librespot[27949]: time="2026-03-05T00:54:36+07:00" level=debug msg="completed challenge"
Mar 05 00:54:37 volumio go-librespot[27949]: time="2026-03-05T00:54:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:38 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:38 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Mar 05 00:54:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:40 volumio go-librespot[27976]: go-librespot daemon starting...
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=debug msg="app state loaded"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=info msg="zeroconf server listening on port 43997"
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=debug msg="obtained new client token: AACytrwN3bYkYvNyaZhvO7A4ixLvBRejcbymqh0E9/5rmFHaX3w8+Z0VAjsolWURD1eP1LLbgavT/ZVEbWeS34Qj+K5OeNYwuuo6+gs4/I6IcXGR1K6XHti8N2lnmG3KF2aXAJkZl0U37Rt31EtCyAaM3yIinlhVQtOIfnFd97YxXMOhQAmNrL8eyxVpTAZ50JyiDGDaKAcdlU2MU8IHgOXnpJEAr8CJJZIk1X4RDnSvgIVpai7bZzsu6A=="
Mar 05 00:54:40 volumio go-librespot[27983]: time="2026-03-05T00:54:40+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused"
Mar 05 00:54:41 volumio go-librespot[27983]: time="2026-03-05T00:54:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:443"
Mar 05 00:54:41 volumio go-librespot[27983]: time="2026-03-05T00:54:41+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:41 volumio go-librespot[27983]: time="2026-03-05T00:54:41+07:00" level=debug msg="completed challenge"
Mar 05 00:54:41 volumio go-librespot[27983]: time="2026-03-05T00:54:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:41 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:41 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:44 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:44 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Mar 05 00:54:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:44 volumio go-librespot[28028]: go-librespot daemon starting...
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=debug msg="app state loaded"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=info msg="zeroconf server listening on port 38743"
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=debug msg="obtained new client token: AAA8k8oP1ItANDi9sIzZ8hJLW0lJCgjNG07qvT1S7IUSGBJoRzP6RsYryTTcyqgSFTO29cnKKFMRFTsuMQ+enRy8TWC/nR1BO/OjKNsBsWEodZ9UnXqx33bBK68U8xW0Z4aA2YvAU35t/hvpnWE12yF6m975CMcGH0QuhSwqSVcYtCf4sGGA4lboNj0JmU8uymvUmvR4UHXiawoNPjP6WlSdgCLnjP+NtjytOVnTEhkK1qzchG5HtxkY1A=="
Mar 05 00:54:44 volumio go-librespot[28029]: time="2026-03-05T00:54:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:45 volumio go-librespot[28029]: time="2026-03-05T00:54:45+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:45 volumio go-librespot[28029]: time="2026-03-05T00:54:45+07:00" level=debug msg="completed challenge"
Mar 05 00:54:45 volumio go-librespot[28029]: time="2026-03-05T00:54:45+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:47 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:47 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:47 volumio volumio[27250]: info: UPDATER: Scheduling automatic update
Mar 05 00:54:47 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Mar 05 00:54:47 volumio volumio[27250]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Mar 05 00:54:47 volumio volumio[27250]: info: UPDATER: Auto update will take place at: Fri Mar 06 2026 04:30:50 GMT+0700 (Indochina Time)
Mar 05 00:54:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Mar 05 00:54:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:48 volumio go-librespot[28037]: go-librespot daemon starting...
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="app state loaded"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=info msg="zeroconf server listening on port 46709"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="obtained new client token: AADHNZZ+1DSO7vP6nvtnVYAeXoYvIU20tqTDoTZyfI4nEwF6Pt4QGSFgr3Fz5+sABXZtnotEHQVFpraV+MkSN16lp1nZG5mkTuhhrxnAXncuGY7IOSnrdn2WNmulkRyfSbns82zlOa8rw93RuAxdqQnaKCpxa9vXuJpfVWHvdpxlyEj9R6b6AWJinFXntdDr7lCCj9ff0W4pNeYwMMzTLSnedGjVrScG/ZL3cX52Fe5NNabChzoBn5aImA=="
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:48 volumio go-librespot[28038]: time="2026-03-05T00:54:48+07:00" level=debug msg="completed challenge"
Mar 05 00:54:49 volumio go-librespot[28038]: time="2026-03-05T00:54:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:50 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:50 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23.
Mar 05 00:54:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:52 volumio go-librespot[28059]: go-librespot daemon starting...
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="app state loaded"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=info msg="zeroconf server listening on port 34425"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="obtained new client token: AACaXSgo0Gb7TA4ACABWMtB6mNLMTEbQnx8eFAD6eFvFik6na9VNn03nHg4ZlLM8q+tZJ0O9rtaqaPmVoPhQPv4wXsDg8f7AKes4N9oLaAfQEk8R2E6Ew2cfp4UOz/eseSfavwEW8WPIBnU6XdJR3JRPt/wHhYPq0FpSEwo/kNrrJH3dFriIfO/yM6elSgpEVVIGGRzmBKFk8nUpOC4BOkzON6vyM+ZOIVSYDgPFo8lv0T+cSNceyCbkLQ=="
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=debug msg="completed challenge"
Mar 05 00:54:52 volumio go-librespot[28060]: time="2026-03-05T00:54:52+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:53 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:53 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24.
Mar 05 00:54:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:56 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:56 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:54:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:54:56 volumio go-librespot[28086]: go-librespot daemon starting...
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="app state loaded"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=info msg="zeroconf server listening on port 41353"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="obtained new client token: AACsEs70+GCacaG6mzi1J7nMYTPqZyAm87T0mMzEn4ozClsnQ7OE6vQnhb8L5A6n4OTJqjLLwqOKN+x7LbBklk5SXQ/wmKPujXJKc8ljxpgu3L/ixht/oPYWIYHjaZRLSL+Dp1nGKumfFmpTuuE6nzooLuh3rbeNiXTnns3GXAURQfuMVNYbp/FN3t3d3FJaXDQKejboJwlCPa2U6D/bEbDc5+wbVOekJUwunH8oZxrlia1sTpClyNHO1A=="
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="completed keyexchange"
Mar 05 00:54:56 volumio go-librespot[28087]: time="2026-03-05T00:54:56+07:00" level=debug msg="completed challenge"
Mar 05 00:54:57 volumio go-librespot[28087]: time="2026-03-05T00:54:57+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:54:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:54:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:54:59 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:54:59 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25.
Mar 05 00:55:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:00 volumio go-librespot[28135]: go-librespot daemon starting...
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=debug msg="app state loaded"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=info msg="zeroconf server listening on port 42797"
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=debug msg="obtained new client token: AACucargRy4vBt4qVF746l6zkgsSYl3F1TnDWOzH+G558tGULRQfkNFVoLOEQeQLaG05DwCpKiN7XI1YIA0trTejYi9KCkWHv5xl2DL4KgF++UvlFu5Sp/pDTeR4bGqA6GlxaMljiS99ZSJ6Xh4TiLAO278XYE7CHYkIeXAoQ3rvZJR0MJZDle24lgy9GJUJQkMImpl6f0HbB8CR6gUGG4YDG3Ah4NKC4Kj01XremmV1wPTOMR8DqEDR2g=="
Mar 05 00:55:00 volumio go-librespot[28136]: time="2026-03-05T00:55:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:01 volumio go-librespot[28136]: time="2026-03-05T00:55:01+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:01 volumio go-librespot[28136]: time="2026-03-05T00:55:01+07:00" level=debug msg="completed challenge"
Mar 05 00:55:01 volumio go-librespot[28136]: time="2026-03-05T00:55:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:02 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:02 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26.
Mar 05 00:55:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:04 volumio go-librespot[28144]: go-librespot daemon starting...
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=debug msg="app state loaded"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=info msg="zeroconf server listening on port 38569"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=debug msg="obtained new client token: AAAywY5mEzH+skOKSAAUA5HqsMYfB47dKrdGJ/jH3J9TwRUncKnq2/1twwxce2M3ZD9BhFZktfAqMCHBucsZizUAeTgdUlmXMnveg91V0nJ+83/onNnXN5PIc9JntmMFEhWnpn/D0o8FCsvgNr+BUyRzLPGO0bH3yc8+rR9ETpPedftI3lrMpPmxX0OkgQad74UQgEjQDR79HlCaNFm5IWgoKGiKsVk+EgmuhaXRjwM3fZLwah9MScU4pA=="
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused"
Mar 05 00:55:04 volumio go-librespot[28145]: time="2026-03-05T00:55:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:443"
Mar 05 00:55:05 volumio go-librespot[28145]: time="2026-03-05T00:55:05+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:05 volumio go-librespot[28145]: time="2026-03-05T00:55:05+07:00" level=debug msg="completed challenge"
Mar 05 00:55:05 volumio go-librespot[28145]: time="2026-03-05T00:55:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:05 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:05 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27.
Mar 05 00:55:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:08 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:08 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:08 volumio go-librespot[28153]: go-librespot daemon starting...
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="app state loaded"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=info msg="zeroconf server listening on port 34801"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="obtained new client token: AAD9OM+SxnI6GZJixCac9yTllBjFSUVS4uvRTiOHGpLH/f0DNVs6W4UTU+qTgOZsf+NE/n6GsPqPZ5Po7CR9AvkoxhiTpLjqfnqx06Ue9J75dEAPIBr+PxvHNmMjZLKDvK1uNH+StuZoVHgtLBkLjeiZDY42DwB+MfWrvkVS37R8tfbisnfBed3Qw1YSBhuQ+FVG8mz9eYDWa4qrr5UDtvuQip3K9zFUzk8+jl94+hR4np6uqsXh38v3BQ=="
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:08 volumio go-librespot[28154]: time="2026-03-05T00:55:08+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:09 volumio go-librespot[28154]: time="2026-03-05T00:55:09+07:00" level=debug msg="completed challenge"
Mar 05 00:55:09 volumio go-librespot[28154]: time="2026-03-05T00:55:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:11 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:11 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28.
Mar 05 00:55:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:12 volumio go-librespot[28218]: go-librespot daemon starting...
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="app state loaded"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=info msg="zeroconf server listening on port 38891"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="obtained new client token: AAA+IbBi+RlApv9RjJPzX3Z53eP3c0YsIh3Yn5TUnaXTNG/k2+EOdo06RYZLBK/VLQckaT3yhzzJGmZEOZypZPYgSZXL9o8HMhedE8IdmkOrd73ilZgcpBZ0NJ6w4bxvrlUM60VOf+lOnhQQdEqqZapIc4SuyKJSte5D+n4WNReNDPkY2snDj2dzOfC9BdkhCcWHH27u6CtClawnRELRuyJDrzFxkxVh+c/Hut9u0oxxUREMBkMqeBuGkA=="
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:12 volumio go-librespot[28222]: time="2026-03-05T00:55:12+07:00" level=debug msg="completed challenge"
Mar 05 00:55:13 volumio go-librespot[28222]: time="2026-03-05T00:55:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:14 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:14 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29.
Mar 05 00:55:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:16 volumio go-librespot[28229]: go-librespot daemon starting...
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="app state loaded"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=info msg="zeroconf server listening on port 41355"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="obtained new client token: AABncDTBcZFuGOdPjmy+x+PD6XlKt/EjCjBtBRr+Y79Irz2kh4byXDGz/Q5H7uuJYWsGrkMXuD+pAUoqZBRX2EhGwV8sBSJWzxB8BDpO0FdbnKlXnEEvhhtgl3n+anjETVlyrw/y0QrcUP46ZLQsqFt4Hu4Z22o4bgNqk5CffxKDzhGH2IpGeBGNl+7ToUHeMkLmWiZU/0TRLHmqWoNYrkxMX6FoMzS6PyCWU0MBZO0Gh+v/rSphmVESmQ=="
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:16 volumio go-librespot[28230]: time="2026-03-05T00:55:16+07:00" level=debug msg="completed challenge"
Mar 05 00:55:17 volumio go-librespot[28230]: time="2026-03-05T00:55:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:17 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:17 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30.
Mar 05 00:55:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:20 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:20 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:20 volumio go-librespot[28254]: go-librespot daemon starting...
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="app state loaded"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=info msg="zeroconf server listening on port 36761"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="obtained new client token: AADxN/qCt/XSCh7Zy4PIASpTU6vtCJYpHXk+vzhTkgH9m3qL97nzUansRLh52pGCjEpZhq3aFDt7JMB7a4P2fFol6Mbwh6GeWcjaRezs0Sk67PB1OX5Zwlg5ZbLApkPL++V0KpfZ9t9S236Q1coUg30WOPsHsWRtRdcZFlRmhh8DPekaCHElASb3Jms2nQURC7BtN+yMqbfCXWRKs8LzMTM6H7sUXA00Feo+t5Q925Rk6FiSjKfylFajcg=="
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:20 volumio go-librespot[28255]: time="2026-03-05T00:55:20+07:00" level=debug msg="completed challenge"
Mar 05 00:55:21 volumio go-librespot[28255]: time="2026-03-05T00:55:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:23 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:23 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31.
Mar 05 00:55:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:24 volumio go-librespot[28262]: go-librespot daemon starting...
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=debug msg="app state loaded"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=info msg="zeroconf server listening on port 35943"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=debug msg="obtained new client token: AACsRQtRa+ax1+b8xs6BREw1iPvzHNqweUztFDyYEDr/I2ZM6UHr07vhlD6CUe6fOb0L4YC63lwn4IU4KGgsfJYsxfKgLCshxn6ApUPu1h9oC8OyFEqzxh9ZEOO8uMS6qlkAxiNXxt3Kef2JAe3XZxyX95bvseouronI3I+OMQFdkffzOtJuP7L6MlO+o0IZ88dRJQ2AFFX0cjfEsKvKb0juzHKh3bJ0YR2Xrwi+QdFPYayMHltfhqPFuQ=="
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused"
Mar 05 00:55:24 volumio go-librespot[28263]: time="2026-03-05T00:55:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:443"
Mar 05 00:55:25 volumio go-librespot[28263]: time="2026-03-05T00:55:25+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:25 volumio go-librespot[28263]: time="2026-03-05T00:55:25+07:00" level=debug msg="completed challenge"
Mar 05 00:55:25 volumio go-librespot[28263]: time="2026-03-05T00:55:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:26 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:26 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:26 volumio nmbd[1053]: [2026/03/05 00:55:26.755905,  0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Mar 05 00:55:26 volumio nmbd[1053]:   query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>.
Mar 05 00:55:26 volumio nmbd[1053]:   This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237.
Mar 05 00:55:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32.
Mar 05 00:55:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:28 volumio go-librespot[28317]: go-librespot daemon starting...
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="app state loaded"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=info msg="zeroconf server listening on port 35653"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="obtained new client token: AACK/HCx773+FXt2ELArzEA9aBaHexmPcnRKtU1cREwSLq+EDUiZkQ4nz3QeoY4Is2L/tYHjLsf9kLF2jO41dlpZnqjIf4UebXJO45Dc+em26r952wo138ngxgKLP0W8Ate/JNX8n51K+MmmAvYDZMj+40SzeSruuU/6ALdcpCnUQxEzMBbEzbpwuBW6W1v87eezs/Fd73oSWWGqp71L7NZASfe9WLvzp40Sr2QDn2U2YmLF1CJb3gGhAQ=="
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:28 volumio go-librespot[28318]: time="2026-03-05T00:55:28+07:00" level=debug msg="completed challenge"
Mar 05 00:55:29 volumio go-librespot[28318]: time="2026-03-05T00:55:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:29 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:29 volumio volumio[27250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Mar 05 00:55:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 05 00:55:32 volumio go-librespot[28340]: go-librespot daemon starting...
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=info msg="running go-librespot 0.4.0"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="app state loaded"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 05 00:55:32 volumio volumio[27250]: info: Initializing connection to go-librespot Websocket
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="new websocket client"
Mar 05 00:55:32 volumio volumio[27250]: info: Connection to go-librespot Websocket established
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=info msg="zeroconf server listening on port 41093"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="obtained new client token: AADiOVj67/AA3wAYaIXKeRpoZDzM3ON/L8kBff91FVgzJyUok39dNGPw0MXKZ5hhgu1RSPRqeLVr21fSL3scA+rwNl4w+YT29yynrodVF4FepL6fC9Ord1frjScKmYbnDF523GvJ5UNxpq/N2SchvGS7wRLG9gPTjrXFDNmsUurgGSD6vuyLBRBC5I/OiEMirIcn7LGYkRQEpJL5bDiZKwrTB+dc0A3QBH/duy9ULdbxfrjN5NT2MQYifQ=="
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="completed keyexchange"
Mar 05 00:55:32 volumio go-librespot[28341]: time="2026-03-05T00:55:32+07:00" level=debug msg="completed challenge"
Mar 05 00:55:33 volumio go-librespot[28341]: time="2026-03-05T00:55:33+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 05 00:55:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 05 00:55:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 05 00:55:33 volumio volumio[27250]: info: Connection to go-librespot Websocket closed
Mar 05 00:55:35 volumio volumio[27250]: info: Getting Spotify volume
Mar 05 00:55:35 volumio volumio[27250]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 05 00:55:35 volumio volumio[27250]: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 05 00:55:35 volumio volumio[27250]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Mar 05 00:55:35 volumio volumio[27250]:   errno: -111,
Mar 05 00:55:35 volumio volumio[27250]:   code: 'ECONNREFUSED',
Mar 05 00:55:35 volumio volumio[27250]:   syscall: 'connect',
Mar 05 00:55:35 volumio volumio[27250]:   address: '127.0.0.1',
Mar 05 00:55:35 volumio volumio[27250]:   port: 9879,
Mar 05 00:55:35 volumio volumio[27250]:   response: undefined
Mar 05 00:55:35 volumio volumio[27250]: }
Mar 05 00:55:35 volumio volumio[27250]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 05 00:55:36 volumio sudo[28364]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-05 00:54'
Mar 05 00:55:36 volumio sudo[28364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"