Dec 05 05:51:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Dec 05 05:51:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:01 volumio go-librespot[15746]: go-librespot daemon starting...
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=debug msg="app state loaded"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51: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-gew1.spotify.com:80]"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-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 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-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 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=info msg="zeroconf server listening on port 45999"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=debug msg="obtained new client token: AACMO5GlLBLUqrDz0gK/ed29oQsmJn/D+o3qL1usFZvq7TaB3taEWFyV/dEcuGdNNnc1Mb4AdtRB3n46r0+s5LUlKA3mWpQHEAhL8Z/V5QjtV7zvK00HYoZviORgpthyMX8fDQch3AIp86ZaNZFfiiKdll4TCaknTOH0olVscS0+zqsmrd73KUCnvxaq9ECdpLyxZDG9nvFkTwTHn8fK9qnWkJ/I+1foNmV01LIgvXuCJL22En0U2lKBMw=="
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51:01-06:00" level=debug msg="completed challenge"
Dec 05 05:51:01 volumio go-librespot[15747]: time="2025-12-05T05:51: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 05:51:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:02 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:02 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Dec 05 05:51:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:04 volumio go-librespot[15754]: go-librespot daemon starting...
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=debug msg="app state loaded"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51: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-gew4.spotify.com:80]"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-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 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-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 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=info msg="zeroconf server listening on port 39951"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=debug msg="obtained new client token: AAA5fP17n6Y8ffkgK5iOX9f8NKtylMzMCwY3cKzFZvJ7t8t/keX+l4XMvSgsRNna7cuLL4NdHIweejYfs1rAmD7CzC8hlD694h+8xgdbiDsVTyUEAGDtWfbS1eExTHmAbRJEvV6zCJ6HUAi+0y738D5elhgIVvJXBsM8Ok0SQt2DIMVwDHPizFlbpzbn0PLOsLX6ojHRz0c6Wxfs9fr9iA1Xk/X1VCZzf0WhbYWvKI6pvJTlwIUFBcKGtA=="
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51:05-06:00" level=debug msg="completed challenge"
Dec 05 05:51:05 volumio go-librespot[15755]: time="2025-12-05T05:51: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 05:51:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:05 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:05 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin multiroom to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 05 05:51:05 volumio volumio[15280]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 05 05:51:06 volumio volumio[15280]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 05 05:51:06 volumio volumio[15280]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 05 05:51:06 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:06 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:06 volumio volumio[15280]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 05 05:51:06 volumio volumio[15280]: info: MyVolumio login type: Token
Dec 05 05:51:06 volumio volumio[15280]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 05 05:51:06 volumio volumio[15280]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 05 05:51:07 volumio volumio[15280]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 05 05:51:07 volumio volumio[15280]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 05 05:51:07 volumio volumio[15280]: info: Streaming services startup
Dec 05 05:51:07 volumio volumio[15280]: info: Starting Streaming Daemon
Dec 05 05:51:07 volumio sudo[15777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 05 05:51:07 volumio sudo[15777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 05:51:07 volumio volumio[15280]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 05 05:51:07 volumio sudo[15777]: pam_unix(sudo:session): session closed for user root
Dec 05 05:51:07 volumio volumio[15280]: error: Cannot start Volumio Streaming Daemon
Dec 05 05:51:07 volumio volumio[15280]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 05 05:51:07 volumio volumio[15280]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 05 05:51:08 volumio volumio[15280]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 05 05:51:08 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:08 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Dec 05 05:51:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:08 volumio go-librespot[15784]: go-librespot daemon starting...
Dec 05 05:51:08 volumio go-librespot[15785]: time="2025-12-05T05:51:08-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:08 volumio go-librespot[15785]: time="2025-12-05T05:51:08-06:00" level=debug msg="app state loaded"
Dec 05 05:51:08 volumio go-librespot[15785]: time="2025-12-05T05:51:08-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:08 volumio go-librespot[15785]: time="2025-12-05T05:51:08-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:08 volumio volumio[15280]: info: MyVolumio token set successfully
Dec 05 05:51:08 volumio volumio[15280]: info: MYVOLUMIO: Adding device
Dec 05 05:51:08 volumio volumio[15280]: info: MYVOLUMIO: Evaluating Server
Dec 05 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51: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 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51: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 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51: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 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51:09-06:00" level=info msg="zeroconf server listening on port 43779"
Dec 05 05:51:09 volumio volumio[15280]: info: MyVolumio status changed
Dec 05 05:51:09 volumio volumio[15280]: info: Streaming services startup
Dec 05 05:51:09 volumio volumio[15280]: info: Starting Streaming Daemon
Dec 05 05:51:09 volumio volumio[15280]: info: Removing browser output: myVolumio user plan is not superstar
Dec 05 05:51:09 volumio volumio[15280]: info: Removing audio output:
Dec 05 05:51:09 volumio volumio[15280]: info: Stoppping Tunnel 1
Dec 05 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51:09-06:00" level=debug msg="obtained new client token: AAC/zb2rV5EwCbPkqPkUvFVBXAnBUTlbSoC49Y1seR5G2C2juk7V/+PtlJpC0tmx5PovB0erYKKtBo/XjqJjVSLYVrR58pANFnCdQHwCNesHjfXduxNEl59ocx5KiFoNbh0NNetahX+dA9NtugvtURE/V80Pprcyqo6CZ6ZHP26kZas7byUcNYq6d1au4K5yW9LcHB0OOFqXBJRRdbu4ER8GXFa2qzyWyesPq54hJJ8/zomOiHSs5xo="
Dec 05 05:51:09 volumio sudo[15814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 05 05:51:09 volumio sudo[15814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 05:51:09 volumio sudo[15816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 05 05:51:09 volumio sudo[15816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 05 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:09 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 05:51:09 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 05:51:09 volumio sudo[15814]: pam_unix(sudo:session): session closed for user root
Dec 05 05:51:09 volumio volumio[15280]: error: Cannot start Volumio Streaming Daemon
Dec 05 05:51:09 volumio volumio[15280]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 05 05:51:09 volumio volumio[15280]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 05 05:51:09 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 05:51:09 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 05:51:09 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 05:51:09 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 05:51:09 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 05:51:09 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 05:51:09 volumio sudo[15816]: pam_unix(sudo:session): session closed for user root
Dec 05 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51:09-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51:09-06:00" level=debug msg="completed challenge"
Dec 05 05:51:09 volumio volumio[15280]: info: Remote SSH Stopped
Dec 05 05:51:09 volumio go-librespot[15785]: time="2025-12-05T05:51: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 05:51:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:09 volumio volumio[15280]: info: Setting Geolocation for MyVolumio to us4
Dec 05 05:51:09 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:09 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:09 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:09 volumio volumio[15280]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 05 05:51:10 volumio volumio[15280]: info: Updating MyVolumio device info
Dec 05 05:51:10 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:10 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:10 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:10 volumio volumio[15280]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 05 05:51:11 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:11 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Dec 05 05:51:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:12 volumio go-librespot[15819]: go-librespot daemon starting...
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=debug msg="app state loaded"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51: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 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51: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 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51: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 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=info msg="zeroconf server listening on port 39315"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=debug msg="obtained new client token: AADuvDapKAN4cJ2KoimqR1ScO+uedL1+H4fKyRsP+JZWXcIU/D1Vdd+9pBIIC0iqkFUzZLeCWeb3qnGs6NCnfRSC27EhDec7Le9C6RZeL6GmB+QAmMYryrb04gQnW8Vp1sb7TBNik3Mn40Xe/l5OTlRT9hUsJ9rEcCRDdQACiEGJFqMyz4RLDHNWS+sPnkmIInckWAhq8k/AZdcMCl9u0LDLp3YA3dhEI5ewvrmMCrGDnNI2K14+QhinoA=="
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-06:00" level=debug msg="completed challenge"
Dec 05 05:51:12 volumio go-librespot[15820]: time="2025-12-05T05:51:12-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 05:51:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:13 volumio volumio[15280]: info: MYVOLUMIO: Adding device
Dec 05 05:51:13 volumio volumio[15280]: info: MYVOLUMIO: Evaluating Server
Dec 05 05:51:14 volumio volumio[15280]: info: Setting Geolocation for MyVolumio to us1
Dec 05 05:51:14 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:14 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:14 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:14 volumio volumio[15280]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 05 05:51:14 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:14 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:15 volumio volumio[15280]: info: Updating MyVolumio device info
Dec 05 05:51:15 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:15 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:15 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:15 volumio volumio[15280]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 05 05:51:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Dec 05 05:51:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:16 volumio go-librespot[15845]: go-librespot daemon starting...
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=debug msg="app state loaded"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-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 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-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 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=info msg="zeroconf server listening on port 36647"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=debug msg="obtained new client token: AAAoldSEdInTKM+4QbSBjpBjGsplkM5qZbn4XAn1fp+7IyaxlKLPSmoErfGJoe7HAQgRKWJ6bvKDLNAhqOCF6RreN3RTzcrKxP/pHjGxvzpOB8XmKlEOC05uNrADZTrPXM+WdoTBrBjwuA4LxS+30MRAXSMtDEB1FGHVtQlx9MObxicFjBu2j0dIE9X3mVE21k3VlDZShYa5PU0ZxpFMhLShdanms+5sbpOZPd8xjpka/3XnQHQXvHnVGA=="
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-06:00" level=debug msg="completed challenge"
Dec 05 05:51:16 volumio go-librespot[15846]: time="2025-12-05T05:51:16-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 05:51:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:17 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:17 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:19 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 05 05:51:19 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:19 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 05 05:51:19 volumio volumio-remote-updater[2353]: Test mode disabled
Dec 05 05:51:19 volumio volumio-remote-updater[2353]: Alpha mode disabled
Dec 05 05:51:19 volumio volumio-remote-updater[2353]: Alpha legacy test mode disabled
Dec 05 05:51:19 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 05 05:51:19 volumio volumio[15280]: 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 05:51:19 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 05 05:51:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Dec 05 05:51:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:19 volumio go-librespot[15875]: go-librespot daemon starting...
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=debug msg="app state loaded"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-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 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-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 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=info msg="zeroconf server listening on port 41655"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=debug msg="obtained new client token: AABzS6S77QSV9wXSQZzLMesggyXrbssi0xWQa1bM7yb/bLLj4BQBaFx2a4B+37H95WOiodgX276dfFvOG4ILotPgLZp8u5FU/MrHtoqNhbLKXu5qlRTOEHpaju6kVKnuWFkqF0huddI6WYW441MI6JlEXAnL+rE8rD+ZFmMzZ05wDGR4JKzc/xZPlA7AcNvd5iz3X+oING39i+eQjG5y8Zw+n53s92H1LbBfXf60//3Jhknqi5B7Yzpj+w=="
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51:20-06:00" level=debug msg="completed challenge"
Dec 05 05:51:20 volumio go-librespot[15876]: time="2025-12-05T05:51: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 05:51:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:20 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:20 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:21 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 05 05:51:21 volumio volumio[15280]: 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 05:51:21 volumio volumio[15280]: info: CoreCommandRouter::volumioGetState
Dec 05 05:51:21 volumio volumio[15280]: info: CorePlayQueue::getTrack 0
Dec 05 05:51:23 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:23 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Dec 05 05:51:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:23 volumio go-librespot[15883]: go-librespot daemon starting...
Dec 05 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-06:00" level=debug msg="app state loaded"
Dec 05 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-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 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-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 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-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 05:51:23 volumio go-librespot[15884]: time="2025-12-05T05:51:23-06:00" level=info msg="zeroconf server listening on port 40359"
Dec 05 05:51:24 volumio go-librespot[15884]: time="2025-12-05T05:51:24-06:00" level=debug msg="obtained new client token: AABdx+qXUsn5BPx2GfwmzFe0WzLrpyJm0AWbzV+RVtGZDTVit/IkPD+i4DiNfz7a/1UTH1tI4fCzWqG+gqKoMad9TMRGY9Uqifznbj3x+5y4eyJ1iwIkMJHBEA9ESQgc26j/iGQXKCOAUtqWkuF/BHS96Sa2yRCjDC0BEPMeIt2d9iFfcxF0drS8ygwwDJBEm0jrt9jpoQnjPHXwk/x6/3oVq7E2RJn2A/toNZOibFzxTZn4zOyDtlM="
Dec 05 05:51:24 volumio go-librespot[15884]: time="2025-12-05T05:51:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:24 volumio go-librespot[15884]: time="2025-12-05T05:51:24-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:24 volumio go-librespot[15884]: time="2025-12-05T05:51:24-06:00" level=debug msg="completed challenge"
Dec 05 05:51:24 volumio go-librespot[15884]: time="2025-12-05T05:51: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 05:51:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:26 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:26 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 05 05:51:26 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 05 05:51:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Dec 05 05:51:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:27 volumio go-librespot[15905]: go-librespot daemon starting...
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=debug msg="app state loaded"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:27 volumio volumio[15280]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 05 05:51:27 volumio volumio[15280]: info: Completed starting MyVolumio Plugin
Dec 05 05:51:27 volumio volumio[15280]: [Metrics] CommandRouter: 53s 730.51ms
Dec 05 05:51:27 volumio volumio[15280]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 05 05:51:27 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 05 05:51:27 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 05 05:51:27 volumio volumio[15280]: info: CoreCommandRouter::Close All Modals sent
Dec 05 05:51:27 volumio volumio[15280]: info: CoreCommandRouter::Close All Modals sent
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-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 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-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 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=info msg="zeroconf server listening on port 42927"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=debug msg="obtained new client token: AAD7Gnn7vbecbcGIokAfOzYLNaH5YfEtHanJ2OTuSZI+ezHBOCWBVPLyvreqZEEihF1LmIzt92l7k+whGlung+qz0/GeR/6mGtH55JUTbwK56PzRQcHgFS9FjBP6ZnMsrcZ6xAC87H5l+R9NSZsxpn/vBWm+5siE/AGXS2bRFdNpbnVNjr+N7TLyGYS/0qKYyPtOp9CeNKZhjBiBZlkOaKSB3VQ4FJdLq2LRm/WjcIa3ZpAQRfUqymNqeg=="
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-06:00" level=debug msg="completed challenge"
Dec 05 05:51:27 volumio go-librespot[15906]: time="2025-12-05T05:51:27-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 05:51:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:28 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 05 05:51:28 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 05 05:51:28 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 05 05:51:29 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:29 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Dec 05 05:51:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:31 volumio go-librespot[15919]: go-librespot daemon starting...
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=debug msg="app state loaded"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-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 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-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 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=info msg="zeroconf server listening on port 38077"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=debug msg="obtained new client token: AAAPusvtwP5EKGBEBkougxDBZT3tdyVAmryMBtrPUq2mRqCKaKJGczN4GnDOEbMvLwDMOM7/VLcZEX3GyJfbF66D3hGoCq0ZanC2kFWSEWfuavXQytBivr21ZH/OXY2YrTnHddGakp4ZGeVA8e+yqTexSDBYPUOxxxs9Xkrz1nY71Soe/YNcp6vcRIRbe/WTiWxOhre/H5xe/XIIQVs2NL6vflqW6K5l442W5SMykP+2v9D17mLpo3MkNA=="
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51:31-06:00" level=debug msg="completed challenge"
Dec 05 05:51:31 volumio go-librespot[15920]: time="2025-12-05T05:51: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 05:51:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:32 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:32 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:34 volumio volumio[15280]: info: BOOT COMPLETED
Dec 05 05:51:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Dec 05 05:51:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:35 volumio go-librespot[15927]: go-librespot daemon starting...
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=debug msg="app state loaded"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-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 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-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 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=info msg="zeroconf server listening on port 34805"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=debug msg="obtained new client token: AAAMBGE3w19iu9xhudGLUuPgF/Sghaj1AAGdEvNgdZpQ9giOmnNXaPOMMh56ggbijHEyvzdDoH0wpuj3JaKnNS03KAogAjby8pqIIhoakcdRL76lm3fAvF5BQqKDDwjrwwIcvfn0T4/FdrT1BBKRgsjYnqB85u6vEtWftFsV/GCDD4O6rXWxEO1ERz65KsslRJe6BQWvS5Yk2FeIbUw//yYdUjAMF4NnqWaGDnHTlIzQuU56yfz6BpwxMw=="
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51:35-06:00" level=debug msg="completed challenge"
Dec 05 05:51:35 volumio go-librespot[15928]: time="2025-12-05T05:51: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 05:51:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:35 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:35 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Dec 05 05:51:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:38 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:38 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:38 volumio go-librespot[15949]: go-librespot daemon starting...
Dec 05 05:51:38 volumio go-librespot[15950]: time="2025-12-05T05:51:38-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:38 volumio go-librespot[15950]: time="2025-12-05T05:51:38-06:00" level=debug msg="app state loaded"
Dec 05 05:51:38 volumio go-librespot[15950]: time="2025-12-05T05:51:38-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:38 volumio go-librespot[15950]: time="2025-12-05T05:51:38-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-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 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-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 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-06:00" level=info msg="zeroconf server listening on port 34817"
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-06:00" level=debug msg="obtained new client token: AAAu11kx9DMKCmLaJhqizBmPnni4Tidq9y4SppUFZDzLF7vpmZNXR0QfZan6D2EF5Wa0CCNYbEHrZW9T5vJxJ7AzOcNq8IQAa+WaHmETK6IzEQTFQUMrl8JIu0J3tyMYPdChxSsO9y4dh6YvYZd80LjxZLnSzyMdxnd4B9EVamXqyjJBWVc1+qqo+ny+y3ze1TNvVgFcRCoWsl7Tu7INatW4Dz7un2JrCHcYkHHiEtUDyoPeh3mWWtg="
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51:39-06:00" level=debug msg="completed challenge"
Dec 05 05:51:39 volumio go-librespot[15950]: time="2025-12-05T05:51: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 05:51:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:41 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:41 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Dec 05 05:51:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:42 volumio go-librespot[15961]: go-librespot daemon starting...
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=debug msg="app state loaded"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-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 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-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 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=info msg="zeroconf server listening on port 37337"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=debug msg="obtained new client token: AACUswT3bCl5ORaUsQt0RDi2+4TKWDAKthYvQ9hk9WI6B2OxpU8zfyosSXEbO3F/7gtAMuGkdzgAuazKqt0gYj+Ra6l95zm1yp3N2MP9oFzNlC0IuLQXK++3qwjiPYQbZPB2QT5RfUpHdGD3hUrISvUO0AXJ+0TsS7NxHloqBnrignSh/y6WjmtygJreifVyonMCjJoNKyN9rZbn4FR/YkxzaVTeucwsqQqe4480jeTL3qhXkhzLDwHiXw=="
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:42 volumio go-librespot[15962]: time="2025-12-05T05:51:42-06:00" level=debug msg="completed challenge"
Dec 05 05:51:43 volumio go-librespot[15962]: time="2025-12-05T05:51: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 05:51:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:44 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:44 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Dec 05 05:51:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:46 volumio go-librespot[15969]: go-librespot daemon starting...
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=debug msg="app state loaded"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-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 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-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 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=info msg="zeroconf server listening on port 46881"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=debug msg="obtained new client token: AACIhWW0ry/wZ31hNhNNDSB1eLWw6YcBuvwWb19lINsp+6QzgUBH87w3y3toOFNoJqIvRABdWv9bRv7nCfEQRYMlYD5iZ5/s7PPvs9bDBbGSEmM6fWYaYdDlUEDovaNzc1XolMZl1Yu+o3DXJXqLRqlTFrLM5/Ale6vsL1fm4OJoKz+Sm6M5LQX2c1FfW/nNSqnXoxhE0xZHcnLc65zrNKQQL1lr/D8HUHWm+Tr1Sc0Qdvn61m3hodpcCw=="
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-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 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:443"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51:46-06:00" level=debug msg="completed challenge"
Dec 05 05:51:46 volumio go-librespot[15970]: time="2025-12-05T05:51: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 05:51:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:47 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:47 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:49 volumio volumio[15280]: info: UPDATER: Scheduling automatic update
Dec 05 05:51:49 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 05 05:51:49 volumio volumio[15280]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 05 05:51:49 volumio volumio[15280]: info: UPDATER: Auto update will take place at: Sat Dec 06 2025 05:11:48 GMT-0600 (Central Standard Time)
Dec 05 05:51:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Dec 05 05:51:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:49 volumio go-librespot[15992]: go-librespot daemon starting...
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=debug msg="app state loaded"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-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 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-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 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=info msg="zeroconf server listening on port 34293"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=debug msg="obtained new client token: AADXK8E7/kYDwjtfMH39g82BhdDtyms4Pd+7Wkti05AYqBbiEFtxVtlzC/p2wjimEy8yxdoODfJnjVkc4jsBx+CoOfYMEAYBHIKgcT5TiJ2fNLWRI/sngqhgDrvcGDb+22cX/rzBMrtJBfPT2T4nGQYMuQCRixWF/9ckcT3UAJ94a9GLBQQoN3deWcX1aGzcfuxRE1xpiKtQ8iuYOn5MyV+a4vPu+uYbkLwqb2fiSU0TQO1yZd6dig3Zpg=="
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51:50-06:00" level=debug msg="completed challenge"
Dec 05 05:51:50 volumio go-librespot[15993]: time="2025-12-05T05:51: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 05:51:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:50 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:50 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Dec 05 05:51:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:53 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:53 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:53 volumio go-librespot[16000]: go-librespot daemon starting...
Dec 05 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-06:00" level=debug msg="app state loaded"
Dec 05 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-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 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-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 05:51:53 volumio go-librespot[16001]: time="2025-12-05T05:51:53-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 05:51:54 volumio go-librespot[16001]: time="2025-12-05T05:51:54-06:00" level=info msg="zeroconf server listening on port 41371"
Dec 05 05:51:54 volumio go-librespot[16001]: time="2025-12-05T05:51:54-06:00" level=debug msg="obtained new client token: AAANELqOFe5mO1KYp9Ug16Gl2FZ0aT/OXMqhnqXJ1LdPHCauFd29hl/yZ4aZgILiGNZiUi0NyfKrWIOR/cYNSWHRctCBJgnoFb40tyaCCGEYxBjRwobneWx5zh6aJWhYAEulH6agNPE86ZFGKwloFiUFvO4KEAyssfChIhb5LTIEGZnhchbjBhIbzmHPKD6u7GIBpezMcKnewrzJ4fJlXnN8nxNKf/KF24VnKA6R5EZFGIrjw3jTIEE="
Dec 05 05:51:54 volumio go-librespot[16001]: time="2025-12-05T05:51:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:54 volumio go-librespot[16001]: time="2025-12-05T05:51:54-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:54 volumio go-librespot[16001]: time="2025-12-05T05:51:54-06:00" level=debug msg="completed challenge"
Dec 05 05:51:54 volumio go-librespot[16001]: time="2025-12-05T05:51: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 05:51:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:56 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:56 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:51:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Dec 05 05:51:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:51:57 volumio go-librespot[16022]: go-librespot daemon starting...
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=debug msg="app state loaded"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=debug msg="stored credentials not found"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-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 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-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 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=info msg="zeroconf server listening on port 42567"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=debug msg="obtained new client token: AADB4e9eeDmN9E1AUezZ6/eS50ube48t2x35aiwYD4oW3ys+mIZJNqKAghmfS5SpIPNPdsPYPw+yJ45TsVy8UWKgb4XLeqpy1eqhR0SGtHV9ABDpDJ7miVz6u8Jmoe6+yoKUXfGHtqjrXSXMuhKDeRvmWVCNQubzJCBFUaHmoh7/GuvcsOp0JJEq4xqKp/1qP5gdCbX0iSDYradotkrpoZSlEWN0VP75p9Z3pOlcYO6yDJ50mNPRmOm34g=="
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=debug msg="completed keyexchange"
Dec 05 05:51:57 volumio go-librespot[16023]: time="2025-12-05T05:51:57-06:00" level=debug msg="completed challenge"
Dec 05 05:51:58 volumio go-librespot[16023]: time="2025-12-05T05:51: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 05:51:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:51:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:51:59 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:51:59 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:52:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Dec 05 05:52:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:01 volumio go-librespot[16034]: go-librespot daemon starting...
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=debug msg="app state loaded"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=debug msg="stored credentials not found"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-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 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-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 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=info msg="zeroconf server listening on port 44903"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=debug msg="obtained new client token: AACEcafOJPtzeMmxfW/en82g9n88OboEnIB/uAEPx2njWOn+3w9VVXK1D+LEtCML5xrAxxWfRorVoGonwn4glRvXj/MP8EdWyhea9o2qqJWLqX84mj4kzjOWmmUdRxpwsoZUHGAOQj4OJ5DPfPqIdFIPcVAXuK6ExRITzllq7T++TlIUzfopNvGV1C6V+bBuzASyeYSqie/9wASlf8ZVxopXxF4NFYRbNfECnawn4Z5pvOIyvQvk1xqsHQ=="
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=debug msg="completed keyexchange"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52:01-06:00" level=debug msg="completed challenge"
Dec 05 05:52:01 volumio go-librespot[16035]: time="2025-12-05T05:52: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 05:52:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:52:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:52:02 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:52:02 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:52:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Dec 05 05:52:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:04 volumio go-librespot[16042]: go-librespot daemon starting...
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=debug msg="app state loaded"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=debug msg="stored credentials not found"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52: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-gew4.spotify.com:80]"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-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 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-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 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=info msg="zeroconf server listening on port 34501"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=debug msg="obtained new client token: AAAZvxq81VBfHNS28OcJKuMp+w6hihO2pi77qG3DdYwAWJ8sVmWNoYK6OfvDLam+sKaAqrtFEnhWowwb51u+WLq70Ll0CyCURmwK5SZA4/jImzzqGR5L2Z/Xlz4yO4Wl5UDuWV4MS7AjJEsrtl0In8URXY6AJ/Wy+12xJuybzc6t5N2cXCThgM84IPXVTkMk+jBC+kjYBr6DWvcozgWQV/7m7q+S0lMhdF8B1AUWeXuAY+HE5EQyepm1Dw=="
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=debug msg="completed keyexchange"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52:05-06:00" level=debug msg="completed challenge"
Dec 05 05:52:05 volumio go-librespot[16043]: time="2025-12-05T05:52: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 05:52:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:52:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:52:05 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:52:05 volumio volumio[15280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:52:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Dec 05 05:52:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:08 volumio go-librespot[16065]: go-librespot daemon starting...
Dec 05 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-06:00" level=debug msg="app state loaded"
Dec 05 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-06:00" level=debug msg="stored credentials not found"
Dec 05 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:52:08 volumio volumio[15280]: info: Initializing connection to go-librespot Websocket
Dec 05 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-06:00" level=debug msg="new websocket client"
Dec 05 05:52:08 volumio volumio[15280]: info: Connection to go-librespot Websocket established
Dec 05 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-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 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-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 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-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 05:52:08 volumio go-librespot[16066]: time="2025-12-05T05:52:08-06:00" level=info msg="zeroconf server listening on port 37719"
Dec 05 05:52:09 volumio go-librespot[16066]: time="2025-12-05T05:52:09-06:00" level=debug msg="obtained new client token: AABZ6hynAqyb1vaxmF2H2mghR31Lhx+fZngdp7u4jbH4dwopxnYLlhhkLwfxDneLmURfnw6aC8Fo5lMcHv5qTg5f3PThowbraHbjo+OBdDcBk5yIp7qH4KkvqicxzRjFpDtD/zkh/BhDU0M3ZKb1wk6QEThj/QrtXesTXNi+jY9EHQaEa/v/txNXCAOLTg4cBB+vDKzn+vX9JyGT3WdOlN9OYkRFNbN1p6KPwQ4I7X4T3B+6vxfM5kA="
Dec 05 05:52:09 volumio go-librespot[16066]: time="2025-12-05T05:52:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070"
Dec 05 05:52:09 volumio go-librespot[16066]: time="2025-12-05T05:52:09-06:00" level=debug msg="completed keyexchange"
Dec 05 05:52:09 volumio go-librespot[16066]: time="2025-12-05T05:52:09-06:00" level=debug msg="completed challenge"
Dec 05 05:52:09 volumio go-librespot[16066]: time="2025-12-05T05:52: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 05:52:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Dec 05 05:52:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Dec 05 05:52:09 volumio volumio[15280]: info: Connection to go-librespot Websocket closed
Dec 05 05:52:11 volumio volumio[15280]: info: Getting Spotify volume
Dec 05 05:52:11 volumio volumio[15280]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 05 05:52:11 volumio volumio[15280]: Error: connect ECONNREFUSED 127.0.0.1:9879
Dec 05 05:52:11 volumio volumio[15280]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Dec 05 05:52:11 volumio volumio[15280]: errno: -111,
Dec 05 05:52:11 volumio volumio[15280]: code: 'ECONNREFUSED',
Dec 05 05:52:11 volumio volumio[15280]: syscall: 'connect',
Dec 05 05:52:11 volumio volumio[15280]: address: '127.0.0.1',
Dec 05 05:52:11 volumio volumio[15280]: port: 9879,
Dec 05 05:52:11 volumio volumio[15280]: response: undefined
Dec 05 05:52:11 volumio volumio[15280]: }
Dec 05 05:52:11 volumio volumio[15280]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 05 05:52:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Dec 05 05:52:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Dec 05 05:52:12 volumio go-librespot[16085]: go-librespot daemon starting...
Dec 05 05:52:12 volumio go-librespot[16086]: time="2025-12-05T05:52:12-06:00" level=info msg="running go-librespot 0.4.0"
Dec 05 05:52:12 volumio go-librespot[16086]: time="2025-12-05T05:52:12-06:00" level=debug msg="app state loaded"
Dec 05 05:52:12 volumio go-librespot[16086]: time="2025-12-05T05:52:12-06:00" level=debug msg="stored credentials not found"
Dec 05 05:52:12 volumio go-librespot[16086]: time="2025-12-05T05:52:12-06:00" level=info msg="api server listening on 127.0.0.1:9879"
Dec 05 05:52:12 volumio sudo[16093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 05:51'
Dec 05 05:52:12 volumio sudo[16093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="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"