Feb 12 03:17:01 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:01 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Feb 12 03:17:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:02 volumio go-librespot[30394]: go-librespot daemon starting... Feb 12 03:17:02 volumio go-librespot[30395]: time="2026-02-12T03:17:02-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:02 volumio go-librespot[30395]: time="2026-02-12T03:17:02-06:00" level=debug msg="app state loaded" Feb 12 03:17:02 volumio go-librespot[30395]: time="2026-02-12T03:17:02-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:02 volumio go-librespot[30395]: time="2026-02-12T03:17:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-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]" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-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]" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-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]" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-06:00" level=info msg="zeroconf server listening on port 36087" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-06:00" level=debug msg="obtained new client token: AACboJZ8OMby2GZ0iaRi2ZciBc754135MQska1U3f0m7K7EIA0PiUll7ZeSoDkYu5MH6cAx4B2qGn1KHwd4oQnStGauJOGRMsFIi8mYYjqXCb9JjT5PcgPdiwLbTtcbr1BUuesqISgwYNe+XTplvHFTozP1A/0OTyPTpjDWrV5OAHBzfFTxmYgonurqXgZgqtnBSnpKl7KYdU/mDsyadphnCkyFnylzv7uQ2NqreSJKkx4LEaGIr9wA=" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-06:00" level=debug msg="completed challenge" Feb 12 03:17:03 volumio go-librespot[30395]: time="2026-02-12T03:17:03-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 " Feb 12 03:17:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:04 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:04 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 12 03:17:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:06 volumio go-librespot[30404]: go-librespot daemon starting... Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=debug msg="app state loaded" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-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]" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-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]" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-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]" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=info msg="zeroconf server listening on port 36523" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=debug msg="obtained new client token: AADwnNCM3kpvsj3mbPhjmxP1AkWi83vw8tyOd8LW7TdmG3m6xAOtyzREOUx6UZ8uQatmy7dcb1DSlLRtMS7ApZs4rjoY3N6EtL4nNQB33nGrvnZSsMkn7Le7mw0dFPZNHydTGhPiegG1hbalWCgn7zSh3ZM8FgtGuZ15koJn9DPEivrtA8E/1P92H9QPX1hF7cjL3Px69q/qqEENOw/8heCUDtBsSYA+BGpRYrGueZEF9L0y0HHblq9C5g==" Feb 12 03:17:06 volumio go-librespot[30405]: time="2026-02-12T03:17:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:07 volumio go-librespot[30405]: time="2026-02-12T03:17:07-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:07 volumio go-librespot[30405]: time="2026-02-12T03:17:07-06:00" level=debug msg="completed challenge" Feb 12 03:17:07 volumio go-librespot[30405]: time="2026-02-12T03:17:07-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 " Feb 12 03:17:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:07 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:07 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:10 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:10 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 12 03:17:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:10 volumio go-librespot[30416]: go-librespot daemon starting... Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=debug msg="app state loaded" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-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]" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-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]" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-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]" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=info msg="zeroconf server listening on port 37961" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=debug msg="obtained new client token: AAB5vUxVlNDg3dJ7ir9BaauB778L4OIDBUpTLyO/LQ0xUV6N4gPxZOMgzdn+BFFAy6BDfYjqgl10otJd6b0MLGN6BL6FOnavlIetxAXjLhyb3CM2cu504OkquCoOhionyQKv/4thNmzmpMM+kAlnlS2Cx2TJcyU5V9yrYhiniyiqBfNf4XBkUtL/d5KCgOV+glefyaEydP0euVa0GE6thbGsYj+el12hqpH1oYbzOt0mMr8K21aD42OwFg==" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-06:00" level=debug msg="completed challenge" Feb 12 03:17:10 volumio go-librespot[30417]: time="2026-02-12T03:17:10-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 " Feb 12 03:17:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:13 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:13 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 12 03:17:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:14 volumio go-librespot[30438]: go-librespot daemon starting... Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=debug msg="app state loaded" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-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]" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-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]" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-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]" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=info msg="zeroconf server listening on port 44019" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=debug msg="obtained new client token: AACkkMnVGs/VKeRXhLABSJKn/tbeLGJH4CwLjPllgK+CuH7zb67bkD6tigmHmWz8NyTVnKtNTz6zg6w94zVE6jJ6WW3j1F4VVia8SwIxnnIitN0a0AEwmDo2VUQS2JQgIJujVhLopPmrqNjF7AA8/FeMvjBNJ0MJ+zKQGzf0LW/7r3zeBLrd6vHuh6tWQLVLKIBc5Yx+ViZZ1TCMkL9MlQeb5OG3gwwOcTgHB95qFqZvaRUdMUEeHnPUGw==" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-06:00" level=debug msg="completed challenge" Feb 12 03:17:14 volumio go-librespot[30439]: time="2026-02-12T03:17:14-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 " Feb 12 03:17:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:16 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:16 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 12 03:17:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:17 volumio go-librespot[30446]: go-librespot daemon starting... Feb 12 03:17:17 volumio go-librespot[30447]: time="2026-02-12T03:17:17-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:17 volumio go-librespot[30447]: time="2026-02-12T03:17:17-06:00" level=debug msg="app state loaded" Feb 12 03:17:17 volumio go-librespot[30447]: time="2026-02-12T03:17:17-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:17 volumio go-librespot[30447]: time="2026-02-12T03:17:17-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-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]" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-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]" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-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]" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-06:00" level=info msg="zeroconf server listening on port 43975" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-06:00" level=debug msg="obtained new client token: AACuw/q1cmrf9Saeu3RcszHacQLbZDOxfjzuVXiYGK1t6FdPOvEc0tPsjQHOtnjy4hKsQ1ymQ2F6UkkSiuulKUSKDEHplWWuWT2oE+JiF4W/r5/73lRwqGYet7Ok1izHWoM3anE923sTI/dg5lpb4L9YQ26KArKh6/A+uetePvjMpG8aOz4/TaGsbUqXssqUAfGo81Q+o0m3ujdWqGQSNkBvkPFvS2bGwL/3J0AMHu75FrZ+Qg91uFQ=" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-06:00" level=debug msg="completed challenge" Feb 12 03:17:18 volumio go-librespot[30447]: time="2026-02-12T03:17:18-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 " Feb 12 03:17:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:19 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:19 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 12 03:17:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:21 volumio go-librespot[30454]: go-librespot daemon starting... Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=debug msg="app state loaded" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-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]" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-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]" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-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]" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=info msg="zeroconf server listening on port 41537" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=debug msg="obtained new client token: AACPIXajid4wUntJlrG4w9bAPXBgMoZHKNqahS0GGooU0Vuu17JUsxP6OjBbYiiW1A8vHvw6XVJVhNirN7Wdz3XeXNW2Nxa7L0j5BLHCdJ/DWjwWuMe84FV7Wtjlp/B+uSZUSRdisGEYrpjb8nBVx9SDRzp5AmH+H9BIxXlDc4pkItqHGnhcBvHTTjlvqZlHAFlC3Fp4l5maruONuM+NMOFuUa9Zep8VKEXgErzb8U4FUL1+6L+Co5c4rg==" Feb 12 03:17:21 volumio go-librespot[30455]: time="2026-02-12T03:17:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:22 volumio go-librespot[30455]: time="2026-02-12T03:17:22-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:22 volumio go-librespot[30455]: time="2026-02-12T03:17:22-06:00" level=debug msg="completed challenge" Feb 12 03:17:22 volumio go-librespot[30455]: time="2026-02-12T03:17:22-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 " Feb 12 03:17:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:22 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:22 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:25 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:25 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 12 03:17:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:25 volumio go-librespot[30476]: go-librespot daemon starting... Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=debug msg="app state loaded" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-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]" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-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]" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-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]" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=info msg="zeroconf server listening on port 40865" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=debug msg="obtained new client token: AAD/kz1fPnrJ66e5jZXVRAVLmYjSAVC0Y6W+cbpxrYk5YhghGBxjPmI+FZGwmrk33RTaCL+R+hn75oKpjuvhD6P5orRA5JdiJggHNhOY3d8b9pA/KYKS2PuH48YZggC7VNSh12rdWrbnVzXoaZA21CfkXpYm+pp7hPsFCKsf3B5cyyGeaneO7jyk/5ruWWder9EAtzzg/Q/AS43zhO3jnNCgJqKc6g4ltJFh+cyQ6TU5ZDmqhKnPcQsBGA==" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-06:00" level=debug msg="completed challenge" Feb 12 03:17:25 volumio go-librespot[30477]: time="2026-02-12T03:17:25-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 " Feb 12 03:17:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:28 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:28 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 12 03:17:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:29 volumio go-librespot[30488]: go-librespot daemon starting... Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=debug msg="app state loaded" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-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]" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-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]" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-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]" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=info msg="zeroconf server listening on port 36741" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=debug msg="obtained new client token: AADyOjE+E1N/YoZVv5BUGDSTxc211WNtXlnkKN5MwtLjp93x3EHCmO/yRrEjZHlMCcCuhNVPOS3PPfZS/iCNdyRecfq0S+oEZtPN+2N/qmBOcgjXFCy5sFi5S3vXopL+ATX2ekAKaESIoyAz8CWPmRDeeTaAv9hiqbByRNPSYgkSSbJTEtAD5JbxvSJeDCPXLu6BayaDjgIWEPhw5uEnnHpcu9bh28QZrpBB/1fv/v/JtkvH5G6NBzqtEg==" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-06:00" level=debug msg="completed challenge" Feb 12 03:17:29 volumio go-librespot[30489]: time="2026-02-12T03:17:29-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 " Feb 12 03:17:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:31 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:31 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 12 03:17:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:32 volumio go-librespot[30510]: go-librespot daemon starting... Feb 12 03:17:32 volumio go-librespot[30511]: time="2026-02-12T03:17:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:32 volumio go-librespot[30511]: time="2026-02-12T03:17:32-06:00" level=debug msg="app state loaded" Feb 12 03:17:32 volumio go-librespot[30511]: time="2026-02-12T03:17:32-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:32 volumio go-librespot[30511]: time="2026-02-12T03:17:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-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]" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-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]" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-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]" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-06:00" level=info msg="zeroconf server listening on port 43919" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-06:00" level=debug msg="obtained new client token: AABpILUGvnUcc2Lfjw9mfhRq8UaBafCxQnA3cakkwzrVilr0cgFXE/80Noi3hMSymsibT4yuCa12Sv3YX7+Ml/ZketTQdbMA3x02oSrh2G777F+g1FJsKtqpxX6860ZZ05MSM+HbjoTxrxTc5WYWbTS2aRPKrkMWwpd7jZpiGCEE01x2AQIbY6RPeJ1k4cFBr6ywqden78oNpVOO+qf/ybG7WmdeYs/lk56mTdsV3CZS/2g3JpIylss=" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-06:00" level=debug msg="completed challenge" Feb 12 03:17:33 volumio go-librespot[30511]: time="2026-02-12T03:17:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 03:17:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:34 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:34 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 12 03:17:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:36 volumio go-librespot[30518]: go-librespot daemon starting... Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=debug msg="app state loaded" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-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]" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-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]" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=info msg="zeroconf server listening on port 36995" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=debug msg="obtained new client token: AADPzzJL3KLBenSgKiPUUcAS7f7X5txFNnGGskyV1IxR0LM/3OjUIrQMc0ub0PDYrxHse4lXeWKpGxu0jxVHAnBPr33lB9TIo0LCtCC8wL7gBNTfKc01MRjGG1Nc5YnPSOyPdHzLa64vxoHmSKi03FfK4X1CSgbl3C1EJfzoAE9BVALKGdgRRcIgyUQ4wlDnly08cpHz9nb6TGVtAhEmXl5apGvolXILbsdHleJGjr3JE4R9/SJ+O1kpQQ==" Feb 12 03:17:36 volumio go-librespot[30519]: time="2026-02-12T03:17:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:37 volumio go-librespot[30519]: time="2026-02-12T03:17:37-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:37 volumio go-librespot[30519]: time="2026-02-12T03:17:37-06:00" level=debug msg="completed challenge" Feb 12 03:17:37 volumio go-librespot[30519]: time="2026-02-12T03:17:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 03:17:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:37 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:37 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:40 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:40 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 12 03:17:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:40 volumio go-librespot[30526]: go-librespot daemon starting... Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="app state loaded" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-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]" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-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]" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=info msg="zeroconf server listening on port 39307" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="obtained new client token: AABeGV2GYI0JIHqRtvv8ZEe2q6Zfl+xtH0Hof6xYrDoPGPJh49B74cjrbnpmPZXvfjcLHc7znVdjLjk+9G9Txsnd4oL9iji5icI92F2DDm+qAa0XQotHhoxgCxkZZeM1f5Z+L2QGJAZbPqCFGHca2mqort1eVjJEDLXGbTy0B+Z0zeiyqvb2G1OhJMxdNDxsHQF6K3D+vAiUJOKb9eAUjrV9tJtC4uoau3RkqGhrLX8KXd1Tr2qUwvvNig==" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=debug msg="completed challenge" Feb 12 03:17:40 volumio go-librespot[30527]: time="2026-02-12T03:17:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 03:17:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:43 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:43 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 12 03:17:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:44 volumio go-librespot[30548]: go-librespot daemon starting... Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=debug msg="app state loaded" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-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]" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-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]" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-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]" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=info msg="zeroconf server listening on port 41879" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=debug msg="obtained new client token: AAAVcSWGuxFL3o9cFU1BQbCi15PDD1B5XgPgIknMIfNt4auqQDmq+yUPMVTqD7bcAAjTMryewj2PCBrhiFAMgQeMUZ+5LS/U/0kcbH9NvynjlibpS5YQo1kSjapxqZxVUl7LoJ2c66dP6ToHS3Scid5jS/h+AAoH3f/eQs8RlnN431mgJXmc117trcHzou8wDjhx/G4I4TviZtqWK6jqAaylvgat6cOpyY2o/BrSHKop8fiKRAwHZGE6vg==" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-06:00" level=debug msg="completed challenge" Feb 12 03:17:44 volumio go-librespot[30549]: time="2026-02-12T03:17:44-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 " Feb 12 03:17:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:46 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:46 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 12 03:17:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:47 volumio go-librespot[30557]: go-librespot daemon starting... Feb 12 03:17:47 volumio go-librespot[30558]: time="2026-02-12T03:17:47-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:47 volumio go-librespot[30558]: time="2026-02-12T03:17:47-06:00" level=debug msg="app state loaded" Feb 12 03:17:47 volumio go-librespot[30558]: time="2026-02-12T03:17:47-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:47 volumio go-librespot[30558]: time="2026-02-12T03:17:47-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-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]" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-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]" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-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]" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-06:00" level=info msg="zeroconf server listening on port 37823" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-06:00" level=debug msg="obtained new client token: AADvXH38J8ERxzIK5qI2qEws3pWOPDNXNU/cKccS9KOoXGCOhUek7DW9RKnEl7cK+eg/Vfh+4JUjh36imk+5zKP4fl2M+dKeNKEMbnKelzsdSH3FvwxQfPznERyz49esO055PIHKnrNzts8E26CHIDmMG9k081xHzv6pxoTgpca3/WyOEdK6Qzy/71D0bff1lrsyI8Jg830fQBWBeCpJuaIytSJqmKjN+BJXKwUq/WMw+COMZr8NLUc=" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-06:00" level=debug msg="completed challenge" Feb 12 03:17:48 volumio go-librespot[30558]: time="2026-02-12T03:17:48-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 " Feb 12 03:17:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:49 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:49 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 12 03:17:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:51 volumio go-librespot[30568]: go-librespot daemon starting... Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=debug msg="app state loaded" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-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]" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-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]" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-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]" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=info msg="zeroconf server listening on port 44133" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=debug msg="obtained new client token: AABLdVRnVyfgKKTM0Q883JtE/+QxNelsqAa2R8I40PTqu4AWi+LwBZvbkIi71+0NEEty+UohKAOfnP7YDUvzWn53PGRjDZYreJrQhjYlelRh7Cj0Y/Zef16bupcKDfYbreYtqCDgZfRgw5v65n5zGwvowmilnmMTZyOviB2Sbz933SH1RT+SprsQS2aKS6WRCYXtnPIW82KH3xavNVJTVf5ygculKQFTYUifWiYbtw/bpwBAy4Vjgc1CBQ==" Feb 12 03:17:51 volumio go-librespot[30569]: time="2026-02-12T03:17:51-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:52 volumio go-librespot[30569]: time="2026-02-12T03:17:52-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:52 volumio go-librespot[30569]: time="2026-02-12T03:17:52-06:00" level=debug msg="completed challenge" Feb 12 03:17:52 volumio go-librespot[30569]: time="2026-02-12T03:17:52-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 " Feb 12 03:17:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:52 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:52 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:55 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:55 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Feb 12 03:17:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:55 volumio go-librespot[30590]: go-librespot daemon starting... Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=debug msg="app state loaded" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-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]" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-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]" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-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]" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=info msg="zeroconf server listening on port 42617" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=debug msg="obtained new client token: AAA3BqKrFZ4eArWzvwl3x8+8K9wDlHvPIhnvULTGKAZeJSGRC8O8/IFaIwFLdzznBVUTFe3VsPWLdfDU+JIWFeM2wF9C5yZgWDE0v8O5khknvbFWTb1tj2n5bBRuOUukqJHkMTQV2vcx6m/Jzma4zkexJqZRORmLvl/ROCBawVt6QnwSDV49TZmQm4kJBrpq91iuCbzOa9G249gwywgyGAN2qZx/N+xT0j9gN+IcsyWCI5JGrHT2YMJ3kQ==" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-06:00" level=debug msg="completed challenge" Feb 12 03:17:55 volumio go-librespot[30591]: time="2026-02-12T03:17:55-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 " Feb 12 03:17:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:17:58 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:17:58 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:17:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Feb 12 03:17:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:17:59 volumio go-librespot[30598]: go-librespot daemon starting... Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=debug msg="app state loaded" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=debug msg="stored credentials not found" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-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]" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-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]" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-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]" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=info msg="zeroconf server listening on port 36965" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=debug msg="obtained new client token: AACDZ8AnkXnON4U7PqPknjZqsczh347hENvvxt0/JQUdfDoaHLyX8M7Qe4Nj6gI8CW76qnfbj/LjNx0w3UxH74s+OnBbtEVXALV4NGXZq3iKUvcuATPqIgvtyj4Vf0mDWwp48I1liz5UaaibzAFHb/B15k9oadz70scnSWFfbzVT4rAJBSUYbk/m8Qz7aXDLCDmOuW2MCEpp4viu0jeW/h07OrFjPZkrxBsI38Rid/4Iz5LFRtEhYGbWTA==" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-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" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=debug msg="completed keyexchange" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-06:00" level=debug msg="completed challenge" Feb 12 03:17:59 volumio go-librespot[30599]: time="2026-02-12T03:17:59-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 " Feb 12 03:17:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:17:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:01 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:01 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:18:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 12 03:18:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:02 volumio go-librespot[30620]: go-librespot daemon starting... Feb 12 03:18:02 volumio go-librespot[30621]: time="2026-02-12T03:18:02-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:02 volumio go-librespot[30621]: time="2026-02-12T03:18:02-06:00" level=debug msg="app state loaded" Feb 12 03:18:02 volumio go-librespot[30621]: time="2026-02-12T03:18:02-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:02 volumio go-librespot[30621]: time="2026-02-12T03:18:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-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]" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-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]" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-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]" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-06:00" level=info msg="zeroconf server listening on port 36863" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-06:00" level=debug msg="obtained new client token: AAD2GryWsKbYZc6sEt5u9rm2fnr3ZyWMCcAbUXwJFM0regaKQWH2MIvEV7HBTpnF/ijGe6JZx/qCtBkihTaLWTlNhhfFISvkU4WIJOaiHMgmSUH2lDabuShfheDkE1tTDTMWq1ziYy2v7mhU40ykHIH7PGyeU60L1p1CBtDevA/HgS0iUVbe2Auj+EcTjMhsFXqphh2Arm2rjHJblEROSbsXjtiv4ZyKt3r8uOoCg3a6bsVAPqWVz68=" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-06:00" level=debug msg="completed challenge" Feb 12 03:18:03 volumio go-librespot[30621]: time="2026-02-12T03:18:03-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 " Feb 12 03:18:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:04 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:04 volumio volumio[29658]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:18:06 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 12 03:18:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 12 03:18:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:06 volumio go-librespot[30641]: go-librespot daemon starting... Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=debug msg="app state loaded" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-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]" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-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]" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-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]" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=info msg="zeroconf server listening on port 44659" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=debug msg="obtained new client token: AABFpwVMpQdRHqx3nZLn6mkawziUh9GJvL/kP6gUGlhfIOx+8w/uVW04hEajZdcQkH2tfTpEDhvPVTZG6fp/3nkSPhfgc+pT+Y6jSOOIZ2caWDkWc7aYtklDx99RkuXRdKLuSc6tAOafvXfutsSo7x0jBWF601IADlaaap7CCAfhNyFS6ZV5Mq7/trD/+X10Owa5Di0WeRhVZiO/AdrEH50JmMmO7d9kYVGPJGv+br6ZARlYIkvfXDM4bw==" Feb 12 03:18:06 volumio go-librespot[30642]: time="2026-02-12T03:18:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:07 volumio volumio[29658]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:07 volumio go-librespot[30642]: time="2026-02-12T03:18:07-06:00" level=debug msg="new websocket client" Feb 12 03:18:07 volumio volumio[29658]: info: Connection to go-librespot Websocket established Feb 12 03:18:07 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 12 03:18:07 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 12 03:18:07 volumio go-librespot[30642]: time="2026-02-12T03:18:07-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:07 volumio go-librespot[30642]: time="2026-02-12T03:18:07-06:00" level=debug msg="completed challenge" Feb 12 03:18:07 volumio go-librespot[30642]: time="2026-02-12T03:18:07-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 " Feb 12 03:18:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:07 volumio volumio[29658]: info: Connection to go-librespot Websocket closed Feb 12 03:18:10 volumio volumio[29658]: info: Getting Spotify volume Feb 12 03:18:10 volumio volumio[29658]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 03:18:10 volumio volumio[29658]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:18:10 volumio volumio[29658]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 03:18:10 volumio volumio[29658]: errno: -111, Feb 12 03:18:10 volumio volumio[29658]: code: 'ECONNREFUSED', Feb 12 03:18:10 volumio volumio[29658]: syscall: 'connect', Feb 12 03:18:10 volumio volumio[29658]: address: '127.0.0.1', Feb 12 03:18:10 volumio volumio[29658]: port: 9879, Feb 12 03:18:10 volumio volumio[29658]: response: undefined Feb 12 03:18:10 volumio volumio[29658]: } Feb 12 03:18:10 volumio volumio[29658]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 03:18:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 12 03:18:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:10 volumio go-librespot[30678]: go-librespot daemon starting... Feb 12 03:18:10 volumio go-librespot[30679]: time="2026-02-12T03:18:10-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:10 volumio go-librespot[30679]: time="2026-02-12T03:18:10-06:00" level=debug msg="app state loaded" Feb 12 03:18:10 volumio go-librespot[30679]: time="2026-02-12T03:18:10-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:10 volumio go-librespot[30679]: time="2026-02-12T03:18:10-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:11 volumio sudo[30688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 03:17' Feb 12 03:18:11 volumio sudo[30688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18: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-gew4.spotify.com:80]" Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-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]" Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-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]" Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-06:00" level=info msg="zeroconf server listening on port 36451" Feb 12 03:18:11 volumio sudo[30688]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-06:00" level=debug msg="obtained new client token: AACjzOuONYrnmknwqMnzpa9Pz4AhXXIDAUnHkaaO14h8BRPZ8/4NfFO2HSg/QXSq4IDOzUJGAWJxr8dSjxAccjuB6OPNHfllz1qV6LaIs4O3C5uSBbw9o8ltwq26TPtumCkvYYsKKQZ4ogkNuIkJTSiVEze+6xAy2dHEh6RcjnqyK5K8cOLwWvs2351p9OwSmebZI5BVlNfj9E4neFrAN+80XrxHseHRl5u34h2Uxfo1DL2+2T3JIxo=" Feb 12 03:18:11 volumio volumio-remote-updater[2265]: [2026-02-12 03:18:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 12 03:18:11 volumio volumio-remote-updater[2265]: [2026-02-12 03:18:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 12 03:18:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 03:18:11 volumio systemd[1]: volumio.service: Consumed 55.308s CPU time. Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-06:00" level=debug msg="completed challenge" Feb 12 03:18:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 03:18:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 03:18:11 volumio go-librespot[30679]: time="2026-02-12T03:18:11-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 " Feb 12 03:18:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6118. Feb 12 03:18:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 03:18:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 03:18:11 volumio systemd[1]: volumio.service: Consumed 55.308s CPU time. Feb 12 03:18:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 03:18:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 03:18:13 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:13 volumio volumio[30700]: info: ----- Volumio3 ---- Feb 12 03:18:13 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:13 volumio volumio[30700]: info: ----- System startup ---- Feb 12 03:18:13 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:14 volumio volumio[30700]: info: MYVOLUMIO Environment detected Feb 12 03:18:14 volumio volumio[30700]: info: Plugin folders cleanup Feb 12 03:18:14 volumio volumio[30700]: info: Scanning into folder /volumio/app/plugins/ Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category audio_interface Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category miscellanea Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category music_service Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category plugins.json Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category system_controller Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category user_interface Feb 12 03:18:14 volumio volumio[30700]: info: Scanning into folder /data/plugins/ Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category audio_interface Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category music_service Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category system_controller Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category system_hardware Feb 12 03:18:14 volumio volumio[30700]: info: Scanning category user_interface Feb 12 03:18:14 volumio volumio[30700]: info: Plugin folders cleanup completed Feb 12 03:18:14 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:14 volumio volumio[30700]: info: ----- Core plugins startup ---- Feb 12 03:18:14 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:14 volumio volumio[30700]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 03:18:14 volumio volumio[30700]: info: Adding plugin upnp to MyMusic Plugins Feb 12 03:18:14 volumio volumio[30700]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 03:18:14 volumio volumio[30700]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 03:18:14 volumio volumio[30700]: info: Loading plugins from folder /data/plugins/ Feb 12 03:18:14 volumio volumio[30700]: info: Loading plugin "system"... Feb 12 03:18:14 volumio volumio[30700]: info: Loading plugin "appearance"... Feb 12 03:18:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 12 03:18:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:14 volumio go-librespot[30727]: go-librespot daemon starting... Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-06:00" level=debug msg="app state loaded" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-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]" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-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]" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-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]" Feb 12 03:18:14 volumio go-librespot[30728]: time="2026-02-12T03:18:14-06:00" level=info msg="zeroconf server listening on port 35551" Feb 12 03:18:15 volumio go-librespot[30728]: time="2026-02-12T03:18:15-06:00" level=debug msg="obtained new client token: AACFIryGoFB8F3B19X/e4TWi6KG5Tck7DmpMbLJN4kc3zHIzghsivuu8fy+dw1W/SbMwa0XEdBK88O5I2YOtCp9dgSjYQhGrpJWCAOamrvHBcOlv8Fot3YeJwGPLDjRkXzuCP/FYBQcrMB5wFp23XFWFP3JtRcH7dfa14HfM95w6iX8WiJ9qxZTTkIGmZr7fapezkBlH3Dl9S+ykOc+lML4vY5kw+DzuO5pQLzxYZci5UUbJXNw1zuL/tQ==" Feb 12 03:18:15 volumio go-librespot[30728]: time="2026-02-12T03:18:15-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" Feb 12 03:18:15 volumio go-librespot[30728]: time="2026-02-12T03:18:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 12 03:18:15 volumio go-librespot[30728]: time="2026-02-12T03:18:15-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:15 volumio go-librespot[30728]: time="2026-02-12T03:18:15-06:00" level=debug msg="completed challenge" Feb 12 03:18:15 volumio go-librespot[30728]: time="2026-02-12T03:18:15-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 " Feb 12 03:18:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:15 volumio volumio[30700]: info: Loading plugin "network"... Feb 12 03:18:15 volumio volumio[30700]: info: Refreshing Cached IP Addresses Feb 12 03:18:15 volumio sudo[30737]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 03:18:15 volumio sudo[30737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:15 volumio sudo[30739]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 03:18:15 volumio sudo[30737]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:15 volumio sudo[30739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:15 volumio volumio[30700]: info: Loading plugin "services"... Feb 12 03:18:15 volumio volumio[30700]: info: Loading plugin "alsa_controller"... Feb 12 03:18:15 volumio sudo[30739]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:15 volumio sudo[30747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 03:18:15 volumio sudo[30747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:15 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 03:18:15 volumio volumio[30700]: info: Loading plugin "wizard"... Feb 12 03:18:15 volumio volumio[30700]: info: Loading plugin "networkfs"... Feb 12 03:18:15 volumio volumio[30700]: info: Starting Udev Watcher for removable devices Feb 12 03:18:15 volumio volumio[30700]: info: Ignoring mount for partition: boot Feb 12 03:18:15 volumio volumio[30700]: info: Ignoring mount for partition: volumio Feb 12 03:18:15 volumio volumio[30700]: info: Ignoring mount for partition: volumio_data Feb 12 03:18:15 volumio volumio[30700]: info: Mounting Device Wikipedia Feb 12 03:18:15 volumio sudo[30776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 12 03:18:15 volumio sudo[30776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:15 volumio sudo[30776]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:15 volumio volumio[30700]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 12 03:18:15 volumio volumio[30700]: dmesg(1) may have more information after failed mount system call. Feb 12 03:18:16 volumio volumio[30700]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 12 03:18:16 volumio volumio[30700]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 12 03:18:16 volumio volumio[30700]: dmesg(1) may have more information after failed mount system call. Feb 12 03:18:16 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 03:18:16 volumio volumio[30700]: info: Loading plugin "volumio_command_line_client"... Feb 12 03:18:16 volumio volumio[30700]: info: Loading plugin "upnp"... Feb 12 03:18:16 volumio volumio[30700]: info: [1770887896029] Starting Upmpd Daemon Feb 12 03:18:16 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 03:18:16 volumio volumio[30700]: info: Loading plugin "my_music"... Feb 12 03:18:16 volumio volumio[30700]: info: Loading plugin "mpd"... Feb 12 03:18:16 volumio volumio-remote-updater[2265]: [2026-02-12 03:18:16] [connect] Successful connection Feb 12 03:18:16 volumio sudo[30747]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:16 volumio volumio[30700]: info: Loading plugin "upnp_browser"... Feb 12 03:18:16 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 03:18:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 12 03:18:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:18 volumio go-librespot[30779]: go-librespot daemon starting... Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-06:00" level=debug msg="app state loaded" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-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]" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-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]" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-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]" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-06:00" level=info msg="zeroconf server listening on port 36647" Feb 12 03:18:18 volumio go-librespot[30780]: time="2026-02-12T03:18:18-06:00" level=debug msg="obtained new client token: AAB90lxUOgWSTL5y+NvrwykKsoZq9+sDPRIHaaBDBEV72aa+v1pYUoX2S3zHQIxpVy9cn6yJWQY1KVXrzYVcgyMyjQ04mXm+hdqllPy3MDAAvyfYypW5VotZPPHFptMpz9BBpNzbwCNmar9Ni4KA6MrYWCiN5tqbU7kxDZoTzMw4tgps8vbn5JYwIwXjogWOdlBBZJTO6f1eLF7Ylxnh2QQBxNBOhqG6BJSHVtQ9Bhk3zmVEXAdlrqgLxw==" Feb 12 03:18:19 volumio go-librespot[30780]: time="2026-02-12T03:18:19-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" Feb 12 03:18:19 volumio go-librespot[30780]: time="2026-02-12T03:18:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 12 03:18:19 volumio go-librespot[30780]: time="2026-02-12T03:18:19-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:19 volumio go-librespot[30780]: time="2026-02-12T03:18:19-06:00" level=debug msg="completed challenge" Feb 12 03:18:19 volumio go-librespot[30780]: time="2026-02-12T03:18: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 " Feb 12 03:18:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "alarm-clock"... Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "airplay_emulation"... Feb 12 03:18:19 volumio volumio[30700]: info: Starting Shairport Sync Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "last_100"... Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "webradio"... Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "i2s_dacs"... Feb 12 03:18:19 volumio volumio[30700]: info: I2S DAC not set, start Auto-detection Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "volumiodiscovery"... Feb 12 03:18:19 volumio volumio[30700]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 03:18:19 volumio volumio[30700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 03:18:19 volumio volumio[30700]: *** WARNING *** For more information see Feb 12 03:18:19 volumio volumio[30700]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 03:18:19 volumio volumio[30700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 03:18:19 volumio volumio[30700]: *** WARNING *** For more information see Feb 12 03:18:19 volumio node[30700]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 03:18:19 volumio node[30700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 03:18:19 volumio node[30700]: *** WARNING *** For more information see Feb 12 03:18:19 volumio node[30700]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 03:18:19 volumio node[30700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 03:18:19 volumio node[30700]: *** WARNING *** For more information see Feb 12 03:18:19 volumio volumio[30700]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 03:18:19 volumio volumio[30700]: info: Discovery: Started advertising with name: Volumio Feb 12 03:18:19 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 03:18:19 volumio volumio[30700]: info: Loading plugin "bandcamp"... Feb 12 03:18:20 volumio volumio[30700]: info: Plugin calmradio is not enabled Feb 12 03:18:20 volumio volumio[30700]: info: Loading plugin "soundcloud"... Feb 12 03:18:21 volumio volumio[30700]: info: Loading plugin "spop"... Feb 12 03:18:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 12 03:18:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:22 volumio go-librespot[30789]: go-librespot daemon starting... Feb 12 03:18:22 volumio volumio[30700]: info: Loading plugin "squeezelite_mc"... Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=debug msg="app state loaded" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-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]" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-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]" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-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]" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=info msg="zeroconf server listening on port 46567" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=debug msg="obtained new client token: AACR4JIorRqlYfXw5YyFW9WfCMGYDDtHohPHOI9g5hwoSPNWnsAz9qLeoN+5m9BEEIU/gfu2kjtIzLGeQHIyenJ/0bwZHf3dEIabWeQseKk6cz/jNDEOAsl4IXNFWr8kZY9Ekaz9PWe4zAYovEPMEGaH2a+I/tsOU1fiCqU9WFeTWREUS1wvWzRwkbuXafXyyDi4avlgf88z0rac4WEcPlIP51EEgebSwiDsmpAqur5ocx86zHRpsGYQ+w==" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-06:00" level=debug msg="completed challenge" Feb 12 03:18:22 volumio go-librespot[30790]: time="2026-02-12T03:18:22-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 " Feb 12 03:18:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:23 volumio volumio[30700]: info: Loading plugin "youtube2"... Feb 12 03:18:23 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 03:18:23 volumio volumio[30700]: info: Loading plugin "ytcr"... Feb 12 03:18:25 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 03:18:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Feb 12 03:18:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:26 volumio go-librespot[30813]: go-librespot daemon starting... Feb 12 03:18:26 volumio volumio[30700]: info: Loading plugin "ytmusic"... Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=debug msg="app state loaded" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-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]" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-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]" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=info msg="zeroconf server listening on port 44533" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=debug msg="obtained new client token: AADsmSdgY9s3uieOBfEEOKSRhC+CbZx1kUv7g6UmNOmJE2yyFvrDOfbX6cD4oFCrzjTI/2yJL/u/9Z7nGVw/EIhTTPILioqPpgj2H2E6BSYMhLhxVwFI7I9yhJSS+UfuONeoDPC3pjR/0fOBihMZw623LNr+qW8Ol4MSVKKbjjN/9AoYF1pZ8gYsHvpOKRO/2og+dvl7IRKHd2+UonxAruDxaRxASf7cmrQN3OFdcnWe6Fkfnsp5RBy0Sg==" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-06:00" level=debug msg="completed challenge" Feb 12 03:18:26 volumio go-librespot[30814]: time="2026-02-12T03:18:26-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 " Feb 12 03:18:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:27 volumio volumio[30700]: info: Plugin now_playing is not enabled Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "outputs"... Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "albumart"... Feb 12 03:18:27 volumio volumio[30700]: info: Plugin example_plugin is not enabled Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "inputs"... Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "updater_comm"... Feb 12 03:18:27 volumio volumio[30700]: info: Plugin mpdemulation is not enabled Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "rest_api"... Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "websocket"... Feb 12 03:18:27 volumio volumio[30700]: info: Starting Socket.io Server version 1.7.4 Feb 12 03:18:27 volumio volumio[30700]: info: Plugin fusiondsp is not enabled Feb 12 03:18:27 volumio volumio[30700]: info: Plugin mpdoutput is not enabled Feb 12 03:18:27 volumio volumio[30700]: info: Plugin RoonBridge is not enabled Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "podcast"... Feb 12 03:18:27 volumio volumio[30700]: info: ControllerPodcast::constructor Feb 12 03:18:27 volumio volumio[30700]: info: Loading plugin "volusonic"... Feb 12 03:18:27 volumio volumio[30821]: Forking 3 albumart workers Feb 12 03:18:28 volumio volumio-remote-updater[2265]: [2026-02-12 03:18:28] [connect] Successful connection Feb 12 03:18:29 volumio volumio[30831]: Starting albumart workers Feb 12 03:18:29 volumio volumio[30700]: info: Applying required configuration parameters for plugin volusonic Feb 12 03:18:29 volumio volumio[30700]: info: Loading plugin "backup_restore"... Feb 12 03:18:29 volumio volumio[30833]: Starting albumart workers Feb 12 03:18:29 volumio volumio[30832]: Starting albumart workers Feb 12 03:18:29 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 03:18:29 volumio volumio[30700]: info: Applying required configuration parameters for plugin backup_restore Feb 12 03:18:29 volumio volumio[30700]: info: Plugin rpi_eeprom_config is not enabled Feb 12 03:18:29 volumio volumio[30700]: info: Plugin rpi_eeprom_updater is not enabled Feb 12 03:18:29 volumio volumio[30700]: info: Loading plugin "scheduledrestart"... Feb 12 03:18:29 volumio volumio[30700]: info: Applying required configuration parameters for plugin scheduledrestart Feb 12 03:18:29 volumio volumio[30700]: info: Plugin Bluetoothremote is not enabled Feb 12 03:18:29 volumio volumio[30700]: info: Plugin music_services_shield is not enabled Feb 12 03:18:29 volumio volumio[30700]: info: Loading plugin "Systeminfo"... Feb 12 03:18:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Feb 12 03:18:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:30 volumio go-librespot[30871]: go-librespot daemon starting... Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=debug msg="app state loaded" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:30 volumio volumio[30700]: info: Plugin peppymeterbasic is not enabled Feb 12 03:18:30 volumio volumio[30700]: info: Loading plugin "peppyspectrum"... Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-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]" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-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]" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=info msg="zeroconf server listening on port 41949" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=debug msg="obtained new client token: AABwh7deLQhZY9QThLMSSq8aACVBeILoGdgRJvXA9nCn3PANRrkqQyziYOztuS0w19Q6AWn+ztpqYNTW89exWP8mb/1Fr9bNAoMOhWOH9RVdP4Lylszk+VQKKesrZ2Kmtt67CKKoj6hUV1tho0JqLHIizrgeXUOO6smn81Nh7vWYeKMfYARY32rc9cp9spBQZFUl2k3MHenvOn/MAwoyDOeYQ+B0nIFxRfgi+45h6HfQo8xgmUaMLarB6w==" Feb 12 03:18:30 volumio volumio[30700]: info: Loading i18n strings for locale en Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:30 volumio volumio[30700]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 12 03:18:30 volumio volumio[30700]: Updating browse sources language Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18:30-06:00" level=debug msg="completed challenge" Feb 12 03:18:30 volumio go-librespot[30878]: time="2026-02-12T03:18: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 " Feb 12 03:18:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::initPlayerControls Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 03:18:30 volumio volumio[30700]: Express server listening on port 3000 Feb 12 03:18:30 volumio volumio[30700]: [Metrics] WebUI: 17s 716.74ms Feb 12 03:18:30 volumio volumio[30700]: info: CoreStateMachine::resetVolumioState Feb 12 03:18:30 volumio volumio[30700]: info: CoreStateMachine::getcurrentVolume Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 03:18:30 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:30 volumio sudo[30897]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 03:18:30 volumio sudo[30897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:30 volumio sudo[30899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 03:18:30 volumio sudo[30899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:30 volumio sudo[30897]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:30 volumio sudo[30899]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:30 volumio volumio[30700]: info: Volumio Network Manager: Network status updated: 1 Feb 12 03:18:31 volumio volumio[30700]: info: VolumeController:: Volume=100 Mute =false Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::pushState Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioPushState Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::updateTrackBlock Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrackBlock Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 03:18:31 volumio volumio-remote-updater[2265]: [2026-02-12 03:18:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770887908 101 Feb 12 03:18:31 volumio volumio[30700]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 03:18:31 volumio volumio[30700]: info: Reloading queue from file Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::setRepeat null single undefined Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::pushState Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioPushState Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::setRandom null Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::pushState Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioPushState Feb 12 03:18:31 volumio volumio[30700]: info: Setting Device type: Raspberry PI Feb 12 03:18:31 volumio volumio[30700]: info: VolumeController:: Volume=100 Mute =false Feb 12 03:18:31 volumio volumio[30700]: info: CoreStateMachine::pushState Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioPushState Feb 12 03:18:31 volumio volumio[30700]: info: Completed loading Core Plugins Feb 12 03:18:31 volumio volumio[30700]: info: Preparing to generate the ALSA configuration file Feb 12 03:18:31 volumio sudo[30912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 03:18:31 volumio sudo[30912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:31 volumio volumio[30700]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 12 03:18:31 volumio volumio[30700]: info: Discovery: Found device Volumio Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioGetState Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:31 volumio volumio[30700]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 12 03:18:31 volumio volumio[30700]: info: Discovery: Found device Volumio Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioGetState Feb 12 03:18:31 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:31 volumio volumio[30700]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 03:18:31 volumio volumio[30700]: info: Reading ALSA contributions from plugins. Feb 12 03:18:31 volumio sudo[30912]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:31 volumio volumio[30700]: info: Upmpdcli Daemon Started Feb 12 03:18:31 volumio volumio[30700]: info: Asound.conf file unchanged, so no further update is needed Feb 12 03:18:31 volumio volumio[30700]: info: Output device has changed, restarting MPD Feb 12 03:18:31 volumio volumio[30700]: info: Output device has changed, restarting Shairport Sync Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:31 volumio sudo[30915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 03:18:31 volumio sudo[30915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:31 volumio sudo[30915]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:31 volumio sudo[30917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 03:18:31 volumio sudo[30917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:31 volumio volumio[30700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 03:18:31 volumio volumio[30700]: info: ___________ START PLUGINS ___________ Feb 12 03:18:31 volumio volumio[30700]: info: ControllerMpd::onStart: Initializing MPD Feb 12 03:18:31 volumio volumio[30700]: info: Creating MPD Configuration file Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio sudo[30925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911362] CoreMusicLibrary::Adding element Media Servers Feb 12 03:18:31 volumio sudo[30925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 03:18:31 volumio sudo[30925]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:31 volumio sudo[30927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 03:18:31 volumio sudo[30927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:31 volumio volumio[30700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:31 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 03:18:31 volumio volumio[30700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 03:18:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 03:18:31 volumio systemd[1]: mpd.service: Consumed 4.082s CPU time. Feb 12 03:18:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 03:18:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 03:18:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911486] CoreMusicLibrary::Adding element Last_100 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911495] CoreMusicLibrary::Adding element Webradio Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 03:18:31 volumio volumio[30700]: info: Initializing BBC Radios Feb 12 03:18:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 03:18:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911578] CoreMusicLibrary::Adding element Bandcamp Discover Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911590] CoreMusicLibrary::Adding element SoundCloud Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:31 volumio volumio[30700]: info: Creating Spotify config file Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:31 volumio sudo[30948]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 03:18:31 volumio sudo[30948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 03:18:31 volumio sudo[30948]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:31 volumio volumio[30700]: info: [squeezelite_mc] Starting proxy server... Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911940] CoreMusicLibrary::Adding element YouTube2 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911958] CoreMusicLibrary::Adding element YouTube Music Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source YouTube Music Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:31 volumio volumio[30700]: info: [1770887911970] CoreMusicLibrary::Adding element Podcast Feb 12 03:18:31 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source YouTube Music Feb 12 03:18:31 volumio volumio[30700]: Cannot find translation for source Podcast Feb 12 03:18:32 volumio volumio[30700]: info: Loading i18n strings for locale en Feb 12 03:18:32 volumio volumio[30700]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 12 03:18:32 volumio volumio[30700]: Updating browse sources language Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source YouTube Music Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Podcast Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source YouTube Music Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Podcast Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:32 volumio volumio[30700]: info: [1770887912018] CoreMusicLibrary::Adding element Volusonic Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source YouTube Music Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Podcast Feb 12 03:18:32 volumio volumio[30700]: Cannot find translation for source Volusonic Feb 12 03:18:32 volumio volumio[30700]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 12 03:18:32 volumio volumio[30700]: info: Volumio Calling Home Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Proxy server started on port 39239 Feb 12 03:18:32 volumio volumio[30700]: info: Preparing to generate the ALSA configuration file Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 03:18:32 volumio volumio[30700]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 03:18:32 volumio volumio[30700]: info: Reading ALSA contributions from plugins. Feb 12 03:18:32 volumio volumio[30700]: info: MPD Permissions set Feb 12 03:18:32 volumio volumio[30700]: info: MPD Permissions set Feb 12 03:18:32 volumio volumio[30700]: info: Spotify config file written Feb 12 03:18:32 volumio sudo[30963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 03:18:32 volumio sudo[30963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:32 volumio volumio[30700]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 12 03:18:32 volumio volumio[30700]: info: VolumeController:: Volume=100 Mute =false Feb 12 03:18:32 volumio volumio[30700]: info: CoreStateMachine::pushState Feb 12 03:18:32 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::volumioPushState Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Server discovery started Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Player finder started Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 12 03:18:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:32 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:32 volumio go-librespot[30966]: go-librespot daemon starting... Feb 12 03:18:32 volumio volumio[30700]: info: No need to fix Spotify hosts Feb 12 03:18:32 volumio sudo[30963]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-06:00" level=debug msg="app state loaded" Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:32 volumio volumio[30700]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 12 03:18:32 volumio volumio[30700]: info: Volumio called home Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-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]" Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-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]" Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-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]" Feb 12 03:18:32 volumio volumio[30700]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-06:00" level=info msg="zeroconf server listening on port 35409" Feb 12 03:18:32 volumio volumio[30700]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 03:18:32 volumio volumio[30700]: SPOTIFY: BQAfLcwlxQs-twjUWUZBGQZepSIWE7PIyHUrhP9NaDNGkKyu0ZzGp-cTF6SrNzQsT7nn02toIbTT0IrX1qOSwb54_kIdkukLCUJwWaLu9o-xeCLOjwCxn0s6a5DuhTrKntIwPwynmWYrPTSji1RYuG1U2WJ9kR5rSY9wWchDafIm5A7FdKc-TDyLRMdxJXNrPUGpV4juao4yf0BfcyH_lxclnZtdUi7GxXgDj7c Feb 12 03:18:32 volumio volumio[30700]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 03:18:32 volumio volumio[30700]: info: New Spotify access token = BQAfLcwlxQs-twjUWUZBGQZepSIWE7PIyHUrhP9NaDNGkKyu0ZzGp-cTF6SrNzQsT7nn02toIbTT0IrX1qOSwb54_kIdkukLCUJwWaLu9o-xeCLOjwCxn0s6a5DuhTrKntIwPwynmWYrPTSji1RYuG1U2WJ9kR5rSY9wWchDafIm5A7FdKc-TDyLRMdxJXNrPUGpV4juao4yf0BfcyH_lxclnZtdUi7GxXgDj7c Feb 12 03:18:32 volumio volumio[30700]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 03:18:32 volumio volumio[30700]: info: Starting Shairport Sync Feb 12 03:18:32 volumio sudo[30988]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 12 03:18:32 volumio volumio[30700]: info: Starting Shairport Sync Feb 12 03:18:32 volumio sudo[30988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:32 volumio volumio[30700]: info: Starting Shairport Sync Feb 12 03:18:32 volumio sudo[30988]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:32 volumio go-librespot[30978]: time="2026-02-12T03:18:32-06:00" level=debug msg="obtained new client token: AADqrB99tkL44lOOMt1bEGrklihDA7wh2nDEjSLPtDt8vTV3qH0OQB0R5/yY+LQFwPBvIJJPvbcXMPPJH+XEmcOH0K5NSLkzeCE5FoNt/K3MG1FBlNep5laVkVGuwA+IZoTg5OcDNGZOUQB+tP5xMCYEV+3l3lB4OoRjKiIkI+MgQ5agI98gFuls2+AstUSDab3P/+z4Xv/ZtFm1rIYFRNBWFXjeIt/qp6Sse+bVaU7hPkRSJpkyECBvzw==" Feb 12 03:18:32 volumio sudo[30990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 03:18:32 volumio sudo[30990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio sudo[30993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 03:18:33 volumio sudo[30993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio sudo[30995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 03:18:33 volumio sudo[30995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio go-librespot[30978]: time="2026-02-12T03:18:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:33 volumio volumio[30700]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 12 03:18:33 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 03:18:33 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 03:18:33 volumio volumio[30700]: info: Asound.conf file unchanged, so no further update is needed Feb 12 03:18:33 volumio volumio[30700]: info: Output device has changed, restarting MPD Feb 12 03:18:33 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 03:18:33 volumio systemd[1]: shairport-sync.service: Consumed 2.485s CPU time. Feb 12 03:18:33 volumio go-librespot[30978]: time="2026-02-12T03:18:33-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:33 volumio go-librespot[30978]: time="2026-02-12T03:18:33-06:00" level=debug msg="completed challenge" Feb 12 03:18:33 volumio volumio[30700]: info: Output device has changed, restarting Shairport Sync Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:33 volumio sudo[31002]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 12 03:18:33 volumio sudo[31002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio go-librespot[30978]: time="2026-02-12T03:18:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 03:18:33 volumio sudo[31004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 03:18:33 volumio sudo[31006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 03:18:33 volumio sudo[31006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio sudo[31004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 03:18:33 volumio sudo[30990]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:33 volumio sudo[30993]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:33 volumio sudo[31002]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:33 volumio sudo[30995]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:33 volumio sudo[31004]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:33 volumio volumio[30700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 03:18:33 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 03:18:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 03:18:33 volumio systemd[1]: mpd.service: Consumed 1.637s CPU time. Feb 12 03:18:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 03:18:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 03:18:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 03:18:33 volumio volumio[30700]: info: MPD Permissions set Feb 12 03:18:33 volumio volumio[30700]: info: Shairport-Sync Started Feb 12 03:18:33 volumio volumio[30700]: Error adding Membership: Error: addMembership EINVAL Feb 12 03:18:33 volumio volumio[30700]: info: Shairport-Sync Started Feb 12 03:18:33 volumio volumio[30700]: info: Shairport-Sync Started Feb 12 03:18:33 volumio volumio[30700]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 12 03:18:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 03:18:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 03:18:33 volumio sudo[31034]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 12 03:18:33 volumio sudo[31034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio sudo[31034]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:33 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::volumioGetState Feb 12 03:18:33 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:33 volumio sudo[31044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 12 03:18:33 volumio sudo[31044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio volumio[30700]: info: Starting Shairport Sync Feb 12 03:18:33 volumio systemd[1]: Reloading. Feb 12 03:18:33 volumio sudo[31047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 03:18:33 volumio sudo[31047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:33 volumio volumio[30700]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Feb 12 03:18:33 volumio volumio[30700]: info: Spotify Successfully logged in Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 03:18:33 volumio volumio[30700]: info: [1770887913727] CoreMusicLibrary::Adding element Spotify Feb 12 03:18:33 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source Bandcamp Discover Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source SoundCloud Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source YouTube2 Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source YouTube Music Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source Podcast Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source Volusonic Feb 12 03:18:33 volumio volumio[30700]: Cannot find translation for source Spotify Feb 12 03:18:33 volumio sudo[31036]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 03:18:33 volumio sudo[31036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 03:18:33 volumio sudo[31036]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:34 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:34 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:34 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 03:18:34 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 03:18:35 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 03:18:35 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 03:18:35 volumio sudo[31044]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:35 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:35 volumio sudo[31069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:35 volumio sudo[31069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 03:18:35 volumio sudo[31069]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 03:18:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 03:18:35 volumio systemd[1]: shairport-sync.service: Consumed 1.543s CPU time. Feb 12 03:18:35 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 12 03:18:35 volumio sudo[31073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 12 03:18:35 volumio sudo[31073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 03:18:35 volumio sudo[31047]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:35 volumio volumio[30700]: info: Shairport-Sync Started Feb 12 03:18:35 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 12 03:18:35 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 12 03:18:35 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 12 03:18:35 volumio volumio[30700]: info: go-librespot daemon successfully initialized Feb 12 03:18:35 volumio sudo[31073]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Feb 12 03:18:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:36 volumio go-librespot[31103]: go-librespot daemon starting... Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="app state loaded" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:36 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:36 volumio sudo[31112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:36 volumio sudo[31112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:36 volumio sudo[31112]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:36 volumio volumio[30700]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 12 03:18:36 volumio sudo[31116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 12 03:18:36 volumio sudo[31116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:36 volumio sudo[31116]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:36 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 12 03:18:36 volumio sudo[31120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 12 03:18:36 volumio sudo[31120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=info msg="zeroconf server listening on port 40413" Feb 12 03:18:36 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 12 03:18:36 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="obtained new client token: AABCUm9Y/CGzs5Z8UwcfZwe8MKs4tFaJd8PAl+1MErnMF2BhrsT53noOce+iUXQhfS7likYQ0JnzLD574oZ0Ycp38Q829BDa3Pj6NbQjC1aZmzGye+oQg1fyDlJbi9Rd7IbyMN2tY5313idTbaKK65dtCLRAB+jeYy/xO2yzOPXyvmQiIurPMNedj4OvSlheNfjKuEU6/I/85yXvFkeeAPr4jU9zxwC4Do382G0j+dj4zVGPeqckyhoB1Q==" Feb 12 03:18:36 volumio sudo[31120]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:36 volumio go-librespot[31104]: time="2026-02-12T03:18:36-06:00" level=debug msg="completed challenge" Feb 12 03:18:37 volumio go-librespot[31104]: time="2026-02-12T03:18:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 03:18:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:37 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:37 volumio sudo[31127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:37 volumio sudo[31127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:37 volumio sudo[31127]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:37 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:38 volumio sudo[31130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:38 volumio sudo[31130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:38 volumio sudo[31130]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:38 volumio mpd[31067]: 2026-02-12T03:18:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 03:18:38 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:38 volumio sudo[31133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:38 volumio sudo[31133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:38 volumio sudo[31133]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 03:18:38 volumio sudo[30927]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:38 volumio sudo[31006]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:38 volumio sudo[30917]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:38 volumio volumio[30700]: error: MPD error: The expression evaluated to a falsy value: Feb 12 03:18:38 volumio volumio[30700]: assert.ok(self.idling) Feb 12 03:18:38 volumio volumio[30700]: error: The expression evaluated to a falsy value: Feb 12 03:18:38 volumio volumio[30700]: assert.ok(self.idling) Feb 12 03:18:38 volumio volumio[30700]: error: MPD error: The expression evaluated to a falsy value: Feb 12 03:18:38 volumio volumio[30700]: assert.ok(self.idling) Feb 12 03:18:38 volumio volumio[30700]: error: The expression evaluated to a falsy value: Feb 12 03:18:38 volumio volumio[30700]: assert.ok(self.idling) Feb 12 03:18:38 volumio volumio[30700]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:38 volumio volumio[30700]: info: MPD running with PID31067 Feb 12 03:18:38 volumio volumio[30700]: ,establishing connection Feb 12 03:18:38 volumio volumio[30700]: error: updateQueue error: null Feb 12 03:18:38 volumio volumio[30700]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:18:38 volumio volumio[30700]: error: updateQueue error: null Feb 12 03:18:39 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:39 volumio sudo[31140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:39 volumio sudo[31140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:39 volumio sudo[31140]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:39 volumio volumio[30700]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 03:18:39 volumio sudo[31143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 03:18:39 volumio sudo[31143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:39 volumio sudo[31143]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:39 volumio volumio[30700]: info: Completed starting Core Plugins Feb 12 03:18:39 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:39 volumio volumio[30700]: info: ----- MyVolumio plugins startup ---- Feb 12 03:18:39 volumio volumio[30700]: info: ------------------------------------------- Feb 12 03:18:39 volumio volumio[30700]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 03:18:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Feb 12 03:18:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:40 volumio go-librespot[31145]: go-librespot daemon starting... Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="app state loaded" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-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]" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-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]" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=info msg="zeroconf server listening on port 34975" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="obtained new client token: AAAinzQGrcd5tTDMfUnTaXVMhuxIRfeB0ON//oXfcYiwWcE5vZuIrerwIXIe0v4eWo2K/81S+cMGaQsuIW6Hyx1+npEAysZKlHWrzB4vkMRjQVpy1fbJW4VV9rdr7QQc33+Xvtf9gd0s6E6h42m0uBjn4c5vZyRux44UGWk+EaQvI0oa1Muq6ATD85CryvgvKjUeUeZwCAlTUQtbuUzV6gSJqPVpoIiStBXrwT7Ebie0Rj+G/zwiXT8EFA==" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=debug msg="completed challenge" Feb 12 03:18:40 volumio go-librespot[31146]: time="2026-02-12T03:18:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 12 03:18:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:41 volumio sudo[31154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 03:18:41 volumio sudo[31154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:41 volumio sudo[31156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 03:18:41 volumio sudo[31156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:41 volumio sudo[31159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 03:18:41 volumio sudo[31159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:41 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 12 03:18:41 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 12 03:18:41 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 12 03:18:41 volumio volumio[30700]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:41 volumio volumio[30700]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:18:41 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 12 03:18:41 volumio sudo[31156]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:41 volumio mpd_monitor.sh[31162]: MPD Monitor Service: Starting MPD Monitor Service Feb 12 03:18:41 volumio sudo[31159]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:41 volumio sudo[31154]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:41 volumio volumio[30700]: info: Successfully started MPD Monitor Feb 12 03:18:41 volumio volumio[30700]: info: Successfully started MPD Monitor Feb 12 03:18:41 volumio volumio[30700]: info: Successfully started MPD Monitor Feb 12 03:18:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Feb 12 03:18:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:44 volumio go-librespot[31166]: go-librespot daemon starting... Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=debug msg="app state loaded" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-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]" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-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]" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-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]" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=info msg="zeroconf server listening on port 43093" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=debug msg="obtained new client token: AAA4nF7N9b74h4+J78HuhLZqdNQZ9ff5NhtPUN3spsXyl7cCtg41+onQew0WK34L3S9/YRXjKS8PlX+X5nUAz4OYJbDNOZk2Y2cKG2HHq9oySV08A78vPLS/1fnmEirO97JnWeKsYW0of0PTjSK3ODGJUGroJSWskgYrRsvlIUvS7fCN3Yx35hBY/hWHAIWlHTQeSR/n8xdCCDwqfjG4RHZMWnHivLxsNhsdct5X1zxszAIVP0Jq4M5UFg==" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-06:00" level=debug msg="completed challenge" Feb 12 03:18:44 volumio go-librespot[31167]: time="2026-02-12T03:18:44-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 " Feb 12 03:18:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:44 volumio volumio[30700]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:45 volumio volumio[30700]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 03:18:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 03:18:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Feb 12 03:18:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:47 volumio go-librespot[31190]: go-librespot daemon starting... Feb 12 03:18:47 volumio go-librespot[31191]: time="2026-02-12T03:18:47-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:47 volumio go-librespot[31191]: time="2026-02-12T03:18:47-06:00" level=debug msg="app state loaded" Feb 12 03:18:47 volumio go-librespot[31191]: time="2026-02-12T03:18:47-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:47 volumio go-librespot[31191]: time="2026-02-12T03:18:47-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-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]" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-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]" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-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]" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-06:00" level=info msg="zeroconf server listening on port 35721" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-06:00" level=debug msg="obtained new client token: AAAjfgS93aUwm6wrEMpOdTAgGL0SW3Q7hzhK1oUJx1qS7l+d43tZ9OXA6rqzbBC6/hcu+z2JTTdzGum9qCrIAuDDGor14FYn6F40sgmGs9bZJqp9NYK1EitOuOS3HAohQkoLKEnqtTHIsvOKSPGukMF3o0PVvIxXx9iitSShtqkh2f+lKVebzUTNVfN1iXtxO/X3EH5RtTH+0SUd1o0qkvpSJbn02TouPyYxvP6qIxkvyCTfdl8+FJshcA==" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-06:00" level=debug msg="completed challenge" Feb 12 03:18:48 volumio volumio[30700]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-06:00" level=debug msg="new websocket client" Feb 12 03:18:48 volumio go-librespot[31191]: time="2026-02-12T03:18:48-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 " Feb 12 03:18:48 volumio volumio[30700]: info: Connection to go-librespot Websocket established Feb 12 03:18:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:48 volumio volumio[30700]: info: Connection to go-librespot Websocket closed Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin bluetooth to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin multiroom to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin metavolumio to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin cd_controller to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 12 03:18:48 volumio volumio[30700]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 12 03:18:49 volumio volumio[30700]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 03:18:49 volumio volumio[30700]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 03:18:49 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:49 volumio volumio[30700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 03:18:49 volumio volumio[30700]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 03:18:49 volumio volumio[30700]: info: MyVolumio login type: Token Feb 12 03:18:49 volumio volumio[30700]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 03:18:49 volumio volumio[30700]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 03:18:50 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 03:18:50 volumio volumio[30700]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 03:18:50 volumio volumio[30700]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 03:18:50 volumio volumio[30700]: info: Streaming services startup Feb 12 03:18:50 volumio volumio[30700]: info: Starting Streaming Daemon Feb 12 03:18:50 volumio sudo[31200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 03:18:50 volumio sudo[31200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:50 volumio volumio[30700]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 03:18:50 volumio sudo[31200]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:50 volumio volumio[30700]: error: Cannot start Volumio Streaming Daemon Feb 12 03:18:50 volumio volumio[30700]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 03:18:50 volumio volumio[30700]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 03:18:51 volumio volumio[30700]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 12 03:18:51 volumio volumio[30700]: info: MyVolumio token set successfully Feb 12 03:18:51 volumio volumio[30700]: info: MYVOLUMIO: Adding device Feb 12 03:18:51 volumio volumio[30700]: info: MYVOLUMIO: Evaluating Server Feb 12 03:18:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Feb 12 03:18:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 03:18:51 volumio go-librespot[31222]: go-librespot daemon starting... Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-06:00" level=debug msg="app state loaded" Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-06:00" level=debug msg="stored credentials not found" Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 03:18:51 volumio volumio[30700]: info: Getting Spotify volume Feb 12 03:18:51 volumio volumio[30700]: info: Initializing connection to go-librespot Websocket Feb 12 03:18:51 volumio volumio[30700]: info: MyVolumio status changed Feb 12 03:18:51 volumio volumio[30700]: info: Streaming services startup Feb 12 03:18:51 volumio volumio[30700]: info: Starting Streaming Daemon Feb 12 03:18:51 volumio volumio[30700]: info: Removing browser output: myVolumio user plan is not superstar Feb 12 03:18:51 volumio volumio[30700]: info: Removing audio output: Feb 12 03:18:51 volumio volumio[30700]: info: Stoppping Tunnel 1 Feb 12 03:18:51 volumio sudo[31235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 03:18:51 volumio sudo[31235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:51 volumio sudo[31237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 12 03:18:51 volumio sudo[31237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-06:00" level=debug msg="new websocket client" Feb 12 03:18:51 volumio volumio[30700]: info: Connection to go-librespot Websocket established Feb 12 03:18:51 volumio volumio[30700]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 12 03:18:51 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. Feb 12 03:18:51 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. Feb 12 03:18:51 volumio sudo[31235]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:51 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. Feb 12 03:18:51 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. Feb 12 03:18:51 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. Feb 12 03:18:51 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. Feb 12 03:18:51 volumio volumio[30700]: error: Cannot start Volumio Streaming Daemon Feb 12 03:18:51 volumio volumio[30700]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 03:18:51 volumio volumio[30700]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 03:18:51 volumio sudo[31237]: pam_unix(sudo:session): session closed for user root Feb 12 03:18:51 volumio volumio[30700]: info: Remote SSH Stopped Feb 12 03:18:51 volumio volumio[30700]: info: CoreCommandRouter::volumioGetState Feb 12 03:18:51 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:51 volumio volumio[30700]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 12 03:18:51 volumio volumio[30700]: SPOTIFY: SPOTIFY VOLUME undefined Feb 12 03:18:51 volumio volumio[30700]: SPOTIFY: VOLUMIO VOLUME 100 Feb 12 03:18:51 volumio volumio[30700]: info: Aligning Spotify Volume to Volumio Volume Feb 12 03:18:51 volumio volumio[30700]: info: CoreCommandRouter::volumioGetState Feb 12 03:18:51 volumio volumio[30700]: info: CorePlayQueue::getTrack 0 Feb 12 03:18:51 volumio volumio[30700]: info: Setting Spotify Volume from Volumio: 100 Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-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]" Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-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]" Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-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]" Feb 12 03:18:51 volumio go-librespot[31228]: time="2026-02-12T03:18:51-06:00" level=info msg="zeroconf server listening on port 38343" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-06:00" level=debug msg="obtained new client token: AADyzLEAxiPe1tlKV+K7HEwLMW4Jr9bidcT0RcwkZ+05SwIPC3v8UqNDnglMi6RY/QwZA4ecPLWi8KoT51/tpGKYwpdCVtjQ4DJ1yVnxd3hOGnYtv+m9KOBPjSy+8BMFrSYZCZbTaVHz7Ycrxhr3k9dycdcXmwMry3x3bFLq52ZHScir+cM/bOeVroxwRbAgcL2/+0NnOM7VGPvwSHfZv0dWHRE9hbG9m0ji5pGz6iWH0sqQujN1rhg=" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-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" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-06:00" level=debug msg="completed keyexchange" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-06:00" level=debug msg="completed challenge" Feb 12 03:18:52 volumio go-librespot[31228]: time="2026-02-12T03:18:52-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 " Feb 12 03:18:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 03:18:52 volumio volumio[30700]: info: Connection to go-librespot Websocket closed Feb 12 03:18:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 03:18:52 volumio volumio[30700]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 03:18:52 volumio volumio[30700]: Error: socket hang up Feb 12 03:18:52 volumio volumio[30700]: at connResetException (node:internal/errors:720:14) Feb 12 03:18:52 volumio volumio[30700]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 12 03:18:52 volumio volumio[30700]: at Socket.emit (node:events:526:35) Feb 12 03:18:52 volumio volumio[30700]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 12 03:18:52 volumio volumio[30700]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 12 03:18:52 volumio volumio[30700]: code: 'ECONNRESET', Feb 12 03:18:52 volumio volumio[30700]: response: undefined Feb 12 03:18:52 volumio volumio[30700]: } Feb 12 03:18:52 volumio volumio[30700]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 03:18:53 volumio sudo[31255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 03:17' Feb 12 03:18:53 volumio sudo[31255]: 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"