Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin bluetooth to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin multiroom to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin metavolumio to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin cd_controller to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 26 14:47:00 vol volumio[1270]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 26 14:47:01 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 26 14:47:01 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:01 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:01 vol go-librespot[1704]: go-librespot daemon starting... Dec 26 14:47:01 vol go-librespot[1705]: time="2025-12-26T14:47:01+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:01 vol go-librespot[1705]: time="2025-12-26T14:47:01+09:00" level=debug msg="app state loaded" Dec 26 14:47:01 vol go-librespot[1705]: time="2025-12-26T14:47:01+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=info msg="zeroconf server listening on port 34417" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="obtained new client token: AAANe2UpIGkGmY3crqaT0216Rf8sAjgqhU+Ir8h32K0I04Nnz1whC+0FCw1mdD9ehFUcLdKq01TjnkZVsoBd7Rbs/lk/CV5W9OSfEiqazDnQqYxY3OuHWDDtGKxPqCFxwKGY4wEaTvVw1h5bF2v9hWXJ3Bi/UJi1+C/9yud3ZkK6mHysXPSr6Y01g+CUoPQ0xbUbPiEFSul2vTTQ/SOBIlhC89U/kJtDjQlUsncAB/tkEcuBDF22eg==" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=debug msg="completed challenge" Dec 26 14:47:02 vol go-librespot[1705]: time="2025-12-26T14:47:02+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:02 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:02 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:05 vol volumio[1270]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 26 14:47:05 vol volumio[1270]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 26 14:47:05 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:05 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:05 vol volumio[1270]: info: Starting MyVolumio Remote Streaming Endpoints Dec 26 14:47:05 vol volumio[1270]: info: MyVolumio login type: Token Dec 26 14:47:05 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 26 14:47:05 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:05 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:05 vol go-librespot[1712]: go-librespot daemon starting... Dec 26 14:47:05 vol go-librespot[1713]: time="2025-12-26T14:47:05+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:05 vol volumio[1270]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 26 14:47:05 vol volumio[1270]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 26 14:47:05 vol go-librespot[1713]: time="2025-12-26T14:47:05+09:00" level=debug msg="app state loaded" Dec 26 14:47:05 vol go-librespot[1713]: time="2025-12-26T14:47:05+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=info msg="zeroconf server listening on port 45159" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="obtained new client token: AACVoKRfuhlrvNH3kgG2cpvCf+HyywpxhQL+iOOUElfQ2Kx60hyGBZNuEEl72MG2knepzJoa2GOiKPsVaJcNsSKoIrKlKqeQio0g6+HPniGMzwf1/WzzSTN1ZP07Od83py1BE/wQRFD2K/LEdiDonZiQ+vAMRgpR2tZA5sbG1RQZev96ZM9vMpdrGybhfkLeSc3plxk3eJitsBALgtcbyibWSjGAefkxGIVOoe0WT3CLofQWWHjrJQ==" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=debug msg="completed challenge" Dec 26 14:47:06 vol go-librespot[1713]: time="2025-12-26T14:47:06+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:06 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:06 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:09 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 26 14:47:09 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:09 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:09 vol go-librespot[1735]: go-librespot daemon starting... Dec 26 14:47:09 vol go-librespot[1736]: time="2025-12-26T14:47:09+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:09 vol go-librespot[1736]: time="2025-12-26T14:47:09+09:00" level=debug msg="app state loaded" Dec 26 14:47:09 vol go-librespot[1736]: time="2025-12-26T14:47:09+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:09+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:09+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:09+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:10+09:00" level=info msg="zeroconf server listening on port 45183" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:10+09:00" level=debug msg="obtained new client token: AAAQx4FRxhXnEyXEfJJ9A/lM6X+tsbcoIriqMSiv5HuSAnI+NeEo8iHsscMPpa+EoVP9O16aRJWbyQgtJBy1dfnRmSg2Kg4NLVHOWvU1DiNx7KU3IfFzUHG++mC76rspNqGsbtPZnbDRCjfvVfxFOcIe9pmVuZliaBqLSmwnLnJFN5h7R4R9RAAAcdaM/GJG20W/Iv9JDe4OK6NMb3wY5pdclTtBM1uvvtYRbvw9/A2zVLErnN9SxQ==" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:10+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:10+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:10+09:00" level=debug msg="completed challenge" Dec 26 14:47:10 vol go-librespot[1736]: time="2025-12-26T14:47:10+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:10 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:10 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:10 vol upmpdcli[1743]: writing RSA key Dec 26 14:47:11 vol volumio[1270]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 26 14:47:11 vol volumio[1270]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 26 14:47:11 vol volumio[1270]: info: Streaming services startup Dec 26 14:47:11 vol volumio[1270]: info: Starting Streaming Daemon Dec 26 14:47:11 vol sudo[1752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 14:47:11 vol sudo[1752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 14:47:11 vol volumio[1270]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 26 14:47:12 vol sudo[1752]: pam_unix(sudo:session): session closed for user root Dec 26 14:47:12 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:12 vol volumio[1270]: error: Cannot start Volumio Streaming Daemon Dec 26 14:47:12 vol volumio[1270]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 14:47:12 vol volumio[1270]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 14:47:12 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:13 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 26 14:47:13 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:13 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:13 vol go-librespot[1758]: go-librespot daemon starting... Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=debug msg="app state loaded" Dec 26 14:47:13 vol volumio[1270]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:13 vol go-librespot[1759]: time="2025-12-26T14:47:13+09:00" level=info msg="zeroconf server listening on port 44495" Dec 26 14:47:14 vol go-librespot[1759]: time="2025-12-26T14:47:14+09:00" level=debug msg="obtained new client token: AAAfYkss6pRgNe/wqzxIkM07GIULgEgWYcgZT3o/v5xraNZeTwuFYvdKqXlAH4NZfSBvdUcwDGgfRkZGXsJQE6eSWGmt3ikih0w2E8W5b1hiH6Z/qCdnutV7WgwJaDs+Efo7ta6ILYRXVPkwv8yU7Y+XkKFzHkj6lqOrzO+UydzrlK/wKUEl6G4XqgAULz7M/PLs1faYj9xK1lALKw5KEqGQ5y3IaclXZmdx5hk77aGcHXH7IvWnbA==" Dec 26 14:47:14 vol go-librespot[1759]: time="2025-12-26T14:47:14+09:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Dec 26 14:47:14 vol go-librespot[1759]: time="2025-12-26T14:47:14+09:00" level=debug msg="connected to ap-gae2.spotify.com:443" Dec 26 14:47:14 vol go-librespot[1759]: time="2025-12-26T14:47:14+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:14 vol go-librespot[1759]: time="2025-12-26T14:47:14+09:00" level=debug msg="completed challenge" Dec 26 14:47:14 vol go-librespot[1759]: time="2025-12-26T14:47:14+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:14 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:14 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:14 vol volumio[1270]: info: MyVolumio token set successfully Dec 26 14:47:14 vol volumio[1270]: info: MYVOLUMIO: Adding device Dec 26 14:47:14 vol volumio[1270]: info: MYVOLUMIO: Evaluating Server Dec 26 14:47:15 vol volumio[1270]: info: MyVolumio status changed Dec 26 14:47:15 vol volumio[1270]: info: Streaming services startup Dec 26 14:47:15 vol volumio[1270]: info: Starting Streaming Daemon Dec 26 14:47:15 vol volumio[1270]: info: Removing browser output: myVolumio user plan is not superstar Dec 26 14:47:15 vol volumio[1270]: info: Removing audio output: Dec 26 14:47:15 vol volumio[1270]: info: Stoppping Tunnel 1 Dec 26 14:47:15 vol sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 26 14:47:15 vol sudo[1790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 14:47:15 vol sudo[1790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 14:47:15 vol sudo[1790]: pam_unix(sudo:session): session closed for user root Dec 26 14:47:15 vol sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 14:47:15 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 14:47:15 vol sudo[1792]: pam_unix(sudo:session): session closed for user root Dec 26 14:47:15 vol volumio[1270]: error: Cannot start Volumio Streaming Daemon Dec 26 14:47:15 vol volumio[1270]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 14:47:15 vol volumio[1270]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 14:47:15 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:15 vol volumio[1270]: info: Remote SSH Stopped Dec 26 14:47:16 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 14:47:16 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:16 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 14:47:16 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 14:47:16 vol volumio-remote-updater[745]: Test mode disabled Dec 26 14:47:16 vol volumio-remote-updater[745]: Alpha mode disabled Dec 26 14:47:16 vol volumio-remote-updater[745]: Alpha legacy test mode disabled Dec 26 14:47:17 vol volumio[1270]: info: Setting Geolocation for MyVolumio to as1 Dec 26 14:47:17 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:17 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:17 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:17 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 26 14:47:17 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:17 vol volumio[1270]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 14:47:17 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 14:47:17 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:17 vol go-librespot[1812]: go-librespot daemon starting... Dec 26 14:47:17 vol go-librespot[1814]: time="2025-12-26T14:47:17+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:17 vol go-librespot[1814]: time="2025-12-26T14:47:17+09:00" level=debug msg="app state loaded" Dec 26 14:47:17 vol go-librespot[1814]: time="2025-12-26T14:47:17+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 14:47:18 vol volumio[1270]: info: Updating MyVolumio device info Dec 26 14:47:18 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:18 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=info msg="zeroconf server listening on port 41419" Dec 26 14:47:18 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="obtained new client token: AACculrcCw0cHI+vRLT/yl20764BpZQg2r2tugyDTVkkjSV0Ap2ZcRGQMtoKc8QuuL69DSdTpnRHdHobBGe9FwE9fQhkPETO2+EfgJ/YvBp1YDliUYnNKvMOCovO/eiWHVzsuqzdP7aMDq1wIrW/p0EfsYxfpCmQ983sMhLtYJWF11PoRNvKmyvD4CJqXGXG6CcL3SNZD5KPo5KRupIvgqN+YSbiK1ekBw4YU8FaOxQBxjplQw+6Mg==" Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:18 vol volumio[1270]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 14:47:18 vol volumio[1270]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=debug msg="completed challenge" Dec 26 14:47:18 vol go-librespot[1814]: time="2025-12-26T14:47:18+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:18 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:18 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:18 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:18 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:20 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 14:47:20 vol volumio[1270]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 26 14:47:20 vol volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 26 14:47:20 vol volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 26 14:47:21 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 26 14:47:21 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:21 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:21 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:21 vol go-librespot[1821]: go-librespot daemon starting... Dec 26 14:47:21 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:21 vol go-librespot[1822]: time="2025-12-26T14:47:21+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:21 vol go-librespot[1822]: time="2025-12-26T14:47:21+09:00" level=debug msg="app state loaded" Dec 26 14:47:21 vol go-librespot[1822]: time="2025-12-26T14:47:21+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=info msg="zeroconf server listening on port 43311" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="obtained new client token: AABWqk5Bx9Ni8h70i6cGfrO7GLoiFs+vEClyyoDB1HMoFOp3LNVkoi7E24IQM7eT4J8gKpi4b5oNPJ8FguAu9+MFJHrDQZV9qdfbkX+/LlfoA45bMk5l9oRsvosaulgFRC6r4egPksGEy/XjYIc7nv0IY7IRMuAEoofjpiWMKfEQ5iEX3mvGwuPf+2HsvkDz5UR+n6B0T0C4Hew66mEaT3lLEpu1X/pITmtYrsYmwErRORSM8x9f8A==" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:22 vol volumio[1270]: info: MYVOLUMIO: Adding device Dec 26 14:47:22 vol volumio[1270]: info: MYVOLUMIO: Evaluating Server Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=debug msg="completed challenge" Dec 26 14:47:22 vol go-librespot[1822]: time="2025-12-26T14:47:22+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:22 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:22 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:24 vol volumio[1270]: info: Setting Geolocation for MyVolumio to as1 Dec 26 14:47:24 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:24 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:24 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:24 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:24 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:25 vol volumio[1270]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 14:47:25 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 26 14:47:25 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:25 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:25 vol go-librespot[1847]: go-librespot daemon starting... Dec 26 14:47:25 vol volumio[1270]: info: Updating MyVolumio device info Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:25 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=debug msg="app state loaded" Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:25 vol go-librespot[1848]: time="2025-12-26T14:47:25+09:00" level=info msg="zeroconf server listening on port 35685" Dec 26 14:47:26 vol volumio[1270]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 14:47:26 vol go-librespot[1848]: time="2025-12-26T14:47:26+09:00" level=debug msg="obtained new client token: AAALEms/ujR4/4/KTOLNgeYSZKCBp+K4IeodRmW9JsdepFvJ8RCR5mgJDs4G9Kz2gVOV8LQ7GW7XDBPr5Dx2bLJVakZNL0JyjVNApV/CoX+7HusvBgDJTr1GnVepq2bCY//q52CxqcXBQQv8KpV27P04yQQ8RtEjDB8AXEGsoZaL0nkBYB/sa6ITM1NioxzuLM9+O0Ht/hACSf2mNX6v2cEffP5LRD4fVm/+AdPURKrP0pN6+D0dvA==" Dec 26 14:47:26 vol go-librespot[1848]: time="2025-12-26T14:47:26+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:26 vol go-librespot[1848]: time="2025-12-26T14:47:26+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:26 vol go-librespot[1848]: time="2025-12-26T14:47:26+09:00" level=debug msg="completed challenge" Dec 26 14:47:26 vol go-librespot[1848]: time="2025-12-26T14:47:26+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:26 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:26 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:27 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:27 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:29 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 26 14:47:29 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:29 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:29 vol go-librespot[1872]: go-librespot daemon starting... Dec 26 14:47:29 vol go-librespot[1873]: time="2025-12-26T14:47:29+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:29 vol go-librespot[1873]: time="2025-12-26T14:47:29+09:00" level=debug msg="app state loaded" Dec 26 14:47:29 vol go-librespot[1873]: time="2025-12-26T14:47:29+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=info msg="zeroconf server listening on port 43009" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="obtained new client token: AABBYAtS3zWiv9SaEcuJ1pH5m1/e/l/kHBLzAbabuK9WrI1aGp/QDOOKwyJSxeA5/VvSIAqoyBdGRimVazrsxysNiEFGr9A5hD3H31xqu6HJFG4O71Uu/CbV6wV+UZbSGS4G0/hINFboUMt/HV61OB5yBOeQ6aC9ya282lkTcDZAjTO3fXT95Y/ipGO/+/UmrRIcMpJnRbNDl5NnzCeodpcyeSp8V0znzJLjov3oQtzl61svaKJ0tnPt" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=debug msg="completed challenge" Dec 26 14:47:30 vol go-librespot[1873]: time="2025-12-26T14:47:30+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:30 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:30 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:30 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:30 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:31 vol volumio[1270]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 26 14:47:31 vol volumio[1270]: info: Completed starting MyVolumio Plugin Dec 26 14:47:31 vol volumio[1270]: [Metrics] CommandRouter: 57s 976.36ms Dec 26 14:47:31 vol volumio[1270]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 14:47:31 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 14:47:31 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 14:47:31 vol volumio[1270]: info: CoreCommandRouter::Close All Modals sent Dec 26 14:47:32 vol volumio[1270]: info: CoreCommandRouter::Close All Modals sent Dec 26 14:47:33 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 14:47:33 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 14:47:33 vol volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 14:47:33 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:33 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:33 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 26 14:47:33 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:33 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:33 vol go-librespot[1886]: go-librespot daemon starting... Dec 26 14:47:33 vol go-librespot[1887]: time="2025-12-26T14:47:33+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:33 vol go-librespot[1887]: time="2025-12-26T14:47:33+09:00" level=debug msg="app state loaded" Dec 26 14:47:33 vol go-librespot[1887]: time="2025-12-26T14:47:33+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=info msg="zeroconf server listening on port 46553" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="obtained new client token: AACQ6qc5co718QJx4nMms2xF+LXRDg/lnY0Z+pO9bcT8+548t1F9zBj82B4UMq76xh2BEsLcy12JuTM8h5EmKUZpj6kx+JIPeHVz0SX2mq+lRD/6zTQCK9jdlUT8prTlm8AFLYp1mFL6bWT5kW6oeBFv3FivmrBLQlP1eMOqtz0jnK6MIsFiotQpzKs/gs4zzfOKGinICGpNZMJVnj7fO/MQzBcnUXeO+dLut7ZoGdOfiPremMnc65jZ" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=debug msg="completed challenge" Dec 26 14:47:34 vol go-librespot[1887]: time="2025-12-26T14:47:34+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:34 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:34 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:36 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:36 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:37 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 26 14:47:37 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:37 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:37 vol go-librespot[1901]: go-librespot daemon starting... Dec 26 14:47:37 vol go-librespot[1907]: time="2025-12-26T14:47:37+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:37 vol go-librespot[1907]: time="2025-12-26T14:47:37+09:00" level=debug msg="app state loaded" Dec 26 14:47:37 vol go-librespot[1907]: time="2025-12-26T14:47:37+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=info msg="zeroconf server listening on port 35801" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="obtained new client token: AABguFYLzhrZG48idGS6Qe3HC09DN8a7W51dUrnj/A1cah9bLWy7Gf4taqDf9o7CKDiy/LwS4kEjEb4mKL6BC1+i3H39HjTLvw8aIRUKcMouLIvksT2pq3eA6k/eW/dTOXh50WH3soRzeS4Jo/kDf/58NrafYHSZB4BjvIQeHTRov1Jv0lrmqnaZyTCtZ6pEo6LlP/0bjmHwopDgc8oubi9bkNHh88BeoLSDyBkzA7oRNt7ZmvnCEywW" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=debug msg="completed challenge" Dec 26 14:47:38 vol go-librespot[1907]: time="2025-12-26T14:47:38+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:38 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:38 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:39 vol volumio[1270]: info: BOOT COMPLETED Dec 26 14:47:39 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:39 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:41 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 26 14:47:41 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:41 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:41 vol go-librespot[1923]: go-librespot daemon starting... Dec 26 14:47:41 vol go-librespot[1924]: time="2025-12-26T14:47:41+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:41 vol go-librespot[1924]: time="2025-12-26T14:47:41+09:00" level=debug msg="app state loaded" Dec 26 14:47:41 vol go-librespot[1924]: time="2025-12-26T14:47:41+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=info msg="zeroconf server listening on port 40427" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="obtained new client token: AACIODsT5g0STEq1whS8KWZiU2SlQH62WG8ZNelLe0Nkkf7a9zfLMiKlnCebLNxCqiyYaT3YmDGnhVLd9a2XJaefpSxI3pM19RMuzRu7zxYfpVKWKPpDOlOVBg1byuP9NyqQCt7PHhEtrNUtf6GVBiHTGekB6+vOdvNGeZrQtuWd9+io8PjgqpjjhB5osBz/uejf1zX0KhNsBblmZnK56FFYd20AN8nDPANj6pZDxj0pDOMShFQ9z3Kk" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=debug msg="completed challenge" Dec 26 14:47:42 vol go-librespot[1924]: time="2025-12-26T14:47:42+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed peeking unencrypted bytes: failed peeking uncrypted: EOF" Dec 26 14:47:42 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:42 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:42 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:42 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:45 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 26 14:47:45 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:45 vol go-librespot[1931]: go-librespot daemon starting... Dec 26 14:47:45 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:45 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:45 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:45 vol go-librespot[1932]: time="2025-12-26T14:47:45+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:45 vol go-librespot[1932]: time="2025-12-26T14:47:45+09:00" level=debug msg="app state loaded" Dec 26 14:47:45 vol go-librespot[1932]: time="2025-12-26T14:47:45+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=info msg="zeroconf server listening on port 44575" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="obtained new client token: AACC0gGmFIpKSbcFaJnkdoHAzSpuwYdLSIgFSrNho9IKMGtyjXSQr38I3eKQBhrSUq/j8HLKofpOAtRH4+SDAxBuq3XDCqqhfnHl1UQ56IGGe7DzqlSh0b07Qc7DUuK9BQeThrGGk4LxGnpt7OE46RVkUAB04SlEZSDew1w9kL8L06KEOOxuVA7y+2iST+wLNNaUVz1vQLpstrfDyX4P0XdpjnKPtAnDyK9qu4Z9oFUgYD1Bp/qE2Kn/" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=debug msg="completed challenge" Dec 26 14:47:46 vol go-librespot[1932]: time="2025-12-26T14:47:46+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:46 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:46 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:48 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:48 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:50 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 26 14:47:50 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:50 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:50 vol go-librespot[1953]: go-librespot daemon starting... Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="app state loaded" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=info msg="zeroconf server listening on port 40871" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="obtained new client token: AACK5T4TVTo4PdMLhhGilLVuBXov872jCxGKUvXAt9ZwCnDguhHlVXeYvaGFSmpjM+cmH+44L6OBs3JfHscWkkQRubx1ONQc5+acjOri5kQa0LfAHcP8jVOrvFI1GPITgjR87etDE75W0N9+oLpUAp4Uzjldp+Um3FRoUaPj04Bo+47DPquG1QZ+u9Ummm5i7Ro5CizFzp794oTZnF+b+jzbblUMzwsfggQbPPHMdANnqtiTySu7mKD4" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=debug msg="completed challenge" Dec 26 14:47:50 vol go-librespot[1954]: time="2025-12-26T14:47:50+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:50 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:50 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:51 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:51 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:54 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 26 14:47:54 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:54 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:54 vol go-librespot[1961]: go-librespot daemon starting... Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="app state loaded" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=info msg="zeroconf server listening on port 33645" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="obtained new client token: AACmShK16iVflPlRwGE5c5G37lYpk+tBC8xFdLNdzN2brBVZk87IcKPfR2iMEPBfCxmXDpgA+3IEq8rXlL3SBzw6nV3CIeTyYXPDAOXa2VOfemoMNgV6P3JAhG40yi+IqCdpIOx8c1x5lKv4mN6e5LynsPmcuACJyVnPSlmeZ+Qriq6epHrUusaJ+qLHRqHY0xJc5ZrdNAr3eQQnA485hVseRhg/PqB3b/fZhgiKM6mfMtwIVKwM6Di1" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=debug msg="completed challenge" Dec 26 14:47:54 vol go-librespot[1962]: time="2025-12-26T14:47:54+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:54 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:54 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:47:54 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:54 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:57 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:47:57 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:47:58 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 26 14:47:58 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:58 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:47:58 vol go-librespot[1974]: go-librespot daemon starting... Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="app state loaded" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=info msg="zeroconf server listening on port 42363" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="obtained new client token: AACHtuNnXJ4uqxI86/Aa1aJ9NhFyT7g/BfXSngFKkDMMqEcnYE+8OV3alRBbAbyG64jMPBMK9VT6+waL97D0fqOzF8Cx/AsP0yuRyNFmVKsLpNXuXg6HQkZkw/NNiBgjeQsEbmxT8/T3qOt3djgDtOfaJ0xvVx82y0CYnC7x5BNChGnr/6s/8jtF0qwaowMlcF1YnQzTkmED6BB8HHsfPIeRafK5VkMECRTRkV042bAk9P2VhpO7ojWb" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="completed keyexchange" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=debug msg="completed challenge" Dec 26 14:47:58 vol go-librespot[1975]: time="2025-12-26T14:47:58+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:47:58 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:47:58 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:00 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:00 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:02 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 26 14:48:02 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:02 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:02 vol go-librespot[1996]: go-librespot daemon starting... Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="app state loaded" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=info msg="zeroconf server listening on port 40639" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="obtained new client token: AACS+klemx1xuWShn1Vhz4uEM4Rx2cn+y/YzSeazNfVROfNAGAsM8u1Z19lMiWNudaiyqZhSKaAsVPKY2yOZKS/4ephPmwgTOXfRcmjji3VJAi542yHSESfMfKBX3Y0tyy3SkA2Z+HJLc87/BRgcBbTkG9tDYgIO06xXC7Ng6t+96Jk7E6n0NDuHCfkFyW29ryrD/XVXD+sai0cGysPm7fkyPRLdPQC1RpnAcwA11RnLFhvhUnAssCQA" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=debug msg="completed challenge" Dec 26 14:48:02 vol go-librespot[1997]: time="2025-12-26T14:48:02+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:02 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:02 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:04 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:04 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:06 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 26 14:48:06 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:06 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:06 vol go-librespot[2004]: go-librespot daemon starting... Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="app state loaded" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=info msg="zeroconf server listening on port 36421" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="obtained new client token: AAAR3LXPjkjT8K+DWdzVsoqbiGfjfHDRmZK6iN7j5XhHfklLY+SkLFJqDJOit2aBa4083q6iD4gTbbOcmiKZZNUq5+ZKYXzEjqjWC7NJe51DEXIrb5oGgsGLR3EOEvuF7BihBOHM6mlfjsfBeRysc/UWt79l+i4Mm0JAqOGTJJL6d2Z2x/BITCKf/wHyhS4yJIA7p8nd2rL13Bz3MM7VmcioXVpjEnmN3dnZGYp9pIxlA5WTih7E+D8H" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=debug msg="completed challenge" Dec 26 14:48:06 vol go-librespot[2005]: time="2025-12-26T14:48:06+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:06 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:06 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:07 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:07 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:10 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:10 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:10 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 26 14:48:10 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:10 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:10 vol go-librespot[2026]: go-librespot daemon starting... Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="app state loaded" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=info msg="zeroconf server listening on port 45135" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="obtained new client token: AAAdp87+wfrVVSS0g53m/SL38yLy/5ErWB7Csu5ae3WZ+dmIxJ0WA/P9TkgNgf1TVM+wtlAX30ZyTRvRlvoTkAUuRD1zYFriXOpQ4QQP48H8npVv2bif1QGmqqsRCbAGCd2PQkvnEqUbANVOsHuqa7LgImGKhD5hRNt1k7JTPbVBsculpKsFRCFhjQ62RfUUrhE8Dj+hYetJzovfWy6Dx/+5E66eZmai9VqRBAgU+DE4XL5GQOVZfuOZ" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=debug msg="completed challenge" Dec 26 14:48:10 vol go-librespot[2027]: time="2025-12-26T14:48:10+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:10 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:10 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:13 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:13 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:14 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 26 14:48:14 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:14 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:14 vol go-librespot[2037]: go-librespot daemon starting... Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="app state loaded" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=info msg="zeroconf server listening on port 36421" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="obtained new client token: AAA1JMJqhA1ADfrr7fGRUYA2WQcg8sHct+K6Rjh4HT5H7pxSfzp76o8VP7s7Elh+hpCtggGeutCkChQZemZAhbNNy6Jub8BBVbQQ+cbpELXNK7oqhz0/Fa6kV5WkF6faJHd4VBIlNwp87ibVIG0VZlXQzXj9A1Uv7IJQyzwnTue6KFkVN9JuI6SJbEauE74WQ5ssffYpl70nD6aRe9gLyxvb4fX564qJXa3eu70868h3Ly33mQc492Rx" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=debug msg="completed challenge" Dec 26 14:48:14 vol go-librespot[2038]: time="2025-12-26T14:48:14+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:14 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:14 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:16 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:16 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:18 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 26 14:48:18 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:18 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:18 vol go-librespot[2048]: go-librespot daemon starting... Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="app state loaded" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=info msg="zeroconf server listening on port 44395" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="obtained new client token: AAAmzAzmLzGlLn0nuvGAedBFlEZ2/B+i0OtbBxruq4Ac5LRnAwSVje8EXwROHtoKCgfEElNI1ONGZreArstXChqnkPIJ7L506hZOygW+t/fO33BVvYSmxRD/LZ55SR8GELd3q8xOE6NEq+1qi4uutq4tPiM4ZMh5Y6glI55F4GORzTihcrzkjzV0gLcZzXtGlTAlin5d6A3gQ53SlT1TXi/yVGPShyGqk51qUJD0xhUEWNJVOFcZM/bS" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=debug msg="completed challenge" Dec 26 14:48:18 vol go-librespot[2049]: time="2025-12-26T14:48:18+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:18 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:18 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:19 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:19 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:22 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 26 14:48:22 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:22 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:22 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:22 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:22 vol go-librespot[2070]: go-librespot daemon starting... Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="app state loaded" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=info msg="zeroconf server listening on port 40455" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="obtained new client token: AADq19clrThOauWu+vDYKL6tapp9I7nUfQ5qFHy/KM39ib+oSOos/xRBAFTYEXuZ5EJhjE3ULV5MAdHuImQaMe3+4Rf7A3F57pY/ne0ckirKsFSz+dMncp6xqy/36qkivCP4PzDwtBLDqmQy+ZjeycKiTTEZ6yXYc7bw9QgNshT00pnUpK4j3rGi0DS02WfpHJWCR6tLkPk/9KhOQ5XML/o+DJ309YAwdYoSFAzh+/OigOEH2etSQVoi" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=debug msg="completed challenge" Dec 26 14:48:22 vol go-librespot[2071]: time="2025-12-26T14:48:22+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:22 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:22 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:25 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:25 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:26 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Dec 26 14:48:26 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:26 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:26 vol go-librespot[2078]: go-librespot daemon starting... Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="app state loaded" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=info msg="zeroconf server listening on port 35503" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="obtained new client token: AAA3qt73j7oVfpi9emm5TpqCWgXearllcbkGJCE3TqvAXen3ab+phuNzW+ObHfeKqEX5R+9SFoH8kyTopFZ7gYHcomciW6eSimlRfV6B3aom8YOObaa2Hu7pcOVE2RcrU9Mn2rbvaJkzAW1B0TKKPb4P6LyQyY0I/DOcZSz7YXxZqNCc42QG3Xkx7NXVJiG89FHmPBBu1VWzNvloBGwmFOiWFsOEXsxx0ivvsGqO10I0iHp+YG8pQeUr" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=debug msg="completed challenge" Dec 26 14:48:26 vol go-librespot[2079]: time="2025-12-26T14:48:26+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:27 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:27 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:28 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:28 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:30 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 26 14:48:30 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:30 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:30 vol go-librespot[2104]: go-librespot daemon starting... Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="app state loaded" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=info msg="zeroconf server listening on port 33499" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="obtained new client token: AAAVbPZGtshdeR7x7BshfhxfLcKxCyx5DFW8yDhVB6EcGrgcwDAyAVSl7zUFndaGN3xatnGLCXoQH1puEp0XswQoq4+NSmJeBKh6YHcHlAVGR3aVXl1inRsmtr5eHV2Gayl1JyaiaWfXSH+vY9Me+k3JyvxFTk3rkm9+NlzJdt/bbObtTRb/xWUOfyTrFxM2xQnVKpwwrRPaIrNBljgUdfS74i3nDlWn9wt056TRFarzrxvdYYyumOTP" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=debug msg="completed challenge" Dec 26 14:48:30 vol go-librespot[2105]: time="2025-12-26T14:48:30+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:30 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:30 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:31 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:31 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:34 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 26 14:48:34 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:34 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:34 vol go-librespot[2113]: go-librespot daemon starting... Dec 26 14:48:34 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:34 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="app state loaded" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=info msg="zeroconf server listening on port 46545" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="obtained new client token: AAAvJKiDs9yrk4F3KKLFuddQIu99EUAj+uW84LFrdFMzJPqW3srtWxziX3tXGIFIN+xf7Py4uAECdlgchokyLL25VhSNs5S9zy2NBzaSa5JePu9164lNb8zuK7oyEU7mN9uP1+efEG9XXXwsNyM42psA/lZCCgNxD3ZFarsCIE08fW/7kVnJrj4geMlYrUsFrkDPzwoCpKjbd/7fiwoDktamuAx7kZzX0cbNlvWz4U1YejAFcZX6z1B+" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=debug msg="completed challenge" Dec 26 14:48:34 vol go-librespot[2114]: time="2025-12-26T14:48:34+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:34 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:34 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:37 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:37 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:38 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 26 14:48:38 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:38 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:38 vol go-librespot[2124]: go-librespot daemon starting... Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="app state loaded" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=info msg="zeroconf server listening on port 41175" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="obtained new client token: AAA9XGZDJjfUCK14LbKadCxTLppWG+gZI6nV23R6yhZU0B8lCa5lnwUyg7Z5dtUJyS/DxiIjFzn4GqTg2D2JMSymz9DoyxxRthF8o+2SVh1v5qBpII6Lt2QtNwDJqpzZtxr0va0YgFtfXTGmfMOY2oqQLyr/fcQPHgT7009ebS/s6MNmscqZXQwCBgndzbw+M3Pp8YAof10OqbxQF+ktstk0Jsbhld1deaT934iTLS2cyN1n2q5cYp8m" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=debug msg="completed challenge" Dec 26 14:48:38 vol go-librespot[2125]: time="2025-12-26T14:48:38+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:38 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:38 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:40 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:40 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:42 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 26 14:48:42 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:42 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:42 vol go-librespot[2146]: go-librespot daemon starting... Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=debug msg="app state loaded" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=info msg="zeroconf server listening on port 39193" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=debug msg="obtained new client token: AACT0qFQQpnrsicLbq76UeklLOkYrYbXPDFuI/jdUFF7QY1rZpDZ259CYc1NdXpfnNN1izFE1T53zIxJt/Cuu8tcq8MHK9+ljm6crRUSZjmoF+E/2vn6kDR3JSEEE/3bgZcmN+uixuLkVHHK7cgAWp63CEz6Hx6gbQy01zHPZuYgDzFN/LkIYx2OFHY10RLkrpzCSSRc2zaIyhqr3oL+qJUqVt0nt0j9TjhGCW4c6xSH3UV2ghSCSZBP" Dec 26 14:48:42 vol go-librespot[2147]: time="2025-12-26T14:48:42+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:43 vol go-librespot[2147]: time="2025-12-26T14:48:43+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:43 vol go-librespot[2147]: time="2025-12-26T14:48:43+09:00" level=debug msg="completed challenge" Dec 26 14:48:43 vol go-librespot[2147]: time="2025-12-26T14:48:43+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:43 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:43 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:43 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:43 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:46 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:46 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:46 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Dec 26 14:48:46 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:46 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:46 vol go-librespot[2159]: go-librespot daemon starting... Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=debug msg="app state loaded" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=info msg="zeroconf server listening on port 32889" Dec 26 14:48:46 vol go-librespot[2160]: time="2025-12-26T14:48:46+09:00" level=debug msg="obtained new client token: AACboRt23OsVN2ghzge41KBvhNW5malhFdtGkqAFcwAmFFahFz54AId6wTyBEeXVJvGlDT2fMdWmWIN1RP6SaZGkAIwHDwnGMDf1mjob3p2IPVaRQrACvwTubimifLjjDlw8Trhfab+ipRIsUIL8qKnK53MprAhnwcZoAQuv0B0RvG2WBMl4wPvG/NN0LPE1cH4giVJXvV57KZmFaaY3tQq/3o6VU6pVXclHKW/EBsE5MFABA9z/C0Vm" Dec 26 14:48:47 vol go-librespot[2160]: time="2025-12-26T14:48:47+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:47 vol go-librespot[2160]: time="2025-12-26T14:48:47+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:47 vol go-librespot[2160]: time="2025-12-26T14:48:47+09:00" level=debug msg="completed challenge" Dec 26 14:48:47 vol go-librespot[2160]: time="2025-12-26T14:48:47+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:47 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:47 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:49 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:49 vol volumio[1270]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:50 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Dec 26 14:48:50 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:50 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:50 vol go-librespot[2181]: go-librespot daemon starting... Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=debug msg="app state loaded" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=info msg="zeroconf server listening on port 37399" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=debug msg="obtained new client token: AAAMFCdtk6v6f+0PIA9PLN/zoRrjrACmq8+YGn4rFrnoX8rfwKyQ+1fL+jSptvZAs11oaZ0L5VOEFWaivbL1HcnD3RmDse6njg4K2deZJkC87ooNJcqUsYKey7FQcroPFGjWox93/VbAXIjNUEmc7P68N41yGjGWH5Pwh8+ty7AiKVt2QzG8AbxsS7+OwHbVDaNMlkFsuAHNIy9530qbcg7zFZ+Fl8GJJMXr5PbqKdl8D8J4fuARjeaj" Dec 26 14:48:50 vol go-librespot[2182]: time="2025-12-26T14:48:50+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:52 vol go-librespot[2182]: time="2025-12-26T14:48:52+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:52 vol go-librespot[2182]: time="2025-12-26T14:48:52+09:00" level=debug msg="completed challenge" Dec 26 14:48:52 vol volumio[1270]: info: Initializing connection to go-librespot Websocket Dec 26 14:48:52 vol go-librespot[2182]: time="2025-12-26T14:48:52+09:00" level=debug msg="new websocket client" Dec 26 14:48:52 vol volumio[1270]: info: Connection to go-librespot Websocket established Dec 26 14:48:52 vol go-librespot[2182]: time="2025-12-26T14:48:52+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:52 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:52 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:52 vol volumio[1270]: info: Connection to go-librespot Websocket closed Dec 26 14:48:55 vol volumio[1270]: info: Getting Spotify volume Dec 26 14:48:55 vol volumio[1270]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 14:48:55 vol volumio[1270]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 14:48:55 vol volumio[1270]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 26 14:48:55 vol volumio[1270]: errno: -111, Dec 26 14:48:55 vol volumio[1270]: code: 'ECONNREFUSED', Dec 26 14:48:55 vol volumio[1270]: syscall: 'connect', Dec 26 14:48:55 vol volumio[1270]: address: '127.0.0.1', Dec 26 14:48:55 vol volumio[1270]: port: 9879, Dec 26 14:48:55 vol volumio[1270]: response: undefined Dec 26 14:48:55 vol volumio[1270]: } Dec 26 14:48:55 vol volumio[1270]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 14:48:55 vol systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Dec 26 14:48:55 vol systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:55 vol systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 14:48:55 vol go-librespot[2195]: go-librespot daemon starting... Dec 26 14:48:55 vol go-librespot[2204]: time="2025-12-26T14:48:55+09:00" level=info msg="running go-librespot 0.4.0" Dec 26 14:48:55 vol go-librespot[2204]: time="2025-12-26T14:48:55+09:00" level=debug msg="app state loaded" Dec 26 14:48:55 vol go-librespot[2204]: time="2025-12-26T14:48:55+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=info msg="zeroconf server listening on port 38193" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="obtained new client token: AAAJ3tV8BbePSj8J9T5h8Fs8ritcmmdU76MVS3HMQMYG/iDEJktAOfC6LfoD5ihfnN+fEQnrw2zz30Orh+dHSMQ9sDdETmJUemHTm0capYutDD6U6Nem2kBAddQ3qT95aw7kQv16NaW9ivP3d4qi4E6ljp9sxVSqWoDmnLLQ3G7SIyK/0wk+JUkslLgkkFwPvJ6M5lQoBU0bc64ChPbdpKeZcj+I2HIwPxQhYLpnAgZEOD6gXb0QQQ==" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="completed keyexchange" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=debug msg="completed challenge" Dec 26 14:48:56 vol go-librespot[2204]: time="2025-12-26T14:48:56+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 26 14:48:56 vol systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 14:48:56 vol systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 14:48:58 vol sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 14:47' Dec 26 14:48:59 vol sudo[2218]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"