Dec 06 05:33:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 107. Dec 06 05:33:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:00 volumio go-librespot[19150]: go-librespot daemon starting... Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=debug msg="app state loaded" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-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 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-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 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-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 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=info msg="zeroconf server listening on port 38721" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=debug msg="obtained new client token: AAACXW2MabE6BZ3Z23slxUz8Qq2dDLxCAQVf81u5zId8djpxmZQZIfXVdIW8VzZuHgJIsa4EF7u90OdMpda5iNK5iuXwNMLv5bn/cYvEIc9qTrWjaOh0iIPH5xYIp5L2ELOsG7v7NzZR4PvhD86Hmeyo1n9KGORJ/pci7qTTounLbKnso4NPt/xq+ebN8Iypoz7/RkJhbz43MCZ77L22EgbPjbOLhy9xnMy6qad+qDUa0Jgw4RCZDmD1tQ==" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-06:00" level=debug msg="completed challenge" Dec 06 05:33:00 volumio go-librespot[19151]: time="2025-12-06T05:33:00-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 06 05:33:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:02 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:02 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 05:33:03 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 05:33:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 108. Dec 06 05:33:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:04 volumio go-librespot[19172]: go-librespot daemon starting... Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=debug msg="app state loaded" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:04 volumio volumio[18536]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 06 05:33:04 volumio volumio[18536]: info: Completed starting MyVolumio Plugin Dec 06 05:33:04 volumio volumio[18536]: [Metrics] CommandRouter: 53s 792.76ms Dec 06 05:33:04 volumio volumio[18536]: info: CoreCommandRouter::volumiosetStartupVolume Dec 06 05:33:04 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 05:33:04 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 05:33:04 volumio volumio[18536]: info: CoreCommandRouter::Close All Modals sent Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-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 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-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 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-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 06 05:33:04 volumio volumio[18536]: info: CoreCommandRouter::Close All Modals sent Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=info msg="zeroconf server listening on port 38495" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=debug msg="obtained new client token: AAAtjMHKFFwfvvhb+lM9YWur9WfiG/ymX53nPOxHxN3FqWv+EAhnG4kLb4/xn9St13BJVP++MJXJ6de3+62qAWX4spNtlnTJ0WwuvbRPPXRquR4QejaH/APGFqsU2RXvfGnmhiKlj1xi4pP+27Q8xwsmhbwmNBRt4McVuDX5H0LcvEqwv1Dt0qFAMMDuYjRuFfI1FmREqBLIEqGhQGGPhy2BZ8pz220sB3N2SlOh1o4azJ3JdRwFAIEc/A==" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-06:00" level=debug msg="completed challenge" Dec 06 05:33:04 volumio go-librespot[19173]: time="2025-12-06T05:33:04-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 06 05:33:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:05 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 06 05:33:05 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 05:33:05 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 06 05:33:05 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:05 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 109. Dec 06 05:33:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:07 volumio go-librespot[19186]: go-librespot daemon starting... Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=debug msg="app state loaded" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-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 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-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 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=info msg="zeroconf server listening on port 32931" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=debug msg="obtained new client token: AAD5SZI64ShfDLBxO4pcOsHd5QTaoTFkuA0L0gI4Id58hR6kfjGX8WPGuutUkpu0r+9NtQ6tr1zdlSjQ0GVl27mHUJB3nYFj32tmtBXzzB1rMvz9U1ejURuWTu8EDKd7YeZGkExJuD+w8fiR9SmL9Ww0CzwqUczQhbEdcHBZUbghBkTNIZoY4+4gs/ceK+7X2bchogGL3ikDxKE6aE6NNZ2xKPLnoOe57hYG3uy9sRR+JCs3CoalPCUzHw==" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-06:00" level=debug msg="completed challenge" Dec 06 05:33:08 volumio go-librespot[19187]: time="2025-12-06T05:33:08-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 06 05:33:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:08 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:08 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:11 volumio volumio[18536]: info: BOOT COMPLETED Dec 06 05:33:11 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:11 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 110. Dec 06 05:33:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:11 volumio go-librespot[19194]: go-librespot daemon starting... Dec 06 05:33:11 volumio go-librespot[19195]: time="2025-12-06T05:33:11-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:11 volumio go-librespot[19195]: time="2025-12-06T05:33:11-06:00" level=debug msg="app state loaded" Dec 06 05:33:11 volumio go-librespot[19195]: time="2025-12-06T05:33:11-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:11 volumio go-librespot[19195]: time="2025-12-06T05:33:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-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 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-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 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-06:00" level=info msg="zeroconf server listening on port 45505" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-06:00" level=debug msg="obtained new client token: AABKrG8rQyYu3W9CWUyJ8srUJ8QqmxyuP+npQOtgCUxFvjoIEnm9zGR+PulINn/vEySVG63jBTMsjEykeusvTsbZ72b76oqOjQFoCfWg3umpI7utuAzwiMipiEKcqOnEfLYMgPcQ+wOVBkjORocJa58eJpJ4c85K34/8QAdUZc8jiCyi1bOEo9cTf7V4+THMGYDHD/NOJinAaEmd7XLHxD28CpSRDLZVnKrbsOQJqu01Wa8gjw2L52k=" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33:12-06:00" level=debug msg="completed challenge" Dec 06 05:33:12 volumio go-librespot[19195]: time="2025-12-06T05:33: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 06 05:33:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:14 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:14 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 111. Dec 06 05:33:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:15 volumio go-librespot[19220]: go-librespot daemon starting... Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=debug msg="app state loaded" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-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 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-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 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-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 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=info msg="zeroconf server listening on port 41785" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=debug msg="obtained new client token: AADYcDghKNYihoTEB2pqq66VXaLB3GTjc6XaSPppWkK6CBYiE3Twym9jcQjC3yX0Odp/m1MSRu2VhxBQ/Qiymtep9syUUxMfaenyOQWrnIAT10pmVQ7AomFDMLzgNkQO6Lzjr4yylTxuy0iiwuX/if6TcYE8bQg9ksf4pBYBCezzw8eSKLHsPMtS/U9J0Iks6MMeQjdkCxfZccls7wGJjlAVk6zkUiUCtw9Y7bz0ERnGiY23a1bkJYIocg==" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:15 volumio go-librespot[19221]: time="2025-12-06T05:33:15-06:00" level=debug msg="completed challenge" Dec 06 05:33:16 volumio go-librespot[19221]: time="2025-12-06T05:33: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 06 05:33:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:17 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:17 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 112. Dec 06 05:33:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:19 volumio go-librespot[19228]: go-librespot daemon starting... Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=debug msg="app state loaded" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-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 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-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 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-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 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=info msg="zeroconf server listening on port 37839" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=debug msg="obtained new client token: AADWI7a+l3nI1VjtG81RX9FjlpENgNo1ikHPRkpvrXJuM00MnX6rbDgDs+eNo49uQd9FkeXnKUjlweLVUr9oArOWBW/1RH+PwIjIdiDfnyJrkbzNhpGQ/i468k6hgbAevff41bqcxp6+iNcwzE57AYB8M/Mz7dErPnrNMvAeQfbPuGUutSN0YnNmTECKZZD4WGJz8u6qdGx0qGq2pfr2Emkq+DO7rSkQ4BxPjVT6FIyO97hqcyQqHsw2vA==" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-06:00" level=debug msg="completed challenge" Dec 06 05:33:19 volumio go-librespot[19229]: time="2025-12-06T05:33:19-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 06 05:33:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:20 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:20 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 113. Dec 06 05:33:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:22 volumio go-librespot[19237]: go-librespot daemon starting... Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=debug msg="app state loaded" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-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 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-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 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=info msg="zeroconf server listening on port 35851" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=debug msg="obtained new client token: AAAn8LDa3UajOpv+7wp6dQt8bUljBACJF+yNzkuMEXeYqYNld/IVhDWnCGVIuO2CVt3iEaHdHPzyQfMdJZptPFTiwQ/LkXRNMCW6QumASNu27za1wA70QF2WcrGMF/gtPN/UfKj2+hRNOmv/ozrP7y6OAFQraLlr1OzewSXMQuQP3EviFiEQQ0vpjp66naRLL1rUzDvSEx+xPgOoh06pZSvSejx2cSCf0lRSC8bMlHNNO5Dcm1IWi+mcoQ==" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-06:00" level=debug msg="completed challenge" Dec 06 05:33:23 volumio go-librespot[19238]: time="2025-12-06T05:33:23-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 06 05:33:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:23 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:23 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:26 volumio volumio[18536]: info: UPDATER: Scheduling automatic update Dec 06 05:33:26 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Dec 06 05:33:26 volumio volumio[18536]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Dec 06 05:33:26 volumio volumio[18536]: info: UPDATER: Auto update will take place at: Sun Dec 07 2025 05:17:05 GMT-0600 (Central Standard Time) Dec 06 05:33:26 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:26 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 114. Dec 06 05:33:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:26 volumio go-librespot[19259]: go-librespot daemon starting... Dec 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-06:00" level=debug msg="app state loaded" Dec 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-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 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-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 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-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 06 05:33:26 volumio go-librespot[19260]: time="2025-12-06T05:33:26-06:00" level=info msg="zeroconf server listening on port 46341" Dec 06 05:33:27 volumio go-librespot[19260]: time="2025-12-06T05:33:27-06:00" level=debug msg="obtained new client token: AACX7JxqaKCGzGxqmuN3+mOGx77mnX8Jp/Ug0DVE3Gwf+O+wFFpevamJ1GUFjYGlO3Kp+gVxEFj32OSV3uIKdCiNu7R1OHq75GTkv3z0pvVDefCWBVNQApK0J4S9z5ovkuA+OkL3wP664QHIGb6d1Nvor5Hnl27NIYO4zr+f+ALfPNL8l9MuEJI6HN97daf90rVX9V4B2c7+BsgfeW2azOGm8md5wXOpAYWQJHHF19Mbwu0Ibmm5w4o=" Dec 06 05:33:27 volumio go-librespot[19260]: time="2025-12-06T05:33:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:27 volumio go-librespot[19260]: time="2025-12-06T05:33:27-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:27 volumio go-librespot[19260]: time="2025-12-06T05:33:27-06:00" level=debug msg="completed challenge" Dec 06 05:33:27 volumio go-librespot[19260]: time="2025-12-06T05:33: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 06 05:33:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:29 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:29 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 115. Dec 06 05:33:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:30 volumio go-librespot[19267]: go-librespot daemon starting... Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=debug msg="app state loaded" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-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 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-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 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-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 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=info msg="zeroconf server listening on port 41169" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=debug msg="obtained new client token: AACGE6BWZabzBaaloWI7I1AqiwmxlVECrafMERjetn3hO3eSs8aL6v1AJx+uqjLBYLkfYemBPEdHMWA47JXkb//+Z6Gdx4oH5Arzz5IKnMD0dQYW2clDPuwSzUyQ5jQLa+It66pE+uWI7XNek/uoTh6ZRQUiJc8Qxy2CrIcp/LVI+wXHAkpVoR/33EOoMT60Kh70ZrzHIrT0/QOE/C2ZSpwN41Lu6rg5RqKtxknVg1/9PdXcyI/LPc/yjQ==" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-06:00" level=debug msg="completed challenge" Dec 06 05:33:30 volumio go-librespot[19268]: time="2025-12-06T05:33:30-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 06 05:33:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:32 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:32 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 116. Dec 06 05:33:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:34 volumio go-librespot[19289]: go-librespot daemon starting... Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=debug msg="app state loaded" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-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 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-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 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-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 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=info msg="zeroconf server listening on port 46431" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=debug msg="obtained new client token: AAAKVoHhPeVf5ExXlamOBi5PJID49MDOcQGk0K1kPSlaHW0f9vS3Hl1OwoLP9FClWKKs0n0pOJyQPsNG26i0A/sWTnB9pFc+PQet7Rymx2ZP/LkZ/SalMNjgK0V60yFzPRxlMmGgw4rBMiiUbtf4Wc9J8DqyqynOZ7QKTReUO21ZD0zpY7I2wPH5Jn5h8YdK3nPwTSu1qia9wumFlOFJR570YL4LnDc+LbPsiTsBdOYX6b7iqY5HM58qXQ==" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-06:00" level=debug msg="completed challenge" Dec 06 05:33:34 volumio go-librespot[19290]: time="2025-12-06T05:33:34-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 06 05:33:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:35 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:35 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117. Dec 06 05:33:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:38 volumio go-librespot[19300]: go-librespot daemon starting... Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=debug msg="app state loaded" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-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 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-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 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-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 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=info msg="zeroconf server listening on port 33253" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=debug msg="obtained new client token: AADyidX+dy1GzRwyei2kePmnevqqDVHnDOFFPnK8qQJmcDFAPAxtkHyc+d2A62dz7UDW0FLW6BvF+6fL5lp0jWoVLygZv1MdWFo1bSNESQKlhSru/bHkrwrMQxv6KJ4rayzc0NOy0/Iz0mFluSJ3LBFX00OpP4CzB9eap3670DL5HTnlqICWNHev1cJAiB0DShGUUaR+R2PRU/eTRDBnnd/pw8eZv12ilOlywjt21IaXVv7dnlzS8nrHfg==" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-06:00" level=debug msg="completed challenge" Dec 06 05:33:38 volumio go-librespot[19301]: time="2025-12-06T05:33:38-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 06 05:33:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:38 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:38 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:41 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 118. Dec 06 05:33:41 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:41 volumio go-librespot[19309]: go-librespot daemon starting... Dec 06 05:33:41 volumio go-librespot[19310]: time="2025-12-06T05:33:41-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:41 volumio go-librespot[19310]: time="2025-12-06T05:33:41-06:00" level=debug msg="app state loaded" Dec 06 05:33:41 volumio go-librespot[19310]: time="2025-12-06T05:33:41-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:41 volumio go-librespot[19310]: time="2025-12-06T05:33:41-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33: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 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33: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 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33: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 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33:42-06:00" level=info msg="zeroconf server listening on port 36367" Dec 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33:42-06:00" level=debug msg="obtained new client token: AAB8flufELNjDTcve/8VpYCVKdQRIZ8YDGXLy46J06ZGCwA+afs3DReetDIy9XNmrWIN10oF2vpvxermjHgjlQ45JoFUkZgFSsDezqSNWFeyA5ncJvzq6vlej1IX3LfdBwOR6/0VC6uow7r2f5rYcfHRhL1DfNEzErc1zw5TOX8kpmI40L9qmIMVCLlZcxvQN7mG1gMNCnsO4WDj7MHaWQxm35BKkSi7j5DzvXnQkJVRmYwRftVHkIM=" Dec 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33:42-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33:42-06:00" level=debug msg="completed challenge" Dec 06 05:33:42 volumio go-librespot[19310]: time="2025-12-06T05:33:42-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 06 05:33:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:44 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:44 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 119. Dec 06 05:33:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:45 volumio go-librespot[19331]: go-librespot daemon starting... Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=debug msg="app state loaded" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-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 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-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 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-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 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=info msg="zeroconf server listening on port 36385" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=debug msg="obtained new client token: AABSXlEemiFGuMUxqXnI+Or+HY7oYIgfijr+IPWj2s/cBL3mlsqBYvEAFYIjh8Q/gYfcrb3Iwn9B+nSRt42/CYvTWgjEH8Z2RP7R4FzxW5NSF66zPYVmwiyPDACQ1RlOew53fYkAdp8Y78Lm0jsC0dntb5XMdYZnnaZTPpaa39ecWimT28TlGP0+MC/Nq+GZ9iSKN+eWeFB9+pPaRrEaJAU1dhASyCM4YZBWyWeCFn4Zx4+YSS0Pf0QiBw==" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-06:00" level=debug msg="completed challenge" Dec 06 05:33:45 volumio go-librespot[19332]: time="2025-12-06T05:33:45-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 06 05:33:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:47 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:47 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120. Dec 06 05:33:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:49 volumio go-librespot[19339]: go-librespot daemon starting... Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=debug msg="app state loaded" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-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 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-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 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-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 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=info msg="zeroconf server listening on port 37989" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=debug msg="obtained new client token: AAAfh2lurv6PZvilhnYdjn/hoJeKERBC9h8Say65xOz5Gx2Sm1azFqYwNyPVwgRqUGbimVxoOg0eOhTrU3MjUgum/iVe00MV+ozWfxRlh3wPQ2y+a/Du9Gi5o0bShRGMG5W6agPG3wBvac5n0579hq2QF+VceHp4a2E5r5GjsYBqjlGXApmg2kBc7VRRL6JkKyL9KIfTzfdP3dV6lJiL54V7BzSXVp2hSK1pBrwhmx48Vu9yZV9YHjRCwQ==" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-06:00" level=debug msg="completed challenge" Dec 06 05:33:49 volumio go-librespot[19340]: time="2025-12-06T05:33:49-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 06 05:33:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:50 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:50 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121. Dec 06 05:33:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:52 volumio go-librespot[19348]: go-librespot daemon starting... Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=debug msg="app state loaded" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33: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 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33: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 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33: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 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=info msg="zeroconf server listening on port 38573" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=debug msg="obtained new client token: AABIW9m8zz9X07D7Cfrs7kTCPvEiYBjaYPbACAWzgKUL0GZo6a3Dg998F4SrWe6ugsqDsUmulYmwH4zUlcn45hg4FLO8pH2l+7RFyrd1DmPwfhY+usaKlQgK1mvMCykvO9FYyOFSW2rpL+scH2PBS8Bv3MXZFDPjhbT5dNA/gGMYAyOKJSUBPXwqBS6e7DD/5X/eduw0vIPmDu81u8U6q5O+kjYJcPosjUHpApmhvUnvFM0v+U9RHmwxXQ==" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-06:00" level=debug msg="completed challenge" Dec 06 05:33:53 volumio go-librespot[19349]: time="2025-12-06T05:33:53-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 06 05:33:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:53 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:53 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122. Dec 06 05:33:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:56 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:56 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:33:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:33:56 volumio go-librespot[19373]: go-librespot daemon starting... Dec 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-06:00" level=debug msg="app state loaded" Dec 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-06:00" level=debug msg="stored credentials not found" Dec 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-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 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-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 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-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 06 05:33:56 volumio go-librespot[19374]: time="2025-12-06T05:33:56-06:00" level=info msg="zeroconf server listening on port 43111" Dec 06 05:33:57 volumio go-librespot[19374]: time="2025-12-06T05:33:57-06:00" level=debug msg="obtained new client token: AAAVyQHxg5EXNYpZaHesPUswyJ6RR/FwLKDi4Ffc1WYwcydCCjMVz3Mj6yWxiZDwy9UU5Vpx4xbhbmXSC/oLcdnzrdLBG7DmMWUCEAxNSExZktgkZ5slTqzANoJ8aRv0OuK28n3w4dgA5Lzna6rTI46UneyesKRATCmLVZJzXCqoYP9S9uptRCbW2aLbV55WNPU0ZdN4i35XWvvPJ6ZPR+QcAAeBo7g1Z2xsjek3LO5J9yE2BmZLaC4=" Dec 06 05:33:57 volumio go-librespot[19374]: time="2025-12-06T05:33:57-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:33:57 volumio go-librespot[19374]: time="2025-12-06T05:33:57-06:00" level=debug msg="completed keyexchange" Dec 06 05:33:57 volumio go-librespot[19374]: time="2025-12-06T05:33:57-06:00" level=debug msg="completed challenge" Dec 06 05:33:57 volumio go-librespot[19374]: time="2025-12-06T05:33:57-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 06 05:33:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:33:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:33:59 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:33:59 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123. Dec 06 05:34:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:00 volumio go-librespot[19382]: go-librespot daemon starting... Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=debug msg="app state loaded" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-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 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-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 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-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 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=info msg="zeroconf server listening on port 36849" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=debug msg="obtained new client token: AACNhhSazk0jScIWGotMgYgBTgqtuCa1NZqnKbS+PztC05owHnA62BLese42gH3HIxUbhDVhaq3xWp1PFahcoen1bcflOXXVDl6M6mTYiEOR69QQUQKAB/7arRR6bXlEx1oNjXfjFgvotR4Z6H0uy9fXoEP/OfMD0cGLC6gCGvTSocTW1M9ZH+XQsPKRKjwbI96mQz9aZsexcux4u6o6xMmtBKi4QQ8OBQQmw2NFpkP+4iJuDcOo1o40sw==" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:00 volumio go-librespot[19383]: time="2025-12-06T05:34:00-06:00" level=debug msg="completed challenge" Dec 06 05:34:01 volumio go-librespot[19383]: time="2025-12-06T05:34: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 06 05:34:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:02 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:02 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124. Dec 06 05:34:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:04 volumio go-librespot[19405]: go-librespot daemon starting... Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=debug msg="app state loaded" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-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 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-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 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-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 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=info msg="zeroconf server listening on port 42981" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=debug msg="obtained new client token: AADU6174FaocD2H4rnlFtoW8BQScSc67mM+bdn0NLgnnr8P6T9l+xGluLw1Jt7eAIY0QxdqAxV04Lkfd7jLyuaAA0lwauD/oO3DCruOOGJjh2XMoS/6E6oFjkcgYt23p/0tu9Q771q9Uyp2njbQfoo0b7vRF/8HvMGxVAAX93KPCGCSLLzkMuAFcLtmmwNyqIDIJ4az4rl9rEj3Rz7ADGccn+8LkiCBdTgW23nxXF7cd2NWUZibqONSDvg==" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-06:00" level=debug msg="completed challenge" Dec 06 05:34:04 volumio go-librespot[19406]: time="2025-12-06T05:34:04-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 06 05:34:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:05 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:05 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125. Dec 06 05:34:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:07 volumio go-librespot[19413]: go-librespot daemon starting... Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=debug msg="app state loaded" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34: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-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-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 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-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 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=info msg="zeroconf server listening on port 39875" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=debug msg="obtained new client token: AACoKTDLvlVaDDQxumliZJJIlrbqKNkqF+8TCbl1Ltt2msrh/CqjIHTk5vbL8h1IJCmA3rqFMpjz9jHGwXTy2bJ0I4+TDh+HAg12atA37gKAbnyb6VXEN5MQjKTiRhw2Jn76ro5qVsDGo6b9bFehhG3v3MqJL1o8CpR1306OAoXzUqvGEOMgwIoTi7/mZIfYh1zII+ktkwJNOxI+CG9xxH6Q+8BQ6uLIAstpn4xsmCTrcHPTGjkNQTpTfg==" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-06:00" level=debug msg="completed challenge" Dec 06 05:34:08 volumio go-librespot[19414]: time="2025-12-06T05:34:08-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 06 05:34:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:08 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:08 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126. Dec 06 05:34:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:11 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:11 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:11 volumio go-librespot[19422]: go-librespot daemon starting... Dec 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-06:00" level=debug msg="app state loaded" Dec 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-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 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-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 06 05:34:11 volumio go-librespot[19423]: time="2025-12-06T05:34:11-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 06 05:34:12 volumio go-librespot[19423]: time="2025-12-06T05:34:12-06:00" level=info msg="zeroconf server listening on port 37365" Dec 06 05:34:12 volumio go-librespot[19423]: time="2025-12-06T05:34:12-06:00" level=debug msg="obtained new client token: AAA4olDnN4K2jJ+vCBc3lMem/l8fkZXYOINWCyvqs0UOxlc9eouzGCVHCW9YCZVMgiBElZOphvwjrtPYkeK0Q/nvuBggMENymHyoaU0CcZYg4i/0NLCdVCxhv9GQkLnGGhf0751HfvsJDJ55q4i3hvJUO30IuDKuyqhsbLi+lY51ETLp3GOaGvc5XREkb6qdBsh5AGS+KVtDdnDgd9aDLYjTni2Qc5u257c7by2TmrSk+TMK7P6H3zc=" Dec 06 05:34:12 volumio go-librespot[19423]: time="2025-12-06T05:34:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:12 volumio go-librespot[19423]: time="2025-12-06T05:34:12-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:12 volumio go-librespot[19423]: time="2025-12-06T05:34:12-06:00" level=debug msg="completed challenge" Dec 06 05:34:12 volumio go-librespot[19423]: time="2025-12-06T05:34: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 06 05:34:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:14 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:14 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127. Dec 06 05:34:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:15 volumio go-librespot[19447]: go-librespot daemon starting... Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=debug msg="app state loaded" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-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 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-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 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-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 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=info msg="zeroconf server listening on port 41737" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=debug msg="obtained new client token: AAA4WSoIq+cRH/mAfEZycckFjadDv4uY2TSuDEYd9WbmmDlW2QdO7cpJTiCpMSGV3VDU0uP2nDBBqs6JDFFmYXUR7Csoqi5r08hJnhwT394ToMNHVyihqhcQOfouFamOiZcwhW+Nr0ZyQ66VE5TOWDmxbSTdCDVBBkPW6bbGPj+65GtUAo21V5r4hK5iSA5SQcm7gSn0SGQuiYuZZG7zZhbnY9uOilb973xy7I9JRxRkpVjajrhdrOdkJQ==" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:15 volumio go-librespot[19448]: time="2025-12-06T05:34:15-06:00" level=debug msg="completed challenge" Dec 06 05:34:16 volumio go-librespot[19448]: time="2025-12-06T05:34: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 06 05:34:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:17 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:17 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128. Dec 06 05:34:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:19 volumio go-librespot[19456]: go-librespot daemon starting... Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=debug msg="app state loaded" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-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 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-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 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-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 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=info msg="zeroconf server listening on port 44447" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=debug msg="obtained new client token: AAAxPokQb7meFeg/Btx687GkrTLYdT840e2w0oiFFgpo55yd6mEHt+3vAOYrJ2LoCyyZdK9OsMvFQyJXX6HhntXSsns4MpmZl5R5vlASdcZAjBYoNFHGLmieUh413R69OjgUlzlzkr5L2zm+BszoqYH5xejbIRYTOTmm/91xNAYcUL9eu6DONjMQQ5SMcdYqG3pFUrUw+JexVtUFGYhNZe4X7rgYYXnwFtkllFJElGqp7OeM+xOLYFRyTg==" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-06:00" level=debug msg="completed challenge" Dec 06 05:34:19 volumio go-librespot[19457]: time="2025-12-06T05:34:19-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 06 05:34:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:20 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:20 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129. Dec 06 05:34:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:22 volumio go-librespot[19464]: go-librespot daemon starting... Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=debug msg="app state loaded" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-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 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-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 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=info msg="zeroconf server listening on port 33439" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=debug msg="obtained new client token: AAB1NeQYpm+gMT+yrI1bLa6oe5UhnDlJ3RsGw9W5M/ifFz5qdCZbe9aG+K9NNAiDaf/UTfMRGRyMSKXYUOV6zEXM+5g170RSZ+ubCse4uP9j96AHgQDdJ7MdJlnFjfKnjbT+Cb+kursUgN1AmMqQk3EvAgWqYuh57RAvvlMq8RfbW+r8kuRgUoFJZ3KCvwcHFIgBlyuTBbW/4oAP/1bFaNprG2q0Gz1i5DJVrAr+Lkmp06hWFX3wgyEAHA==" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-06:00" level=debug msg="completed challenge" Dec 06 05:34:23 volumio go-librespot[19465]: time="2025-12-06T05:34:23-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 06 05:34:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:23 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:23 volumio volumio[18536]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 130. Dec 06 05:34:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:26 volumio go-librespot[19486]: go-librespot daemon starting... Dec 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-06:00" level=debug msg="app state loaded" Dec 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:26 volumio volumio[18536]: info: Initializing connection to go-librespot Websocket Dec 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-06:00" level=debug msg="new websocket client" Dec 06 05:34:26 volumio volumio[18536]: info: Connection to go-librespot Websocket established Dec 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-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 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-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 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-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 06 05:34:26 volumio go-librespot[19487]: time="2025-12-06T05:34:26-06:00" level=info msg="zeroconf server listening on port 41303" Dec 06 05:34:27 volumio go-librespot[19487]: time="2025-12-06T05:34:27-06:00" level=debug msg="obtained new client token: AABzuNO37SQVdnmfnbjgwZke1i1RpwQAHJZ/HanIDSMVe9GUdMWOAFuxp+chLbUuyTCWpWvboT17iQUPx+TFsM7cEIXwCrTwTeJOyvmBrDD4JYTbgGwzArIpcmEgpSlq8uORQdfLc2pcYuZa4pvia8hqRnMOR0Cynr//tu5ldrNvho7o2qS/hmV+s6LvOjvJZXcLN8/R/ULNcuaHygahd9Xii6XN8F11WbQIHHvTAo9U11h5ESc4COE=" Dec 06 05:34:27 volumio go-librespot[19487]: time="2025-12-06T05:34:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 06 05:34:27 volumio go-librespot[19487]: time="2025-12-06T05:34:27-06:00" level=debug msg="completed keyexchange" Dec 06 05:34:27 volumio go-librespot[19487]: time="2025-12-06T05:34:27-06:00" level=debug msg="completed challenge" Dec 06 05:34:27 volumio go-librespot[19487]: time="2025-12-06T05:34: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 06 05:34:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 05:34:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 05:34:27 volumio volumio[18536]: info: Connection to go-librespot Websocket closed Dec 06 05:34:29 volumio volumio[18536]: info: Getting Spotify volume Dec 06 05:34:29 volumio volumio[18536]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 05:34:29 volumio volumio[18536]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 05:34:29 volumio volumio[18536]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 06 05:34:29 volumio volumio[18536]: errno: -111, Dec 06 05:34:29 volumio volumio[18536]: code: 'ECONNREFUSED', Dec 06 05:34:29 volumio volumio[18536]: syscall: 'connect', Dec 06 05:34:29 volumio volumio[18536]: address: '127.0.0.1', Dec 06 05:34:29 volumio volumio[18536]: port: 9879, Dec 06 05:34:29 volumio volumio[18536]: response: undefined Dec 06 05:34:29 volumio volumio[18536]: } Dec 06 05:34:29 volumio volumio[18536]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 05:34:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 131. Dec 06 05:34:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 05:34:30 volumio go-librespot[19507]: go-librespot daemon starting... Dec 06 05:34:30 volumio go-librespot[19508]: time="2025-12-06T05:34:30-06:00" level=info msg="running go-librespot 0.4.0" Dec 06 05:34:30 volumio go-librespot[19508]: time="2025-12-06T05:34:30-06:00" level=debug msg="app state loaded" Dec 06 05:34:30 volumio go-librespot[19508]: time="2025-12-06T05:34:30-06:00" level=debug msg="stored credentials not found" Dec 06 05:34:30 volumio go-librespot[19508]: time="2025-12-06T05:34:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 05:34:30 volumio sudo[19516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 05:33' Dec 06 05:34:30 volumio sudo[19516]: 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"