Dec 05 21:35:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33.
Dec 05 21:35:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:01 volumio go-librespot[16121]: go-librespot daemon starting...
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="app state loaded"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=info msg="zeroconf server listening on port 44041"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="obtained new client token: AAAChGyPi6lKHSi2OGpo0wCQuPFkiucG235+SVE8VTVm9JQAPjUsssysqgZLcVlzKb8ACLILh6M/Vl+QyQ1zy5fbsWVFVIDChfkTwF8TkEFpyErbAZd1RxiqquQhzn3rIJY/YpwW/om6d5yVGBscp2WbNA/S2Hcg87ycPjgYGKJ+qzohl5hRCiFw+l3S+Uq1IRlyyIyjt9jHoRT5/LYQlEa5D/YXs98IRh9H8hmc2WZQN967P0vHGBYo/w=="
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=debug msg="completed challenge"
Dec 05 21:35:01 volumio go-librespot[16122]: time="2025-12-05T21:35:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:02 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin multiroom to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 05 21:35:02 volumio volumio[15646]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 05 21:35:03 volumio volumio[15646]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 05 21:35:03 volumio volumio[15646]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 05 21:35:03 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:03 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:03 volumio volumio[15646]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 05 21:35:03 volumio volumio[15646]: info: MyVolumio login type: Token
Dec 05 21:35:03 volumio volumio[15646]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 05 21:35:03 volumio volumio[15646]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 05 21:35:04 volumio volumio[15646]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 05 21:35:04 volumio volumio[15646]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 05 21:35:04 volumio volumio[15646]: info: Streaming services startup
Dec 05 21:35:04 volumio volumio[15646]: info: Starting Streaming Daemon
Dec 05 21:35:04 volumio sudo[16144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 05 21:35:04 volumio sudo[16144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:35:04 volumio volumio[15646]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 05 21:35:04 volumio sudo[16144]: pam_unix(sudo:session): session closed for user root
Dec 05 21:35:04 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:04 volumio volumio[15646]: error: Cannot start Volumio Streaming Daemon
Dec 05 21:35:04 volumio volumio[15646]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 05 21:35:04 volumio volumio[15646]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 05 21:35:04 volumio volumio[15646]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 05 21:35:04 volumio volumio[15646]: info: MyVolumio token set successfully
Dec 05 21:35:04 volumio volumio[15646]: info: MYVOLUMIO: Adding device
Dec 05 21:35:04 volumio volumio[15646]: info: MYVOLUMIO: Evaluating Server
Dec 05 21:35:05 volumio volumio[15646]: info: MyVolumio status changed
Dec 05 21:35:05 volumio volumio[15646]: info: Streaming services startup
Dec 05 21:35:05 volumio volumio[15646]: info: Starting Streaming Daemon
Dec 05 21:35:05 volumio volumio[15646]: info: Removing browser output: myVolumio user plan is not superstar
Dec 05 21:35:05 volumio volumio[15646]: info: Removing audio output:
Dec 05 21:35:05 volumio volumio[15646]: info: Stoppping Tunnel 1
Dec 05 21:35:05 volumio sudo[16171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 05 21:35:05 volumio sudo[16171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:35:05 volumio sudo[16173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 05 21:35:05 volumio sudo[16173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:35:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34.
Dec 05 21:35:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:05 volumio go-librespot[16176]: go-librespot daemon starting...
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="app state loaded"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:05 volumio sudo[16171]: pam_unix(sudo:session): session closed for user root
Dec 05 21:35:05 volumio volumio[15646]: error: Cannot start Volumio Streaming Daemon
Dec 05 21:35:05 volumio volumio[15646]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 05 21:35:05 volumio volumio[15646]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 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.
Dec 05 21:35:05 volumio sudo[16173]: pam_unix(sudo:session): session closed for user root
Dec 05 21:35:05 volumio volumio[15646]: info: Remote SSH Stopped
Dec 05 21:35:05 volumio volumio[15646]: info: Setting Geolocation for MyVolumio to us4
Dec 05 21:35:05 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:05 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:05 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:05 volumio volumio[15646]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=info msg="zeroconf server listening on port 45209"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="obtained new client token: AACKqg3CHXcxr2wmvuD08qRfhQnN6DAQohzgG08nXzHIWUokL68jy/wTP0bmw2SNAZWRd5jVRW3GfkPRGjFKBtyOGdWUDMQI/qbaMGIsjnZdbgH/qgA7vOhBFVTwzeF2f26XbT+2Lx1kWpzzYjcmSHEnPTYU759R1ObMXDYaFeC037oLlnd9Nlq9NE7UNxPAm1DhoCJgsqvnbFC9DjFAbHq/ITuty+JXzuzmclyfTmN5Y+XpdwgW167tKg=="
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=debug msg="completed challenge"
Dec 05 21:35:05 volumio go-librespot[16177]: time="2025-12-05T21:35:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:06 volumio volumio[15646]: info: Updating MyVolumio device info
Dec 05 21:35:06 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:06 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:06 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:07 volumio volumio[15646]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 05 21:35:07 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:07 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35.
Dec 05 21:35:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:09 volumio go-librespot[16184]: go-librespot daemon starting...
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="app state loaded"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=info msg="zeroconf server listening on port 34525"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="obtained new client token: AAA4vHfNx60cmiq5+07Lyfu8hkuDdgj8SMDk1EY9p3iA90GvtfTJW29eBPjaBjaG9K9SxYbWVEF6DzfH6J5w4Ax1vOqxM8bQMXeAl6lMPxRYA4kjo54E7Kr1Z3bWeI7djpW6ZkYHj6ickI4rM9sW4GUm/ysSBjfbzbiH0m5t8MUFltgLDVOWdqad5U9MlrN7ovH9C5OpDORQVWrQNmjuNSDRIq9Y3stLrRpuPXGNCoCIRKT6rIPnyXeFRw=="
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=debug msg="completed challenge"
Dec 05 21:35:09 volumio go-librespot[16185]: time="2025-12-05T21:35:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:09 volumio volumio[15646]: info: MYVOLUMIO: Adding device
Dec 05 21:35:09 volumio volumio[15646]: info: MYVOLUMIO: Evaluating Server
Dec 05 21:35:09 volumio volumio[15646]: info: Setting Geolocation for MyVolumio to us4
Dec 05 21:35:09 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:09 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:09 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:10 volumio volumio[15646]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 05 21:35:10 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:10 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:10 volumio volumio[15646]: info: Updating MyVolumio device info
Dec 05 21:35:10 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:10 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:10 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:11 volumio volumio[15646]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 05 21:35:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36.
Dec 05 21:35:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:12 volumio go-librespot[16210]: go-librespot daemon starting...
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=debug msg="app state loaded"
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:12 volumio go-librespot[16211]: time="2025-12-05T21:35:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:13 volumio go-librespot[16211]: time="2025-12-05T21:35:13-06:00" level=info msg="zeroconf server listening on port 38533"
Dec 05 21:35:13 volumio go-librespot[16211]: time="2025-12-05T21:35:13-06:00" level=debug msg="obtained new client token: AAAXJ62Af2/icPQx0g9COVkCyyDphXJZqqmO+lC0p0kt2eVyFQDM/0rjBDHAmzGOgVavkkrvOlOq3lCACNPdkNN6dmoPXvNJa/1XiqXE2u5tLGUoPxDa2HeblNqWAMSvPS8Fu4tbTLKyNcqazn3JytaxYUexIWiUhsj2nkQ/g182j1D5UdFAR6CYoDoWR8LGTAbt4iI8Z/8u9HUlB+m8nDTOlC9WyCaKpFNXPcNX/eEYwF1m/M0qWuo="
Dec 05 21:35:13 volumio go-librespot[16211]: time="2025-12-05T21:35:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:13 volumio go-librespot[16211]: time="2025-12-05T21:35:13-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:13 volumio go-librespot[16211]: time="2025-12-05T21:35:13-06:00" level=debug msg="completed challenge"
Dec 05 21:35:13 volumio go-librespot[16211]: time="2025-12-05T21:35:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:13 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:13 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:15 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 05 21:35:15 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:15 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 05 21:35:15 volumio volumio-remote-updater[2353]: Test mode disabled
Dec 05 21:35:15 volumio volumio-remote-updater[2353]: Alpha mode disabled
Dec 05 21:35:15 volumio volumio-remote-updater[2353]: Alpha legacy test mode disabled
Dec 05 21:35:15 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 05 21:35:16 volumio volumio[15646]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Fix for TIDAL low quality (AAC) playback
\n
\n","title":"Update v4.072","updateavailable":true}
Dec 05 21:35:16 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 05 21:35:16 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:16 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37.
Dec 05 21:35:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:16 volumio go-librespot[16239]: go-librespot daemon starting...
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="app state loaded"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=info msg="zeroconf server listening on port 36207"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="obtained new client token: AACOhIxN9er+BOzscUyHoEeGX2VrW/HGsbeXj8gPfFuuMZTNv6T8cWmwmcVR5EurNLmtK2YfPnvKobvzeEkkXRlc8xRF+OXyOnyHMEVXL2/2vRCsBUIEsEvgQgD5C1/1TcKNbRoN1JTgit+D3uqPQfPr705v4y8c45KHzLOXEIxCv1cwMZzXN1TqyWW31VjyH98TAyJVWC6t0N/5ktttjolqBgwqiLEQSRA1h43VrO9H4LiIz6clThF25Q=="
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:16 volumio go-librespot[16240]: time="2025-12-05T21:35:16-06:00" level=debug msg="completed challenge"
Dec 05 21:35:17 volumio go-librespot[16240]: time="2025-12-05T21:35:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:18 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 05 21:35:18 volumio volumio[15646]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Dec 05 21:35:18 volumio volumio[15646]: info: CoreCommandRouter::volumioGetState
Dec 05 21:35:18 volumio volumio[15646]: info: CorePlayQueue::getTrack 0
Dec 05 21:35:19 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:19 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38.
Dec 05 21:35:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:20 volumio go-librespot[16247]: go-librespot daemon starting...
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="app state loaded"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=info msg="zeroconf server listening on port 36003"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="obtained new client token: AAC9EA5sqEyY+2amuENa6XINRIKlOp/RNxspTAP888TrJ3giwhLvxccp2WrjC23JHlSf37e1ILeOb0tc5HcESg4A22JHIJ8+V4F0UFrocN/nisB9M/YNBdEkIZUFer7j+AVFDBIRzYbgogt7PdeQHvCLqQgAo66zYedxuNEAXRVFnUvJrI2jzsUYJRvnSHMs+QnLTqZcCeU6TyncTH+4VnUVBh7Fn4CrEQ7KxoO8PTeD2dK96i+SaIfveg=="
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=debug msg="completed challenge"
Dec 05 21:35:20 volumio go-librespot[16248]: time="2025-12-05T21:35:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:22 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:22 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 05 21:35:23 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 05 21:35:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39.
Dec 05 21:35:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:23 volumio go-librespot[16270]: go-librespot daemon starting...
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="app state loaded"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:24 volumio volumio[15646]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 05 21:35:24 volumio volumio[15646]: info: Completed starting MyVolumio Plugin
Dec 05 21:35:24 volumio volumio[15646]: [Metrics] CommandRouter: 53s 708.93ms
Dec 05 21:35:24 volumio volumio[15646]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 05 21:35:24 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 21:35:24 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 21:35:24 volumio volumio[15646]: info: CoreCommandRouter::Close All Modals sent
Dec 05 21:35:24 volumio volumio[15646]: info: CoreCommandRouter::Close All Modals sent
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=info msg="zeroconf server listening on port 33203"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="obtained new client token: AADie07zhl5llWhocjMw3g6nSBIT391d/xEhJ/9X/SvQKPdqSoGXLgQBamYZ6r1wSXQcXhVCECCatT56gMT6F1ZxWo51AVE2e03qn+aK8YoaShmF0lHPxVotUJo1M72btISuiBda5VWXzvEescL8H3wyetn6AiosmgUYaqAL7mhr+y0SooCyAbdt90Ya8hzCvS4fndgpB/21Rdos1JMEoEkL/Mn5wXRMX/f3cryYhpQBTBRupLkUIe8ylw=="
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=debug msg="completed challenge"
Dec 05 21:35:24 volumio go-librespot[16271]: time="2025-12-05T21:35:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:25 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 05 21:35:25 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 05 21:35:25 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 05 21:35:25 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:25 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40.
Dec 05 21:35:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:27 volumio go-librespot[16284]: go-librespot daemon starting...
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=debug msg="app state loaded"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:27 volumio go-librespot[16285]: time="2025-12-05T21:35:27-06:00" level=info msg="zeroconf server listening on port 40813"
Dec 05 21:35:28 volumio go-librespot[16285]: time="2025-12-05T21:35:28-06:00" level=debug msg="obtained new client token: AABVI0bnRLx+fVqfZ8/Sc+ZSunJnDn/9TTEtB+g/goIamuc/HWlKbs9hHPYHdde9XgDFFMm1fw2si1380oIY+ZjQL1+im2e2/ci+8oiwY1E7wp0DRduMQu+QNToZt/oYyzYbWDiXmeesIdwLDcKBLhk0vuigUWNWzjKfSrtvZ+E5Jhv/vpg2MnOHe7Zj4VdxsQJpmtD58WHgYN4TxnNeTVGShOu6xzYLikIF+PyROhbhqwivF3w0n5o="
Dec 05 21:35:28 volumio go-librespot[16285]: time="2025-12-05T21:35:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:28 volumio go-librespot[16285]: time="2025-12-05T21:35:28-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:28 volumio go-librespot[16285]: time="2025-12-05T21:35:28-06:00" level=debug msg="completed challenge"
Dec 05 21:35:28 volumio go-librespot[16285]: time="2025-12-05T21:35:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:28 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:28 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:31 volumio volumio[15646]: info: BOOT COMPLETED
Dec 05 21:35:31 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:31 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41.
Dec 05 21:35:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:31 volumio go-librespot[16292]: go-librespot daemon starting...
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="app state loaded"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=info msg="zeroconf server listening on port 37181"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="obtained new client token: AAB9K8rnH6XSj/PmZWS3MRAsvcLSw/KeYNaxHmgH6x7vBBfGGdovriTSrWgce2erLxy4IA5FdGqhx1XECLPdfNaLOArBFNu/HAxwxM+XOSB/1anxDumld42pxyd93jLFL5srhYKUyDWJ9DUzaJDRIEp40iqwS+QCHAki6CFXjpxPmfmFiFOtgUGSPS9Yj4ftbqGgdvAHgAoFezy0Ig98T7dAdOvv4liqSOZq/imgp5h4IuxSq9YauUAdrg=="
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=debug msg="completed challenge"
Dec 05 21:35:31 volumio go-librespot[16293]: time="2025-12-05T21:35:31-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:34 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:34 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42.
Dec 05 21:35:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:35 volumio go-librespot[16317]: go-librespot daemon starting...
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="app state loaded"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=info msg="zeroconf server listening on port 36159"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="obtained new client token: AADM6IZvJ5qRoqw2k91bp927U+T60d+oqYUoLjZZdfP9esNm3bhWtxOIZc3IE4zgScMFM0DiduS/qcGiWe9DInMqCP6+P9CGU+xRfRVoqrI5a65muU/ucOcWBwcsmshchNhtIdlXArYEbLvJ/j6ts+YndhF4IpzA5WqYVBq15uGbFTU1IKMeANYUEHMp7lBTtBnVJ2zehwZK18N+OiTkWij5EuWhxCLLZUW26pEmeeE72tdJ1OJ76DrFkQ=="
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=debug msg="completed challenge"
Dec 05 21:35:35 volumio go-librespot[16318]: time="2025-12-05T21:35:35-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:37 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:37 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43.
Dec 05 21:35:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:38 volumio go-librespot[16325]: go-librespot daemon starting...
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="app state loaded"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=info msg="zeroconf server listening on port 36009"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="obtained new client token: AAC47ra/SZGUaIZhZYEhORZ9LciQ2FOZO1nFK5u5bHLaPkFp3IG+/zi+OSg/UMQJGBbFtd2sJbr1K7bE1C6glR/ehaT9dCPkyoVS4I+hdwr5/g+VLHgKjFla2ls8m9EYEv39NzcmXEkFc5v4dua3FkpeBs8yDbrPOLjgsJSRmxRhOSHSzv7so7WwNLeeKkQ2FSN9htVlgquSSwPT4+Jgp5u4ScW6Z4O2qtHr3zigCepsZHaTzEdCh6aHKA=="
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=debug msg="completed challenge"
Dec 05 21:35:39 volumio go-librespot[16326]: time="2025-12-05T21:35:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:40 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:40 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44.
Dec 05 21:35:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:42 volumio go-librespot[16334]: go-librespot daemon starting...
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=debug msg="app state loaded"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:42 volumio go-librespot[16335]: time="2025-12-05T21:35:42-06:00" level=info msg="zeroconf server listening on port 45089"
Dec 05 21:35:43 volumio go-librespot[16335]: time="2025-12-05T21:35:43-06:00" level=debug msg="obtained new client token: AABxUMMy9cuxgzNBB/YiEMuJxUeIILXFE3vSYYtx9iY//uH21Xz/NGuBJPNDXY7f/eH1LjkBilGHSSAukd3/2gLn34GLqD5q5JizLl1qbfLtHDWkSrGAEk6Iho+JG+N+3lLdLFihKD88B8FmbrYPBNy7t0grSVL0dM1BWitpErgicqkbVHBoCcZCPzLIqb4UlnK0RCgcAcRLRMqRDYswGiCq+hMWJmMeknGRME+zSzwH/bU2XRQ1Tb4="
Dec 05 21:35:43 volumio go-librespot[16335]: time="2025-12-05T21:35:43-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:43 volumio go-librespot[16335]: time="2025-12-05T21:35:43-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:43 volumio go-librespot[16335]: time="2025-12-05T21:35:43-06:00" level=debug msg="completed challenge"
Dec 05 21:35:43 volumio go-librespot[16335]: time="2025-12-05T21:35:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:43 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:43 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:45 volumio volumio[15646]: info: UPDATER: Scheduling automatic update
Dec 05 21:35:45 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 05 21:35:45 volumio volumio[15646]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 05 21:35:45 volumio volumio[15646]: info: UPDATER: Auto update will take place at: Sat Dec 06 2025 03:11:25 GMT-0600 (Central Standard Time)
Dec 05 21:35:46 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:46 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45.
Dec 05 21:35:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:46 volumio go-librespot[16357]: go-librespot daemon starting...
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="app state loaded"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=info msg="zeroconf server listening on port 41221"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="obtained new client token: AACMF5/oveYUZGl4F3x+G1tn69uqZaIjaNq6L71CnI0N3Sz8Sw307ipPJwlmrA19LWlTei5zPK2j+St8vB9Yj0Qr961UlgbuJmdo3VS8Bn3jE2sOinfgIZTdex/rVahy+pHljHzARpem7mrRT6RWcOfeY/I74gI2BiH2jhLSTydwGF5tPyp8HaDOofpFA+wRh4ykl+UtvpdRFqoXTLJjTmVocFcpnIbIfqq6v1OvDjtslzqJOurNZG7wqA=="
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=debug msg="completed challenge"
Dec 05 21:35:46 volumio go-librespot[16358]: time="2025-12-05T21:35:46-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:49 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:49 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46.
Dec 05 21:35:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:50 volumio go-librespot[16365]: go-librespot daemon starting...
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="app state loaded"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=info msg="zeroconf server listening on port 33425"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="obtained new client token: AADGq9bWBs/C8w/xg/pJ5hbRuyeZ/bu/oDA+x22tm/E6CYTcY8ruwQolQhde4SWbMSEVQWCy9iQ6X+58DwzOZx6IhIj87rhSqA+TRKsNDduFAUiykiZgoARdG8dlF/9MFYvDe11x0E2NB3K57KY/eFp62+UzAoKdTEvwiwUVicmdNbAD7xd7KhqcYZ+uC02PHy3SyfEv1RIBR/bQVY25WGM13muqXAUa7NRW4Pj3gXzuKd+Jp2b1G3mVsQ=="
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=debug msg="completed challenge"
Dec 05 21:35:50 volumio go-librespot[16366]: time="2025-12-05T21:35:50-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:52 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:52 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47.
Dec 05 21:35:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:53 volumio go-librespot[16388]: go-librespot daemon starting...
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="app state loaded"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=info msg="zeroconf server listening on port 39047"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="obtained new client token: AAABxM5c+T4HartHniuKax5A+n9Jfw6o+LrYa/KFefmgRF1c21RfZItEyFCG3uq4pBxCv4SbMkHSO5ZUYPW89AbExH+aEEvT2615dm3gDr8sg7OYV5jiDo6th9jcZSvJ1Lx//TwV8O+q2HX7UrIiYo4evZw1L8tQq3On8tug6rjI4fBha/Qw0lIjMqjJq+KUsmKkIxIqCjI25E2x4k2m85FCT2oFz+HJf6hhxQG17bCsUw/9tZfjuWpWOg=="
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=debug msg="completed challenge"
Dec 05 21:35:54 volumio go-librespot[16389]: time="2025-12-05T21:35:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:55 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:55 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:35:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48.
Dec 05 21:35:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:35:57 volumio go-librespot[16399]: go-librespot daemon starting...
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=debug msg="app state loaded"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=debug msg="stored credentials not found"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 05 21:35:57 volumio go-librespot[16400]: time="2025-12-05T21:35:57-06:00" level=info msg="zeroconf server listening on port 39767"
Dec 05 21:35:58 volumio go-librespot[16400]: time="2025-12-05T21:35:58-06:00" level=debug msg="obtained new client token: AAA2bqoxZ4MRoGBUGQmqK4LMS+cVq9hTGHJlFK9TV/q8or5gnkJH8RmgD26S5i6E/bkEPYLxTz3f0wsk4/mIW6moSTJdf68aedkFlMWWPJPqI0q/KxgwnOUGZiujVZy4B691JrAPMK9c38ULOqqUIu6B8PB3IA7ZNocNcJVEcuIU6dm5pMqy8M5wfxqG/HIjLKCMZxcGWoDBQlV2NZqlZrlvU6u5COtuwWEVNt0crGmV+j+MdVTYyzw="
Dec 05 21:35:58 volumio go-librespot[16400]: time="2025-12-05T21:35:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:35:58 volumio go-librespot[16400]: time="2025-12-05T21:35:58-06:00" level=debug msg="completed keyexchange"
Dec 05 21:35:58 volumio go-librespot[16400]: time="2025-12-05T21:35:58-06:00" level=debug msg="completed challenge"
Dec 05 21:35:58 volumio go-librespot[16400]: time="2025-12-05T21:35:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:35:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:35:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:35:58 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:35:58 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:01 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:01 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49.
Dec 05 21:36:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:01 volumio go-librespot[16407]: go-librespot daemon starting...
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="app state loaded"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=info msg="zeroconf server listening on port 32837"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="obtained new client token: AABupdAoP5KK/lCl+Mql9jwgm91AmipXZVdHOxqNLMvgQ2BFSH0CSzLqRrzMDD8ZIBEeBL1C2sA3EfYkwK7+v+hAvMLkQxzj5ecZURSgXmHycK5O7g55cPbmFNt7GWOEych/veLdHPQ6hoNXquriOUsp8pfd09rmb8BzhbroEkAHcZdvRMCPgv0Cq4m8XmGriSQVC46fSt8OzNl6cDND0neIGxGcZSGCJlZwDe4OrzzvKDq643bnbavZgQ=="
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=debug msg="completed challenge"
Dec 05 21:36:01 volumio go-librespot[16408]: time="2025-12-05T21:36:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:04 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:04 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50.
Dec 05 21:36:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:05 volumio go-librespot[16430]: go-librespot daemon starting...
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="app state loaded"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=info msg="zeroconf server listening on port 46255"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="obtained new client token: AABa6aBMmuc90ud8VeWIju/2WQx+t/+F2QsHjd8vt9Oy6nLjbJauP4YGlyFAMBoM+FMrZVCBbsJC+BK+wuEiAdQdElThFWmL5FS6Nd7vpUMMVHJY2D75cwIW9tzAwPKT7nSFB/AKj5S1jrXvKUfb+dKVvLltzBxk1AMz65K3Pv4n9v42rIUo6LK6ro+ERkK2SrxiwyhUyRcBqXkGjiTFOOhlPprq1v1aUlVC675LDaJFYHNjRPTns0YW/w=="
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=debug msg="completed challenge"
Dec 05 21:36:05 volumio go-librespot[16431]: time="2025-12-05T21:36:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:07 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:07 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51.
Dec 05 21:36:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:08 volumio go-librespot[16438]: go-librespot daemon starting...
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="app state loaded"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=info msg="zeroconf server listening on port 42951"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="obtained new client token: AADxvHYdf1TIIgMiVsSyWntigZ5dyuiBosP5Co5JLplMMvJ6V0iRUTcKpdPsjLHLWsob1yKBqq8wZTDEXdkE6VHxzV8hSRFm1h4JRb7m32O++UKJ75hr7rD9YqHqjjCNZOnSMDELXQoAbBNdgWSqIhKKBOkDiD+QXUGCPivCXOYSp/YJ+FkA6NHznAObG7XTWddq5IcM6J2Y3ThB9lVqYAyTMNQJbEdrUCM8odK8QpxU+xDAXAewllhHYQ=="
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=debug msg="completed challenge"
Dec 05 21:36:09 volumio go-librespot[16439]: time="2025-12-05T21:36:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:10 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:10 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52.
Dec 05 21:36:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:12 volumio go-librespot[16446]: go-librespot daemon starting...
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=debug msg="app state loaded"
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 05 21:36:12 volumio go-librespot[16447]: time="2025-12-05T21:36:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 05 21:36:13 volumio go-librespot[16447]: time="2025-12-05T21:36:13-06:00" level=info msg="zeroconf server listening on port 45565"
Dec 05 21:36:13 volumio go-librespot[16447]: time="2025-12-05T21:36:13-06:00" level=debug msg="obtained new client token: AAC6cdJtFfUVZo7eobr02u0Vi4yb9WQsPrNe6aZQ3aO/oEO3lffbaSR4ZR9tM8AclgwOERUzSnqYFxpH6n+pL921DxoWzXkXIwiKtNVTs4qLG36H/aCAr/pAMKA3Lp/ERQ100Jw+SyuSDuzqMiE0W/QfmYjeN5Vxr+87k7jan3zGAbVEaZGA0NSoFQqtwt6UUfUaEBeCt5CqqaOzkoO22Fu4GuN5cGSYHZLB2cBwy72iXqjF0aHM+q4="
Dec 05 21:36:13 volumio go-librespot[16447]: time="2025-12-05T21:36:13-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:13 volumio go-librespot[16447]: time="2025-12-05T21:36:13-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:13 volumio go-librespot[16447]: time="2025-12-05T21:36:13-06:00" level=debug msg="completed challenge"
Dec 05 21:36:13 volumio go-librespot[16447]: time="2025-12-05T21:36:13-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:13 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:13 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:16 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:16 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53.
Dec 05 21:36:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:16 volumio go-librespot[16471]: go-librespot daemon starting...
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="app state loaded"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=info msg="zeroconf server listening on port 45135"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="obtained new client token: AACOt6GPo8i19OwYm9GJy4LYrdFsY+drkYri8fdPsJPkkNmpOteS8wMKrSSvRBylh5d13LFaO12reDtwB60Gu0/KgNFtaeQT1/lMQla2Hgj8YM4jtUsKw8bQD3A0QeUT0tkKUMqO+oHSUH8FPOaZ5GvO5TF7V7wGpA2LGdec9n1uQTft7E8+K++PMn4cFj/c9QFYTbRS1DnRUJAd6DRR9oq6hNeqietvxgxcsJ4V2onqubNC709zgDwvEw=="
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:16 volumio go-librespot[16472]: time="2025-12-05T21:36:16-06:00" level=debug msg="completed challenge"
Dec 05 21:36:17 volumio go-librespot[16472]: time="2025-12-05T21:36:17-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:19 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:19 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54.
Dec 05 21:36:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:20 volumio go-librespot[16479]: go-librespot daemon starting...
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="app state loaded"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=info msg="zeroconf server listening on port 39001"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="obtained new client token: AADvsRdoB2GzNb8j+ZViBPOGETEBY+yuRkWuqilaWY9dNTNP+KHNcwXLdhXmlp90Df0SKN7RATM5r4k11tax/diWpfmMx7DF7vqMcP9G/Ulfrss35yBdhwJziJp42recC6WwBRZgYroKnJvtiLDRdDADYCeMoAkuAziGeE3clmHDZ8wLImRb9ZnT51tSzOc9mEfk36vXD1GGVKx5Q8uEqQfMEAlmvgllf9csql0vESSk1ddwQVMx10WfOA=="
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=debug msg="completed challenge"
Dec 05 21:36:20 volumio go-librespot[16480]: time="2025-12-05T21:36:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:22 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:22 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55.
Dec 05 21:36:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:24 volumio go-librespot[16501]: go-librespot daemon starting...
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="app state loaded"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=info msg="zeroconf server listening on port 42949"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="obtained new client token: AADBJhWfS6UW5tcT7tepRXUvm7EWzb+pW7NJ8sFnCcl3jF8Io7zGVXwRfrECgGwUyIqoGJw3GXCI/ceU5ObwaChUI19pVNaSyN8qABoZPr3rGjIVZ6wg+lDfHoTqlPhII1V6YPV+0sDzgfFK7eg6EzWl2z2ijubOgWYlOigztb/B1At+fMI3YkAfycOW+ulByHgKsPBvz0mgfVgcI9eajACRw4poI3T9BWBIXO74vtnkV3it1KKufR2Jjw=="
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=debug msg="completed challenge"
Dec 05 21:36:24 volumio go-librespot[16502]: time="2025-12-05T21:36:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:25 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:25 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56.
Dec 05 21:36:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:27 volumio go-librespot[16510]: go-librespot daemon starting...
Dec 05 21:36:27 volumio go-librespot[16511]: time="2025-12-05T21:36:27-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:27 volumio go-librespot[16511]: time="2025-12-05T21:36:27-06:00" level=debug msg="app state loaded"
Dec 05 21:36:27 volumio go-librespot[16511]: time="2025-12-05T21:36:27-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:27 volumio go-librespot[16511]: time="2025-12-05T21:36:27-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=info msg="zeroconf server listening on port 40071"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="obtained new client token: AAAQPyNLfwncbHUxAAktJMBnAdgWwst6gkxBfzEOluBbbiu0UpEfN2n+V1AU+A+yy6Bx/LVWDRn4100CfPwJlhQlM9CsAIk2Uhg6iG/+ZCpYHLpsngZ3xeW/pL/tKfHuodRcN6QOfJogt8twwoiJfRbjGosc8UUjEtI4rxh6bMTbn4kitVQGUqI0srXhUlNYwOwpUs8bICIJyd3YVRajNVhpFPhp0CPlagqXwaFmOO580AggDXzfs/U="
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=debug msg="completed challenge"
Dec 05 21:36:28 volumio go-librespot[16511]: time="2025-12-05T21:36:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:28 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:28 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:31 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57.
Dec 05 21:36:31 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:31 volumio go-librespot[16518]: go-librespot daemon starting...
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="app state loaded"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=info msg="zeroconf server listening on port 41837"
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="obtained new client token: AADJd/YO/FpykZOgAU688iVCeHtP3l1rNtU/appVuIn1/T1xe9z+ceLi4mgT476r2IeZFcEqbdjE29bYYQMh1DFzUSyPZ0GpxBF9xWNLnD9qgLwx0AyGH5eiSZ9eSrT4o9TIM1fq7VjVRMIrtwaFrsY9EQY79sSo0PPf6nEi/v0x7r4KVPbGiMdFXEF5tjagXE7HZ3KAcUlRBWOj1vTxs7jZprywtEH04lwSMmgR1yaMRazrcVW0ntCU6g=="
Dec 05 21:36:31 volumio go-librespot[16519]: time="2025-12-05T21:36:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:33 volumio go-librespot[16519]: time="2025-12-05T21:36:33-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:33 volumio go-librespot[16519]: time="2025-12-05T21:36:33-06:00" level=debug msg="completed challenge"
Dec 05 21:36:33 volumio go-librespot[16519]: time="2025-12-05T21:36:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:34 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:34 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58.
Dec 05 21:36:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:36 volumio go-librespot[16543]: go-librespot daemon starting...
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="app state loaded"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=info msg="zeroconf server listening on port 37415"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="obtained new client token: AAC35R6r07HPjoBQsZEHln0sdomWsyByaj4F/Vgtbmsf7Ty6fL+0tUY/op+CLc6ccsl5NYZwtbrsUcM4tWXNO0wbz/o+5FaY3r8mRAU0EAj8cmzvqXHBWe2VeXWqW/Gx2q06+UDcBSdnyoa2DO3G4ex0eu0odBcQcFvmz5C9SMEND1uhJi0siSp7Y8DnX/qZvTA+lKnGa1wDTzhPN/b3f9wpn58gjix7fA3z/uYlLfKbNefdrV3gdR3mDQ=="
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:36 volumio go-librespot[16544]: time="2025-12-05T21:36:36-06:00" level=debug msg="completed challenge"
Dec 05 21:36:37 volumio go-librespot[16544]: time="2025-12-05T21:36:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:37 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:37 volumio volumio[15646]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59.
Dec 05 21:36:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:40 volumio go-librespot[16551]: go-librespot daemon starting...
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="app state loaded"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 21:36:40 volumio volumio[15646]: info: Initializing connection to go-librespot Websocket
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="new websocket client"
Dec 05 21:36:40 volumio volumio[15646]: info: Connection to go-librespot Websocket established
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=info msg="zeroconf server listening on port 37403"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="obtained new client token: AAANgzWUoeQBMXXmOTUJ7ann9qIF8jLSyaXnMpjUmV8ScsRMnJRvhpx5e3nt8zGTO8JTpB0sB9lFxwfxhB65j/pCjQ9a5OWGsLERyI8G1XOPnxVvDwTdKvrdTUaJCgLgFTrh9cfhCFdvmc4iZ7AMQtKVvRAs5NBeTOjci5kUFGLborJqm+O5Bf1h6Ge5GzyjfLR1ZuvWRRiJvVs0YVx97sIOQeTuJHFP/9CSyrbptrY5EhRggLA/1zXu0w=="
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="completed keyexchange"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=debug msg="completed challenge"
Dec 05 21:36:40 volumio go-librespot[16552]: time="2025-12-05T21:36:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Dec 05 21:36:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 21:36:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 21:36:40 volumio volumio[15646]: info: Connection to go-librespot Websocket closed
Dec 05 21:36:43 volumio volumio[15646]: info: Getting Spotify volume
Dec 05 21:36:43 volumio volumio[15646]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 05 21:36:43 volumio volumio[15646]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 21:36:43 volumio volumio[15646]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 05 21:36:43 volumio volumio[15646]: errno: -111,
Dec 05 21:36:43 volumio volumio[15646]: code: 'ECONNREFUSED',
Dec 05 21:36:43 volumio volumio[15646]: syscall: 'connect',
Dec 05 21:36:43 volumio volumio[15646]: address: '127.0.0.1',
Dec 05 21:36:43 volumio volumio[15646]: port: 9879,
Dec 05 21:36:43 volumio volumio[15646]: response: undefined
Dec 05 21:36:43 volumio volumio[15646]: }
Dec 05 21:36:43 volumio volumio[15646]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 05 21:36:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60.
Dec 05 21:36:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 21:36:44 volumio go-librespot[16585]: go-librespot daemon starting...
Dec 05 21:36:44 volumio sudo[16588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 21:35'
Dec 05 21:36:44 volumio sudo[16588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 21:36:44 volumio go-librespot[16589]: time="2025-12-05T21:36:44-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 21:36:44 volumio go-librespot[16589]: time="2025-12-05T21:36:44-06:00" level=debug msg="app state loaded"
Dec 05 21:36:44 volumio go-librespot[16589]: time="2025-12-05T21:36:44-06:00" level=debug msg="stored credentials not found"
Dec 05 21:36:44 volumio go-librespot[16589]: time="2025-12-05T21:36:44-06:00" level=info msg="api server listening on 127.0.0.1:9879"
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"