-- Logs begin at Sun 2025-03-02 21:48:34 CET, end at Mon 2025-03-03 21:20:25 CET. -- Mar 03 21:19:00 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:00 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:02 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:02 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1042. Mar 03 21:19:02 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:02 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:02 volumio-wz go-librespot[28513]: go-librespot daemon starting... Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="app state loaded" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=info msg="zeroconf server listening on port 37731" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="obtained new client token: AAB4gl10x/fgkD8VRDZWuGSAjGDsyiKqyP7OE1KPrjoDaDYOrLrKk8u6UJB+1p5/qD1m4PcXCsq6P0U3uizrnXiYPFhJwMV980QnG1td3HFxYjmUDqApggOCqnDJaSaXu0FOdefXopqVOUv5FJMVGptnB9LYj0avLowkeT3ZWABw29e59txx0agDivhhgV8dTlbmVjC25gTp1iDWRyBghkSYA34CRvw0OVN9w//L+ICjtY+Jfc1cgvG3" Mar 03 21:19:02 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:03 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:03+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:03 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:03+01:00" level=debug msg="completed challenge" Mar 03 21:19:03 volumio-wz go-librespot[28513]: time="2025-03-03T21:19:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:03 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:03 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:03 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:19:03 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:19:03 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:19:03 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:03 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:19:03 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:03 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:06 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:06 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1043. Mar 03 21:19:06 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:06 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:06 volumio-wz go-librespot[28524]: go-librespot daemon starting... Mar 03 21:19:06 volumio-wz sudo[28526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 21:19:06 volumio-wz sudo[28526]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 21:19:06 volumio-wz sudo[28526]: pam_unix(sudo:session): session closed for user root Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="app state loaded" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:06 volumio-wz sudo[28535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 21:19:06 volumio-wz sudo[28535]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 21:19:06 volumio-wz sudo[28535]: pam_unix(sudo:session): session closed for user root Mar 03 21:19:06 volumio-wz volumio[834]: verbose: New Socket.io Connection to 192.168.178.34 from 192.168.178.76 UA: Mozilla/5.0 (Linux; Android 14; SM-X510 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=info msg="zeroconf server listening on port 33159" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="obtained new client token: AACBLbi1WoWB+R/SrJgzn0MzwZVTUb8OZgVXWeN1Tsx+CK7lItk9sMuuqxroHYiLbsmeVhGT3eG35AViX+PCwDMdcRdGfEV/RIahcmX61fpKpuRckfyI0Ui7pWMWi8X+DYGRxjEThJbWdfvh3o6FhHfsqlP0kz/ftGoeJArL8EeGOWLUOGJgjtz4qwbj+sAIeBaiyXPWMjZlXJeLnrHKzdJt3ViQiCVMxiz6ytg0/3yQ1HATXXqTGTcU" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=debug msg="completed challenge" Mar 03 21:19:06 volumio-wz go-librespot[28524]: time="2025-03-03T21:19:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:06 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:06 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:06 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:06 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:06 volumio-wz sudo[28539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 21:19:06 volumio-wz sudo[28539]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 21:19:06 volumio-wz sudo[28539]: pam_unix(sudo:session): session closed for user root Mar 03 21:19:06 volumio-wz sudo[28542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 21:19:06 volumio-wz sudo[28542]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 21:19:06 volumio-wz sudo[28542]: pam_unix(sudo:session): session closed for user root Mar 03 21:19:06 volumio-wz volumio[834]: verbose: New Socket.io Connection to 192.168.178.34 from 192.168.178.76 UA: Mozilla/5.0 (Linux; Android 14; SM-X510 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetQueue Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreStateMachine::getQueue Mar 03 21:19:07 volumio-wz volumio[834]: info: CorePlayQueue::getQueue Mar 03 21:19:07 volumio-wz volumio[834]: info: Listing playlists Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 21:19:07 volumio-wz volumio[834]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 03 21:19:07 volumio-wz volumio[834]: info: Received Get System Info Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:19:07 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 03 21:19:07 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:19:08 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 21:19:08 volumio-wz volumio[834]: info: Received Get System Info Mar 03 21:19:08 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:19:08 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:19:08 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:19:08 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:08 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:19:08 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 03 21:19:09 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 21:19:09 volumio-wz volumio[834]: info: Received Get System Info Mar 03 21:19:09 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:19:09 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:19:09 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:19:09 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:09 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:19:09 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:09 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:09 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:09 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1044. Mar 03 21:19:09 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:09 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:09 volumio-wz go-librespot[28558]: go-librespot daemon starting... Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="app state loaded" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=info msg="zeroconf server listening on port 35325" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="obtained new client token: AADmGnTmW1vRBLWRUXbN//9e4kKECguMHY3rKQLma3BFZ5WnJR1URWqKl9Puu4n+IYc1j9gBsj6d8ekWmjBmAqRicqyOQK+7fLg6idt/Zt/kQIlRPeY4Jprj/DTeU8/KioGNNuF1YlN9S6ntrxjH13X4uSn9Ry36gbGqhtOlV9RNQXc3SeiWgLAGRMLXv+LHdUop4iCoSFCw4DKSLtNljzl9C1dPbqERYp+/rxZIjfyWkTUAte+24SKF" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=debug msg="completed challenge" Mar 03 21:19:09 volumio-wz go-librespot[28558]: time="2025-03-03T21:19:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:09 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:10 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:12 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:12 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:13 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:13 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1045. Mar 03 21:19:13 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:13 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:13 volumio-wz go-librespot[28566]: go-librespot daemon starting... Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="app state loaded" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=info msg="zeroconf server listening on port 32885" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="obtained new client token: AADWP6NFEh2U44yMirrlB0BsOfFx3VYAnULMa585Qr2eVJn14SnI6UQ7VFSIfZPGcAIVtPpuu6WKhCJRAEc3btH+gIxJ4YG1Gr8qm/0b4Oxqjrt9fOgUNBP8SM0008X/0MMuaJWijLCH0Tjtap+7MIF+1WR83OXYlXZMyKwI6kkFYe/m+VpdGlDUNj49w3Cmzs0xRkIRy+arcLkiaMRmAcK/swrn3IHJkBW61MMm84wvc7YfhJ/9KmtD" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=debug msg="completed challenge" Mar 03 21:19:13 volumio-wz go-librespot[28566]: time="2025-03-03T21:19:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:13 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:13 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:15 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:15 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:16 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:16 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1046. Mar 03 21:19:16 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:16 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:16 volumio-wz go-librespot[28577]: go-librespot daemon starting... Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="app state loaded" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:16 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=info msg="zeroconf server listening on port 33627" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="obtained new client token: AAA86KmRpg384reksKElHyKMMKO9dArxyhFm98gd6B8S29zYIoSx+oxfFIBeYrIFtD6Z5mw5o7k1gflAomL3b5OhkGKDBUg01NGuZkg9WDTI+OPk+oMfzQ+KulFRDTZUr3J8YmxqrLNgGD9sWAaQ6Sw+ZY0/f+QxWmfkAgVC3Onxc/EeOn5AlYBgXx9v14+LwBHd6t7ITBlwpopKXgBMQKyiVZYq3e+H7fQymeQZYItuVbSxFQusjAh4" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=debug msg="completed challenge" Mar 03 21:19:16 volumio-wz go-librespot[28577]: time="2025-03-03T21:19:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:16 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:16 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:17 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 03 21:19:17 volumio-wz volumio[834]: info: Preload queue cleared Mar 03 21:19:18 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:18 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:20 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:20 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1047. Mar 03 21:19:20 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:20 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:20 volumio-wz go-librespot[28600]: go-librespot daemon starting... Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="app state loaded" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=info msg="zeroconf server listening on port 35553" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="obtained new client token: AACMEqlK641hLVNLMf5FwOdwgjtbSwUNWPZVfQGaVxXLwNKLwn2ATsLTiMLMXxckYno57XNm8gv52cQkL0NFbkpsdpwtAPecQbq8uTMFZKzKi4qg6M7EoHU3GGwky0/R5zCyKTuNbU/CzjzlBocyNlNfJIMMTDc9QBtu90no8dwk6vRoA1tB0pfjDmNl4ZL2hwuQyH/+SHpy8qwNMRCN8fp1prVExtf8z5aiPer+jeTtJ8Xs4J8U5npU" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=debug msg="completed challenge" Mar 03 21:19:20 volumio-wz go-librespot[28600]: time="2025-03-03T21:19:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:20 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:20 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:21 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:21 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:23 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:23 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1048. Mar 03 21:19:23 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:23 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:23 volumio-wz go-librespot[28609]: go-librespot daemon starting... Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="app state loaded" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=info msg="zeroconf server listening on port 43565" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="obtained new client token: AABVxar6oBMOa53b8aN9s4UcyesD8eS54h3TF/+BNqm0qhyADF0SMZtIcLp6nnugxWKetTopLQe5DRdygctzENPuzXnNEZIai3XyomCP/uRkomVOvZ7jpy5vo58SoCP0IT118zhTOf4ZRJ1WlGW2ylwbZVOzHl7H8kSmaZvundmBCgo6INzOnwmOM9/gbv/2Ppj7et+9Qcf9iX/eleyjrBMJcZXJGGx16tVwrJdmzwE8fMEFT/CuM/Ai" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=debug msg="completed challenge" Mar 03 21:19:23 volumio-wz go-librespot[28609]: time="2025-03-03T21:19:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:23 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:23 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:24 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:24 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 03 21:19:24 volumio-wz volumio[834]: info: Getting webradio selection Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 21:19:24 volumio-wz volumio[834]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 03 21:19:24 volumio-wz volumio[834]: info: Received Get System Version Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 21:19:24 volumio-wz volumio[834]: info: Received Get System Info Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:19:24 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:19:24 volumio-wz volumio[834]: info: Preload queue cleared Mar 03 21:19:27 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:27 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1049. Mar 03 21:19:27 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:27 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:27 volumio-wz go-librespot[28621]: go-librespot daemon starting... Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="app state loaded" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=info msg="zeroconf server listening on port 35711" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="obtained new client token: AAAnlyfx31J/Rhp1ycyDLI9TqcQ1ktdvXnTM79VPGoRXBzLcxLtVQAC/FppSvucBJOuAOzaDqLn2QAWH34+FyxacS+9fbvPVsp1K8x28WeiQjQh/UaQyxSljgRUMwCMixG+y9jr9ZctRq6h374qsOfjc5Ry4Ff/c9NGhrq6evf+cOTEFGRFjRxeONqt/XdJXXPUSu0Uc0TK3AfcjnYXQaF0RbVopVbzBA8DCkI3LmunZAaN+ASgURMjT" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=debug msg="completed challenge" Mar 03 21:19:27 volumio-wz go-librespot[28621]: time="2025-03-03T21:19:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:27 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:27 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:27 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:27 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:30 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:30 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:30 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:30 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1050. Mar 03 21:19:30 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:30 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:30 volumio-wz go-librespot[28643]: go-librespot daemon starting... Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="app state loaded" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=info msg="zeroconf server listening on port 40585" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="obtained new client token: AAAwA4W7qx05/Mokno5AjpN2qBAguc2Cd2cdf+DzRTYnHHZBVsU/ckcz8b+8pVGGYb/ohZLVOM/2aOv4VS8XhX6cFyS3s9nbGt3JPPtfJgN4gASR5IgSRLKDksxwoKyr3Y6Av94t4fvd+Jz3WPZ6MrY6Fg+7m4hRyGxe0bTCnOcMN+VOYV2TdhSfLWbz6QZLZKNee0lww1GqCMz60X1ApqFnpcoUaHvlyL1sAD7CY2qhk574x78f3jRi" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=debug msg="completed challenge" Mar 03 21:19:30 volumio-wz go-librespot[28643]: time="2025-03-03T21:19:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:30 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:30 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:33 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:19:33 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:19:33 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:19:33 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:19:33 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:19:33 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:33 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:34 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:34 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1051. Mar 03 21:19:34 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:34 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:34 volumio-wz go-librespot[28651]: go-librespot daemon starting... Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="app state loaded" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=info msg="zeroconf server listening on port 36285" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="obtained new client token: AAD6x18YwH289uwQVrflE7N9bV5WXk27owuQQjt59YNhDlrYBzVWBjte7M7ic+Y+jVbSJtUJObghuO0NOaP5pb6g0gXLOa6iAjvlU8Ia3+YsBhfYbqtqWW9uZnEBNSVNfhEjzeYh7sqkRf1YQxQRQIqgups5iAzldVG0zpTArgr1FbdwNRiAM4npFidSrXAIp61IVwgjWTDQzT4FgRH/ctxyT9cpptrDNyvp1G2fweGAeOp0VxbEMJPL" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=debug msg="completed challenge" Mar 03 21:19:34 volumio-wz go-librespot[28651]: time="2025-03-03T21:19:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:34 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:34 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:36 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:36 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:37 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:37 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1052. Mar 03 21:19:37 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:37 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:37 volumio-wz go-librespot[28676]: go-librespot daemon starting... Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="app state loaded" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=info msg="zeroconf server listening on port 44863" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="obtained new client token: AAAh3Vx6ZCFMQmSwIVEyA1GX7QcWIy+dqIPENx0Ox4q7egA4L8yFr9tCygpQ3Svrgy/ybXn8+lS6e76n0QoB9mO+kRh5UHagypc3/c4WQ6PP4S+UHQGd8cjfsZlaTHzbI0EvRkpxTyFhSXvWmiYzAprfUlzemU1umMTBhRNJpeHPbS4J/5kQhm14f09JmrkAQd0q3zEhN2LLVuu8lwCJuCqMKufmOb26W1d9M3hYYB3/dyqjk4ydeRWk" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=debug msg="completed challenge" Mar 03 21:19:37 volumio-wz go-librespot[28676]: time="2025-03-03T21:19:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:37 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:37 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:39 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:39 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:41 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:41 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1053. Mar 03 21:19:41 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:41 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:41 volumio-wz go-librespot[28684]: go-librespot daemon starting... Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="app state loaded" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=info msg="zeroconf server listening on port 34863" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="obtained new client token: AACwcFxt1RyWEspPTQMQoRXEeNXEpO1rQ2OZl+v6KTUmTQurXG6WX8I2cT5VLtX+QYyPYVcKl/OI8mA5htCJ45ZCVcnGdmzF0OVWH8bFcm4BD8Fx4HfhXI5rDSPkr99UGVDdBYH8Ns5hy6q5k4gjzEf8RhxxCAEhAPw6dSFfiCGNH55tysiR+TVcrouT5H+CxwVL4X3ce9u+lotAH3ZiQxK/k++r1KqFF1yNqHL1UMOzFSaLnmnIj+6U" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=debug msg="completed challenge" Mar 03 21:19:41 volumio-wz go-librespot[28684]: time="2025-03-03T21:19:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:41 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:41 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:42 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:42 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:44 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:44 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1054. Mar 03 21:19:44 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:44 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:44 volumio-wz go-librespot[28692]: go-librespot daemon starting... Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="app state loaded" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=info msg="zeroconf server listening on port 34043" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="obtained new client token: AABTB3yGikrqyb9hGAl2wkbzDZOY5O6gp+AH70Xc1cuiK8ONa9spAFnG7ZNYuYDM4vDD7e17oNS4HgRx/7vBbbQflb21ruPXNqnSX5tqPNlovCCowGn5qTpN54ru/8xRHG2qNO49jkVGX+giZxr+WLmpYeMY/AJVZRxFRMU5v6Kvc2FSHhbpJY3adIehHJUYgX9CcHyRfk0+qZ7fEofoNAOwnLqwRdUhqb1JSZREFl3JPykH5jRZm1B3" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=debug msg="completed challenge" Mar 03 21:19:44 volumio-wz go-librespot[28692]: time="2025-03-03T21:19:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:44 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:44 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:45 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:45 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:47 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 03 21:19:47 volumio-wz volumio[834]: info: Preload queue cleared Mar 03 21:19:48 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:48 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1055. Mar 03 21:19:48 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:48 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:48 volumio-wz go-librespot[28718]: go-librespot daemon starting... Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="app state loaded" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=info msg="zeroconf server listening on port 44065" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="obtained new client token: AACMgLpXB9JDVpMP/F89I3pQRaQJa6v6uaxzGIzbNLfKX7s80lMw9QjtEDt5J9GGcE25OFvOq2X+Tl8bLUkleFVCwpxu476ZnR1OEzxqKp1QLW1uRKcLoiDTvcqO+H0p5HF8pLED2smHRUiww0QbspIhhiuEV1COCx9v7QaJLs2admU+d4Kc1b4xcN46IkcWNxSjY4zX8IkUUH6p8kN9rkqC2V/9cUFbv8mArY345GwoOSBO3DQ8DOia" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=debug msg="completed challenge" Mar 03 21:19:48 volumio-wz go-librespot[28718]: time="2025-03-03T21:19:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:48 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:48 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:48 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:48 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:51 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:51 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:51 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:51 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1056. Mar 03 21:19:51 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:51 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:51 volumio-wz go-librespot[28726]: go-librespot daemon starting... Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="app state loaded" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=info msg="zeroconf server listening on port 41301" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="obtained new client token: AABuw8RmQMA23Hg+ru6QPPWRRSkttR02nSOVpb20fl8Q6x6Roufw3y7C/21kQanvSN1WVbaOifubLZki/HDpAzh8+VxRLZ42okC+VULxoHSW+00BWLnszKCMOsC8bIwtaD2fN3uF1k7ExbpWxvoabhB+GcD+sZhiXOIIZenVGdnYD8U5e9WjApe8hd7ytRVFhS+y9O9I1OPi/cvnHq2DxL2v/MEdqTFkUcqDfMnOTnYhHPlTiocYvoId" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=debug msg="completed challenge" Mar 03 21:19:51 volumio-wz go-librespot[28726]: time="2025-03-03T21:19:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:51 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:51 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:54 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 03 21:19:54 volumio-wz volumio[834]: info: Preload queue cleared Mar 03 21:19:54 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:54 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:55 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:55 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1057. Mar 03 21:19:55 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:55 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:55 volumio-wz go-librespot[28756]: go-librespot daemon starting... Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="app state loaded" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=info msg="zeroconf server listening on port 34891" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="obtained new client token: AADQ1wRcZccETMYfHxDKXh7voP3CBZgtCe6mCb4pk3AUgl7z5jhCTn+3HhE1jSR1AO//f8aYhFIcRUEzG0+xjwECklGRwIKOU8gdKTeZC9AQKoClFWqH9vU3o42jm/jB5MeGhihbQJ1Yi6kUwrvjIfl7r1fgkwxYLk8Ye8Mt546GCA5Di7bpVxnQzWkL4OJ/lR0r2CKR1LLSbu9A8xC2c3RPIIoAWlISrZ3k+PqxsGIEZtbxPqSPfJlr" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=debug msg="completed challenge" Mar 03 21:19:55 volumio-wz go-librespot[28756]: time="2025-03-03T21:19:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:55 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:55 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:19:57 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:19:57 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:19:58 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:19:58 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1058. Mar 03 21:19:58 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:19:58 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:19:58 volumio-wz go-librespot[28797]: go-librespot daemon starting... Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="app state loaded" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="stored credentials not found" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=info msg="zeroconf server listening on port 39525" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="obtained new client token: AAAr/Wpe1ATudKdZbIp6Mx9crr3T4iEGHQahkuVsw3Hr+XOvZKrwPRhcihLfnAaVMwe7huIECCiEzebX3ZYzYc2M/w09VBWz9QcXa6PWiXxXuWnUi/m1GTjd91G3TXz9SUmqn7asrTR0unrMhaaV0jfCj5W4TfhEprtuEzRAAjpcMdofgajxi7uANqjnM9ZhWrnhYJLVuUuRmWfTkJ5Bs/Ui7+CfhTzIOb9awWOMPUqjX9y5lhWOFFlq" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="completed keyexchange" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=debug msg="completed challenge" Mar 03 21:19:58 volumio-wz go-librespot[28797]: time="2025-03-03T21:19:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:19:58 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:19:58 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:00 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:00 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:02 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:02 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1059. Mar 03 21:20:02 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:02 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:02 volumio-wz go-librespot[28805]: go-librespot daemon starting... Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="app state loaded" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=info msg="zeroconf server listening on port 37409" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="obtained new client token: AADb47Ff0HSFgYDz1/AAQq2/JvYDPVodT7Scwgs5jmv/cg9/qfx5oRTWpGOe+YnQwf0ZwxzHZYFMwAMWgi15JKa83pY204lbSqJPDWEnW4XanzG4toep9SGXHrOJBj4zidlXHv8MmlpF5FFLNL5nNVhVdVQEUZePIEEzRqLP3jpdN1qyvhytUkI28HfWEkeZ8jX0PFRCzTRuoSNQIhwwkuqPE/e5QcSQCgnJZLXhcIGh+0Bvcwhd4Jda" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=debug msg="completed challenge" Mar 03 21:20:02 volumio-wz go-librespot[28805]: time="2025-03-03T21:20:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:02 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:02 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:03 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 21:20:03 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 21:20:03 volumio-wz volumio[834]: info: Discovery: Getting this device information Mar 03 21:20:03 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:20:03 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 21:20:03 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:03 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:06 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:06 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1060. Mar 03 21:20:06 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:06 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:06 volumio-wz go-librespot[28817]: go-librespot daemon starting... Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="app state loaded" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=info msg="zeroconf server listening on port 39453" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="obtained new client token: AAC25ZEr0y72m+aCYn9MCyWALLsCJMKjzlXBGftcYShHaIHhfvLnAchDR5I4h2q0HtV8p2kANrePVwjEJIaVMkI/5LsZNTeJoUcPyCSGCSRMrK3QcRFoJkhKbFiO0Cx9VBfEMwFJrMwU3gF8j5iLMwACabn9Wtjovy0ugRj8JEK4Z/h/qu4KrWhtQRk43bft0c+QbjTCIbN+6G7ZLrHJqh0JhE8W62iRtB/DOHUFVJMp1zf60tX04Vup" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=debug msg="completed challenge" Mar 03 21:20:06 volumio-wz go-librespot[28817]: time="2025-03-03T21:20:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:06 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:06 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:06 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:06 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:09 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:20:09 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:09 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:09 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:09 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1061. Mar 03 21:20:09 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:09 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:09 volumio-wz go-librespot[28839]: go-librespot daemon starting... Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="app state loaded" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=info msg="zeroconf server listening on port 35439" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="obtained new client token: AACYIj7PoY4985o3QJ4URK54b8QIAJ5yZmVVLe3oYAxZta20By45iOHEfaL82pHIHUhTw7JEh+Dh0kyhIxSPwcgP8Zk3xQGnDQVH8V9V3aHCfStb+xnYR1IdOs8jiW7FpWR1Af+WpPNA59HIh+cnhhN5abgnGw/FoYW14b7YolOIylKJZC473nFUeOLwtX/bvZznQE1326pQB5TFVVRvjAlgylirYXtkSfrd/EbtR1xIfyx2Zj4tuU5z" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=debug msg="completed challenge" Mar 03 21:20:09 volumio-wz go-librespot[28839]: time="2025-03-03T21:20:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:10 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:10 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:11 volumio-wz volumio[834]: info: Executing endpoint metavolumio Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 21:20:11 volumio-wz volumio[834]: info: Executing endpoint metavolumio Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:20:11 volumio-wz volumio[834]: info: Retrieving Cloud Streaming UI Mar 03 21:20:11 volumio-wz volumio[834]: info: Getting Tidal Cloud Configuration Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:20:11 volumio-wz volumio[834]: info: Getting Qobuz Cloud Configuration Mar 03 21:20:11 volumio-wz volumio[834]: info: Asking plugin for UI Config Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:20:11 volumio-wz volumio[834]: info: Getting Spotify Cloud Configuration Mar 03 21:20:11 volumio-wz volumio[834]: info: Asking plugin for UI Config Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:20:11 volumio-wz volumio[834]: info: Saving Spotify Acccount Mar 03 21:20:11 volumio-wz volumio[834]: info: Got it Mar 03 21:20:11 volumio-wz volumio[834]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 21:20:11 volumio-wz volumio[834]: info: Got Tidal Cloud Configuration Mar 03 21:20:11 volumio-wz volumio[834]: info: Got it Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 21:20:11 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 21:20:12 volumio-wz volumio[834]: info: Tunnel connection is inactive, restarting it Mar 03 21:20:12 volumio-wz volumio[834]: info: Starting Tunnel 1 Mar 03 21:20:12 volumio-wz volumio[834]: info: Starting Tunnel Connection Checker Mar 03 21:20:12 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 21:20:12 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 21:20:12 volumio-wz sudo[28852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 03 21:20:12 volumio-wz sudo[28852]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 21:20:12 volumio-wz autossh[27985]: received signal to exit (15) Mar 03 21:20:12 volumio-wz systemd[1]: Stopping MyVolumio SSH Tunnel... Mar 03 21:20:12 volumio-wz systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Mar 03 21:20:12 volumio-wz systemd[1]: sshtunnel.service: Succeeded. Mar 03 21:20:12 volumio-wz systemd[1]: Stopped MyVolumio SSH Tunnel. Mar 03 21:20:12 volumio-wz volumio[834]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 21:20:12 volumio-wz volumio[834]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 03 21:20:12 volumio-wz systemd[1]: Started MyVolumio SSH Tunnel. Mar 03 21:20:12 volumio-wz sudo[28852]: pam_unix(sudo:session): session closed for user root Mar 03 21:20:12 volumio-wz volumio[834]: info: Remote SSH Started Mar 03 21:20:12 volumio-wz autossh[28859]: port set to 0, monitoring disabled Mar 03 21:20:12 volumio-wz autossh[28859]: starting ssh (count 1) Mar 03 21:20:12 volumio-wz autossh[28859]: ssh child pid is 28862 Mar 03 21:20:12 volumio-wz volumio[834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 03 21:20:12 volumio-wz volumio[834]: info: CoreCommandRouter::volumioGetState Mar 03 21:20:12 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:12 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:13 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:13 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1062. Mar 03 21:20:13 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:13 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:13 volumio-wz go-librespot[28863]: go-librespot daemon starting... Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="app state loaded" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=info msg="zeroconf server listening on port 38701" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="obtained new client token: AACF17D550RKamxpqR31yAgtFmcyOLLUQfgxpTfo00vUPbMI3ejuVQPgfUARN/0GeshqQOpAJ2yKfLVaf3iXpAr8JRxt6AzeFr+ZsvCFuNG4gUdD6AprzhUFrmuPtJnpcydEer5XyHgtiP3chprTF+GQ+nsrlvaWmBTXj8aAB0SQXWClLYay7w6Tfq6Hol+2jSZC5u5jEveW/qPlGQTuPRW5FB0CLYTy6tx9a5rf4mDE3VSxn5iqR0wg" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=debug msg="completed challenge" Mar 03 21:20:13 volumio-wz go-librespot[28863]: time="2025-03-03T21:20:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:13 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:13 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:15 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:15 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:16 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 21:20:16 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:16 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1063. Mar 03 21:20:16 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:16 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:16 volumio-wz go-librespot[28874]: go-librespot daemon starting... Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="app state loaded" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=info msg="zeroconf server listening on port 34473" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="obtained new client token: AABHnksYNh/NyOD4tEUbL8HFqJuENaB0MX3jiFepdnkPpzjJqa9eab3jQBmpw6pQdBbZGznFBZ+IsFB+R75wmdTACZkun3BT16jImTDuuoGaKeljwM0GRLErRWN0vzUPSGC/8JdkgXT8DUpxelq3c0YCa+d7NyP8LWQvLRS7XaXWsPL67edPRFaQe/gcXTWI4rAeVQGXuuQGO5bLIZQZIsPM22jcfik/ri2mmeirSx8AgO//iPw5N4Ev" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=debug msg="completed challenge" Mar 03 21:20:16 volumio-wz go-librespot[28874]: time="2025-03-03T21:20:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:16 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:16 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:18 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:18 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:20 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:20 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1064. Mar 03 21:20:20 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:20 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:20 volumio-wz go-librespot[28896]: go-librespot daemon starting... Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="app state loaded" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=info msg="zeroconf server listening on port 45937" Mar 03 21:20:20 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="obtained new client token: AACbyTg20U5/pbJz6lYRTHYxu4+70RVDu5YbzLl6qnXZrpCHu8v91itzIYv3rTG9a9fzmX90zEW1z+FN403YKpThORm3RjvTZo9D15gz0B8IvoeeYZb6akO7HWv0sNu69BDRASXufyYA3WunXSa42NYjpD5FR72EO5DD6Ia16sXP+TwLYRHPd/n8Cbp0maaIvmgGNvfs01L06IkZAnlHUVXchwFOhS8dFavih5FwjDd/HGcghrGOu/Yj" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=debug msg="completed challenge" Mar 03 21:20:20 volumio-wz go-librespot[28896]: time="2025-03-03T21:20:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:20 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:20 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:21 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:21 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:23 volumio-wz systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 03 21:20:23 volumio-wz systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1065. Mar 03 21:20:23 volumio-wz systemd[1]: Stopped go-librespot Daemon. Mar 03 21:20:23 volumio-wz systemd[1]: Started go-librespot Daemon. Mar 03 21:20:23 volumio-wz go-librespot[28904]: go-librespot daemon starting... Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=info msg="running go-librespot 0.2.0" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="app state loaded" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="stored credentials not found" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=info msg="zeroconf server listening on port 46747" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="obtained new client token: AABHKuYEPSp8+GywLwK5k26q18Zr1Klkbv19dJhXykWSAdkhnEc3+usEKtD9iaDd7P7GcUiyQe6NoHKyXBm71Sg4KzoEL6h2Fqe9w7BCupm865S3Zb8DDGP5xmk8dwgr3nORRTkXsyhH3/FKqWTiaPFPU2KDgJSY5SAyFzS89/BU5Q35aZd7BGMGT7l+VYzLQoNSyAVCTYt5WVigCom0aFO0stV68Y1kMmYn7COuMLtIzDYlwpeUu0/i" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="completed keyexchange" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=debug msg="completed challenge" Mar 03 21:20:23 volumio-wz go-librespot[28904]: time="2025-03-03T21:20:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 03 21:20:23 volumio-wz systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 03 21:20:23 volumio-wz systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 03 21:20:24 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 21:20:24 volumio-wz volumio[834]: info: Initializing connection to go-librespot Websocket Mar 03 21:20:24 volumio-wz volumio[834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 03 21:20:25 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 03 21:20:25 volumio-wz volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 03 21:20:25 volumio-wz volumio[834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 21:20:25 volumio-wz volumio[834]: Error: dns service error: unknown Mar 03 21:20:25 volumio-wz volumio[834]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 03 21:20:25 volumio-wz volumio[834]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 03 21:20:25 volumio-wz volumio[834]: errorCode: -65537 Mar 03 21:20:25 volumio-wz volumio[834]: } Mar 03 21:20:25 volumio-wz volumio[834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 21:20:25 volumio-wz sudo[28928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 21:19 Mar 03 21:20:25 volumio-wz sudo[28928]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"