-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Thu 2026-08-27 13:15:40 EDT. -- Aug 27 13:14:00 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 13:14:00 volumio volumio[1107]: info: Received Get System Info Aug 27 13:14:00 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 13:14:00 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 13:14:00 volumio volumio[1107]: info: Discovery: Getting this device information Aug 27 13:14:00 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Aug 27 13:14:00 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Aug 27 13:14:00 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 13:14:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 27 13:14:00 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:00 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:00 volumio go-librespot[6239]: go-librespot daemon starting... Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=debug msg="app state loaded" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=info msg="zeroconf server listening on port 43605" Aug 27 13:14:00 volumio go-librespot[6239]: time="2026-08-27T13:14:00-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=debug msg="obtained new client token: AAFPvs3sQ9mvfcmGhN+FRX+hnHJX4iYwtzhCqShLIur63+Oc4I610fuRyLwQnJcXAgZGet5eqFcAuUWvaSWb3qf6CpFhsR53iDO+2Lb8uBs5o9AWsBoRm6GERSr8F9wa2rI6Xx+hnp6H/x2S/Tq/WElTpduZHBZRZk0P5bYaLD0uyEI/wAzqoSFiy5UGLUQETzx5NLkb81bdPmlAglgxJKXGqRlToZ4oWOTviPTLPTPusP5gsVgo" Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 13:14:01 volumio volumio[1107]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 27 13:14:01 volumio volumio[1107]: info: Received Get System Version Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 13:14:01 volumio volumio[1107]: info: Received Get System Info Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 13:14:01 volumio volumio[1107]: info: Discovery: Getting this device information Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Aug 27 13:14:01 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Aug 27 13:14:01 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=debug msg="completed challenge" Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:01 volumio go-librespot[6239]: time="2026-08-27T13:14:01-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:01 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:01 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:04 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:04 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 27 13:14:04 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:04 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:04 volumio go-librespot[6251]: go-librespot daemon starting... Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=debug msg="app state loaded" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=info msg="zeroconf server listening on port 35181" Aug 27 13:14:04 volumio go-librespot[6251]: time="2026-08-27T13:14:04-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:05 volumio go-librespot[6251]: time="2026-08-27T13:14:05-04:00" level=debug msg="obtained new client token: AAFUk2rhJrj3To35Z4n1l/QRWyi0GXwydCsksW6WCSwiXmiGdGe26c7ATw/fVTTt34LxoaklwU07XvYluKBPteOxrAb0EX9p1Et4NvINtjFiRrQ7FRKcxMjDhfVD3AFYy83Fe95NPDCuic2MI6RCbqbLk+YmHR3o/BguAx2qZfCw+0h7pBY60QSfPnzB2gHwQ1g72vQHJoQyZaQPQJLdTKmpU2Bgy6zhYSmg2fnezlJ7Q+V88vRa" Aug 27 13:14:05 volumio go-librespot[6251]: time="2026-08-27T13:14:05-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:05 volumio go-librespot[6251]: time="2026-08-27T13:14:05-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:05 volumio go-librespot[6251]: time="2026-08-27T13:14:05-04:00" level=debug msg="completed challenge" Aug 27 13:14:05 volumio go-librespot[6251]: time="2026-08-27T13:14:05-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:05 volumio go-librespot[6251]: time="2026-08-27T13:14:05-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:07 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:07 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 27 13:14:08 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:08 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:08 volumio go-librespot[6275]: go-librespot daemon starting... Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="app state loaded" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=info msg="zeroconf server listening on port 39063" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="obtained new client token: AAHFPmeL2r55Xoe9nMlTzhMdh7tpeOCR0d9KkR28p4E0ClisUBJefiYc4pux9bNSo5+RIRvvi2hvGYHz+Vf9xTdaB0/T5WCOFdURU0Wh4AkBXnUh3ESupzxtq+Q+JJJygxB+YPORsXzOT4GUEm7eJ1q1SQbSs9Gp2DHMhRLgi6XFLf62rI8hlrp6Wt5Td3vcKmktb6a8a4OWl/3PQ5PsUSNfd6W8DKQvwaul4PFM0TCVc2+GNRKqlYc=" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=debug msg="completed challenge" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:08 volumio go-librespot[6275]: time="2026-08-27T13:14:08-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:09 volumio volumio[1107]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 13:14:10 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:10 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 27 13:14:12 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:12 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:12 volumio go-librespot[6287]: go-librespot daemon starting... Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="app state loaded" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=info msg="zeroconf server listening on port 38779" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="obtained new client token: AAGf0dmKedOSRHNzysEU5ppFTymqrmuxOhz8W0rGWxlZ5FVKFnCTXs7Z+DYnyvK/2I2EPDqnN16/6RvUUp21SbQmhxD6U1Akg+MSAKn7x0ng1rYub1X1BA/BX5J8COFhPYJu7PIlDzRQ2kzV7d8G2rttA7yndRdD5g/67YY5JL+BlG9IeY6X0Dtu4ecNbil4pdyuV11nToqFF8J+r6bN4OqkY5FRe8I496xSvm/d0M6BErF4Gk1KDTM=" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=debug msg="completed challenge" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:12 volumio go-librespot[6287]: time="2026-08-27T13:14:12-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:13 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:13 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 27 13:14:15 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:15 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:15 volumio go-librespot[6297]: go-librespot daemon starting... Aug 27 13:14:15 volumio go-librespot[6297]: time="2026-08-27T13:14:15-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:15 volumio go-librespot[6297]: time="2026-08-27T13:14:15-04:00" level=debug msg="app state loaded" Aug 27 13:14:15 volumio go-librespot[6297]: time="2026-08-27T13:14:15-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=info msg="zeroconf server listening on port 40323" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="obtained new client token: AAHeCpbShZ69rqITbj5V2sK8URDGbzKdjekUJOnC3SXGA54bGc98ClBVN69ohM6laCSISxAdM3GT2kPDmIarKC1sufIgw4i/9/Df+VaGG2qRSWI9/wCoNynU8inJuf4TJIQQavMz6RQoeBVvUxIPNO5bw410ERe2bUhV3h634pQjLevY4f6acmPArn2hrDAin8mRSKoWnZ7va3qwgcN4i56tkIgVeXnWc1/0BBrQ2bI3McSd5jTtcNo=" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="completed challenge" Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:16 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=debug msg="new websocket client" Aug 27 13:14:16 volumio volumio[1107]: info: Connection to go-librespot Websocket established Aug 27 13:14:16 volumio go-librespot[6297]: time="2026-08-27T13:14:16-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:16 volumio volumio[1107]: info: Connection to go-librespot Websocket closed Aug 27 13:14:19 volumio volumio[1107]: info: Getting Spotify volume Aug 27 13:14:19 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:19 volumio volumio[1107]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 13:14:19 volumio volumio[1107]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 27 13:14:19 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Aug 27 13:14:19 volumio volumio[1107]: (node:1107) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Aug 27 13:14:19 volumio volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 13:14:19 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Aug 27 13:14:19 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Aug 27 13:14:19 volumio volumio[1107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Aug 27 13:14:19 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:19 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 27 13:14:19 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:19 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:19 volumio go-librespot[6322]: go-librespot daemon starting... Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=debug msg="app state loaded" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=info msg="zeroconf server listening on port 35273" Aug 27 13:14:19 volumio go-librespot[6322]: time="2026-08-27T13:14:19-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:20 volumio go-librespot[6322]: time="2026-08-27T13:14:20-04:00" level=debug msg="obtained new client token: AAEch7R/TqEnIXhuF+czEfnQpTPQ7gkMiv9pJV+Qp9Q8m4GvIFCWLztFsv5uZSZIvkjY8XrC3WOAAgURZ3KOl4dwTn5wxcmYdp7DHhazqAv93ATwEcCsf7cM3d5hxouwHzjMtDpeFLMfYaXPwptlmB1AYss+BRQy0qqArwMrsypnhw96JPirtEHrTYs3k867Icae5hlhYC5Q8IdGmH1EwtRB+G9FRwRdu0JafmV1/S5kXGem8SEQ" Aug 27 13:14:20 volumio go-librespot[6322]: time="2026-08-27T13:14:20-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:20 volumio go-librespot[6322]: time="2026-08-27T13:14:20-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:20 volumio go-librespot[6322]: time="2026-08-27T13:14:20-04:00" level=debug msg="completed challenge" Aug 27 13:14:20 volumio go-librespot[6322]: time="2026-08-27T13:14:20-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:20 volumio go-librespot[6322]: time="2026-08-27T13:14:20-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:20 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 13:14:20 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 13:14:22 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:22 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 27 13:14:23 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:23 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:23 volumio go-librespot[6370]: go-librespot daemon starting... Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="app state loaded" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=info msg="zeroconf server listening on port 39343" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="obtained new client token: AAHKsy7EKQ2ueAXI5ODFTAvDR1yHmcabPPSCiraxudPIrUuLCXf1e0jUzc25HT6xJ8AUWx/ub97OzalVr6Jq5ndStpvL1ZfzkCVgUdnPrCgtSk4s3SefoIl9cEDs9lHJfujdXlc+OJ+l/Bs/2gIZ+62mkSFdw/sRD8gP7CByt9tVBIJ4gkyh28rlzsXGoXpxfmom8CSbGbBzyz0ti6W01038kwm+zYzl13zgX/r1w7qx7TQeW3qtJYc=" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=debug msg="completed challenge" Aug 27 13:14:23 volumio go-librespot[6370]: time="2026-08-27T13:14:23-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:24 volumio go-librespot[6370]: time="2026-08-27T13:14:24-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:25 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:25 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Aug 27 13:14:27 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:27 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:27 volumio go-librespot[6396]: go-librespot daemon starting... Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="app state loaded" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=info msg="zeroconf server listening on port 42871" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="obtained new client token: AAH7Zd3leBkr+aPrLDV6OqoeZEn4EMf+MGtcXEy1GHJAXEThV9VAe1kImFqdWNZgdBMOHudCDAkWOPEE1t44eIKl02A2Ys5968uAo0iTSwhecMLrwAklwBBEKN1EYX0ysnHNp57alFSQ1pnR0j0ZgDmoOCedEcHAMblh6WuEP+y49jLM10YZH9EZQMtivO86t0aB1kuaZBbmHyBF2hbwdFjzPViaMJNBa+usJNo25ciiykZoIMW5gT8=" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=debug msg="completed challenge" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:27 volumio go-librespot[6396]: time="2026-08-27T13:14:27-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:28 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:28 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Aug 27 13:14:30 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:30 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:30 volumio go-librespot[6409]: go-librespot daemon starting... Aug 27 13:14:30 volumio go-librespot[6409]: time="2026-08-27T13:14:30-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:30 volumio go-librespot[6409]: time="2026-08-27T13:14:30-04:00" level=debug msg="app state loaded" Aug 27 13:14:30 volumio go-librespot[6409]: time="2026-08-27T13:14:30-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=info msg="zeroconf server listening on port 37051" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="obtained new client token: AAE2BSP84GOXG3zeX5zcWDorrL0XvSYdvj5cGVEcZX0ouvx78mC2Z+fxrmR6fYL5qbgtf8rEUKS4UxOyj7HKKj2P8pdS7V42TMGIErq4pLzOnoXfSeWywuNOr70yMWz2TF+GNvorHjOXzAy9zpUq5u+CdN0NWrkh4EfWWNq9eP+Prj9MT4LGlV8EiUZiT9v7eS15Nsx/TiKodvmQcqfSB7VULuQwBw7eFiPihW1KAg9qCmZrPbz4" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=debug msg="completed challenge" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:31 volumio go-librespot[6409]: time="2026-08-27T13:14:31-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:31 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:31 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:34 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:34 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Aug 27 13:14:34 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:34 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:34 volumio go-librespot[6419]: go-librespot daemon starting... Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=debug msg="app state loaded" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=info msg="zeroconf server listening on port 34263" Aug 27 13:14:34 volumio go-librespot[6419]: time="2026-08-27T13:14:34-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:35 volumio go-librespot[6419]: time="2026-08-27T13:14:35-04:00" level=debug msg="obtained new client token: AAHADo160m06CMvC2lk3Wup1nknGw8jcbyjdUVZel7AolUtUFXsr9Thg5OKLBeaozR0PCk6TGAAk5sHINCSZliUslY7d7Rj/+1eW0eVQ5DiApC/0cQG4lfTgtqLKSXscqFpWnXiInmTqqBuRhabB6Cl8liVclxSHHHSdxWws2st08cLqwViG2ajiJdpUEiN8wAoRaKScYBtP0jbTF+YOdvFp5CO7S5+kQX36Ul5q7YqlPH2pZEo+" Aug 27 13:14:35 volumio go-librespot[6419]: time="2026-08-27T13:14:35-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:35 volumio go-librespot[6419]: time="2026-08-27T13:14:35-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:35 volumio go-librespot[6419]: time="2026-08-27T13:14:35-04:00" level=debug msg="completed challenge" Aug 27 13:14:35 volumio go-librespot[6419]: time="2026-08-27T13:14:35-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:35 volumio go-librespot[6419]: time="2026-08-27T13:14:35-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:37 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:37 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Aug 27 13:14:38 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:38 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:38 volumio go-librespot[6443]: go-librespot daemon starting... Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="app state loaded" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=info msg="zeroconf server listening on port 33117" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="obtained new client token: AAG4Gf7PB0bAH4QGteATu7ykMwZcGOwZNeEpwBzmmXIKPvbVuhTzi8334va1PIJhwa0ySCMeHXKwM4pKaWfG3wniiiGX6p/TJqg3OrgeT7/QSt5RqefuZZHqI8cZdym8LGMgjQkaHYP7dGBE0LC9ayIfsqrC2XXwZyY5qUm1xpLYy/LWLwVznACfZRJKM7P8GjC4XQHsS9BqoJV9KmjEEdLrQxCqs/rpaPp+T4R892a6t/By+hFMccA=" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=debug msg="completed challenge" Aug 27 13:14:38 volumio go-librespot[6443]: time="2026-08-27T13:14:38-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:39 volumio go-librespot[6443]: time="2026-08-27T13:14:39-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:40 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:40 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Aug 27 13:14:42 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:42 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:42 volumio go-librespot[6454]: go-librespot daemon starting... Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="app state loaded" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:42 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=info msg="zeroconf server listening on port 39215" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="obtained new client token: AAEye1BEDNVRDzkb2Wzsp/+C3jlIV2HbaSyb1Fo+2KrU/xPTaMDf0bP0LE6w/wvQqrCWiI021uHzp2Net/YnChjkHwE0U/eOP+H5hHxIeSM9dU4ktd/kW3B98Dyu48KoD90T0kyfKz53u8ZnoIFswbwzyD665HhSy1jZYFjLLbqIwkV6D79ZIP/YIE75q5aiqwh1wfS1xmoi56SRaJZEoK5PXx5dvCb2p9wYJGMysXzC5fba/HeeFlI=" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=debug msg="completed challenge" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:42 volumio go-librespot[6454]: time="2026-08-27T13:14:42-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:43 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:43 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Aug 27 13:14:45 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:45 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:45 volumio go-librespot[6465]: go-librespot daemon starting... Aug 27 13:14:45 volumio go-librespot[6465]: time="2026-08-27T13:14:45-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:45 volumio go-librespot[6465]: time="2026-08-27T13:14:45-04:00" level=debug msg="app state loaded" Aug 27 13:14:45 volumio go-librespot[6465]: time="2026-08-27T13:14:45-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=info msg="zeroconf server listening on port 35987" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="obtained new client token: AAGtNL1nRuNAopRqmA7wLfWVBBp0EcHL69Fha6d7QXJyZ6NEmFT5Mka0jiFr+nUH7mDkhNwojZQWGRPLm8cjEEiWIlreposlCE9X7ZLRoHTnFXuYUOjKfVXXRj9MIJwsJoHddSa0DQ9aX1FxeS35N6efmC8RimnFOjhYIqHYo9wOLhKr2OGO5w7OESGhghlGmti95/5NZaoYFiWf251jDmZbHMD19DJw9rg/tYK00fWB8r8OzXsB" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=debug msg="completed challenge" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:46 volumio go-librespot[6465]: time="2026-08-27T13:14:46-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:46 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:46 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:49 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:49 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Aug 27 13:14:49 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:49 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:49 volumio go-librespot[6489]: go-librespot daemon starting... Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=debug msg="app state loaded" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=info msg="zeroconf server listening on port 35729" Aug 27 13:14:49 volumio go-librespot[6489]: time="2026-08-27T13:14:49-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:50 volumio go-librespot[6489]: time="2026-08-27T13:14:50-04:00" level=debug msg="obtained new client token: AAFSblw9S5FZRq6717FrBrXeWahH9SpGl9glVRyF5NkN4aFdDkEas2ZT8HpDucpXTxLR5n5q0B2MOLrdj8GZUazizbi3sY1ky7J6w6CTYNCpYrbmbh9GefM7BtFcXm/0hxhC0P4mI0lCerxIL2qFUqHMQZD9iDPP3ecTNiv43vH1rCosYXzyWtf3Og97vsqMKKznPlM+my2jcoRA+yeRzqzxmMII7pv/7n95lwe4HQHk4YGI4iWX" Aug 27 13:14:50 volumio go-librespot[6489]: time="2026-08-27T13:14:50-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:50 volumio go-librespot[6489]: time="2026-08-27T13:14:50-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:50 volumio go-librespot[6489]: time="2026-08-27T13:14:50-04:00" level=debug msg="completed challenge" Aug 27 13:14:50 volumio go-librespot[6489]: time="2026-08-27T13:14:50-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:50 volumio go-librespot[6489]: time="2026-08-27T13:14:50-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:52 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:52 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Aug 27 13:14:53 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:53 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:53 volumio go-librespot[6500]: go-librespot daemon starting... Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="app state loaded" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=info msg="zeroconf server listening on port 41407" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="obtained new client token: AAE/VHqqRJ/BEHOANa63WopCa/0C5Tm+El5ncXFDXprVNoiuLdaSCP6yAaoeCYP7exJNgBz933pe6e5Pg3mnUgE9RVR3mbKyI4XlW6xb4jW1ZykFLgbeiTojAVb9xjOr/zd1Y/n99YrS7RwtSsMgMhcWyiX3VFVvC6A3zq+ZwkvdTGhW7SLsV9lp3jZQC6ndkbbXDkyfzdwSj4QS9NSGM4vZxokMOLlUaakZbCPqZ8tmJ7MTKTCJ48g=" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=debug msg="completed challenge" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:53 volumio go-librespot[6500]: time="2026-08-27T13:14:53-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:55 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:55 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:14:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Aug 27 13:14:57 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:14:57 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:14:57 volumio go-librespot[6524]: go-librespot daemon starting... Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="app state loaded" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=info msg="zeroconf server listening on port 33421" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="obtained new client token: AAHIgwaBClpQ0t02jsZB/WDBAo52A4DP5fEfhT25WKwX91gpHHSCHJv6ZknoudGd+G0mAGIO5S2NX5atwt/hEbI73odMT5FryzZDzj7ncFWBOpcvXcJu0hjIBFtuCYu6Ju2mc9LmjYiT3uhKzQjEJOT4SSZ6715+cwKQYS/zjJE37MKmvP64TD98SFWXXv7UT77JZqxspb/l6mo7w5+/jjvA1ast0iy9+JwhZh0pT/3pVccxZDYWOqk=" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="completed keyexchange" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=debug msg="completed challenge" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:14:57 volumio go-librespot[6524]: time="2026-08-27T13:14:57-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:14:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:14:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:14:58 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:14:58 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:14:58 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 13:15:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Aug 27 13:15:00 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:00 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:00 volumio go-librespot[6534]: go-librespot daemon starting... Aug 27 13:15:00 volumio go-librespot[6534]: time="2026-08-27T13:15:00-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:00 volumio go-librespot[6534]: time="2026-08-27T13:15:00-04:00" level=debug msg="app state loaded" Aug 27 13:15:00 volumio go-librespot[6534]: time="2026-08-27T13:15:00-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=info msg="zeroconf server listening on port 46259" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=debug msg="obtained new client token: AAHJ/2I8x0YKGE5zp418DqQCZyafadVn7InvWzoR9GGI3mAfYNuSWhGl1w+8vpgmjLXl3HxbiQ1phmEeLbOhbLhWv6Ng+7N+o0ic+svUo4CG1fvyqIWIf2alkA37yAB5qK2shJ8UDE9G5xl9qUEvJa+t51lCuzhUgZOV1op/zRyGGKymFzpdaFYMNa8saAv/H9R/5ExL7HCMy3srjo07YnuPdRSsDy/vw76pjo7ldFbAUaeQqhEAe44=" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 27 13:15:01 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:01 volumio go-librespot[6534]: time="2026-08-27T13:15:01-04:00" level=debug msg="new websocket client" Aug 27 13:15:01 volumio volumio[1107]: info: Connection to go-librespot Websocket established Aug 27 13:15:03 volumio go-librespot[6534]: time="2026-08-27T13:15:03-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:03 volumio go-librespot[6534]: time="2026-08-27T13:15:03-04:00" level=debug msg="completed challenge" Aug 27 13:15:03 volumio go-librespot[6534]: time="2026-08-27T13:15:03-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:03 volumio go-librespot[6534]: time="2026-08-27T13:15:03-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:03 volumio volumio[1107]: info: Connection to go-librespot Websocket closed Aug 27 13:15:04 volumio volumio[1107]: info: Getting Spotify volume Aug 27 13:15:04 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:04 volumio volumio[1107]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 13:15:04 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Aug 27 13:15:04 volumio volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 27 13:15:04 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Aug 27 13:15:04 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Aug 27 13:15:04 volumio volumio[1107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Aug 27 13:15:06 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:06 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Aug 27 13:15:07 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:07 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:07 volumio go-librespot[6571]: go-librespot daemon starting... Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=debug msg="app state loaded" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=info msg="zeroconf server listening on port 36713" Aug 27 13:15:07 volumio go-librespot[6571]: time="2026-08-27T13:15:07-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:09 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:09 volumio go-librespot[6571]: time="2026-08-27T13:15:09-04:00" level=debug msg="new websocket client" Aug 27 13:15:09 volumio volumio[1107]: info: Connection to go-librespot Websocket established Aug 27 13:15:12 volumio go-librespot[6571]: time="2026-08-27T13:15:12-04:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: invalid status code from clienttoken: 503" Aug 27 13:15:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:12 volumio volumio[1107]: info: Connection to go-librespot Websocket closed Aug 27 13:15:12 volumio volumio[1107]: info: Getting Spotify volume Aug 27 13:15:12 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:12 volumio volumio[1107]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 13:15:12 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Aug 27 13:15:12 volumio volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 27 13:15:12 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Aug 27 13:15:12 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Aug 27 13:15:13 volumio volumio[1107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Aug 27 13:15:15 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:15 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Aug 27 13:15:15 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:15 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:15 volumio go-librespot[6581]: go-librespot daemon starting... Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="app state loaded" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=info msg="zeroconf server listening on port 46629" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="obtained new client token: AAF89QKa0APiLaoSC2yVK4Ig/V4Abab9Xdm2AEXdc5WM8tgfL8n0WkWWNZ82IVjJBj+Ul/SZ9+PXyG+ulD2pZ9bfpZmShmt+6wFJu/LNWOjO3hMo6Joc1hRFpDAUWQW9w8SqgwjwvAHqJu2+q7yjuWgji3ebF5dx7a8YTzGqU9MpmyRALImDOC01dngiIhsrQwECkaAZx93HXcMHku6HzUM5Ut+n4g5ssgtxWaEi/9rd5qbuuVnKa6Y=" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:15 volumio go-librespot[6581]: time="2026-08-27T13:15:15-04:00" level=debug msg="completed challenge" Aug 27 13:15:16 volumio go-librespot[6581]: time="2026-08-27T13:15:16-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:16 volumio go-librespot[6581]: time="2026-08-27T13:15:16-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:18 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:18 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Aug 27 13:15:19 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:19 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:19 volumio go-librespot[6605]: go-librespot daemon starting... Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="app state loaded" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=info msg="zeroconf server listening on port 43087" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="obtained new client token: AAFzJxNIZMBCFxHUJI7zTt2zTVApM/0H7PV/PgcfNIr5tnsFUE8r1zU9JDXbVSxvgsul3SN72jpH45dvYMX0l0mbt3siKzqFpewPGfxS9tzFTco1IAnBrhuyDv+qptk3Q8Re0HFDrLG2pZlX4DBE24pJ8AnAnp4OoDyCLRzf3N2sCN6XA931ksSczRLIxDJ8YsE8YC0wyXhkJflzifnXwdITfJ5iMAyZ+8U/4TaxTD5SK50Mkmoufmc=" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=debug msg="completed challenge" Aug 27 13:15:19 volumio go-librespot[6605]: time="2026-08-27T13:15:19-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:20 volumio go-librespot[6605]: time="2026-08-27T13:15:20-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:21 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:21 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:22 volumio volumio[1107]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf Aug 27 13:15:22 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:22.803-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Aug 27 13:15:23 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:23 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:23 volumio go-librespot[6657]: go-librespot daemon starting... Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="app state loaded" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=info msg="zeroconf server listening on port 36859" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="obtained new client token: AAHrudrdFMrfEFi96UgQamEp+3se4EQpLf1xI7Wga71ZWoImq/nzNNwFmUuIORJWj+tcRtso3cxI4yV3eXa1rXZEEa50prqRmKLxATxT1z5KvQh+G7uoApPA8UPjTLqay+DJ3tMg2fFLJwAX2jdSML/Z974iv09PpdTAfburCneLgxTZ+DxGxLTmZtUiTpUrh6qh468K6p7/QqW+htpgdHbcjsGqWVdKAdB8A5qCWTBnIsLgIdra99o=" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=debug msg="completed challenge" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:23 volumio go-librespot[6657]: time="2026-08-27T13:15:23-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:23 volumio volumio[1107]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf Aug 27 13:15:23 volumio volumio[1107]: info: Folder /tmp/plugins removed Aug 27 13:15:23 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:23.918-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.031-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Check plugin dependencies Aug 27 13:15:24 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.084-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Checking if plugin already exists Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.095-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Rename folder Aug 27 13:15:24 volumio volumio[1107]: info: Folder /tmp/downloaded_plugin.zip removed Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.136-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Move to category Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.378-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Checking if install.sh is present Aug 27 13:15:24 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:24 volumio volumio[1107]: info: Executing install.sh Aug 27 13:15:24 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:24 volumio sudo[6679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/backup_restore/install.sh Aug 27 13:15:24 volumio sudo[6679]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 13:15:24 volumio sudo[6679]: pam_unix(sudo:session): session closed for user root Aug 27 13:15:24 volumio volumio[1107]: info: Installing backup restore Dependencies Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.521-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: Plugin install end detected on script Aug 27 13:15:24 volumio volumio[1107]: info: Install script completed Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.535-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Adding reference to registry Aug 27 13:15:24 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:24.553-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:24 volumio volumio[1107]: info: Done installing plugin. Aug 27 13:15:24 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 13:15:24 volumio volumio[1107]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 13:15:24 volumio volumio[1107]: info: Folder /tmp/plugins removed Aug 27 13:15:24 volumio volumio[1107]: info: Folder /tmp/downloaded_plugin.zip removed Aug 27 13:15:24 volumio volumio[1107]: info: Folder /data/temp removed Aug 27 13:15:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Aug 27 13:15:26 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:26 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:26 volumio go-librespot[6696]: go-librespot daemon starting... Aug 27 13:15:26 volumio go-librespot[6696]: time="2026-08-27T13:15:26-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:26 volumio go-librespot[6696]: time="2026-08-27T13:15:26-04:00" level=debug msg="app state loaded" Aug 27 13:15:26 volumio go-librespot[6696]: time="2026-08-27T13:15:26-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=info msg="zeroconf server listening on port 36623" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="obtained new client token: AAFz+7Kqy5qrILvysa/fl0bvoz2pSAtiIFZeW3tGoVdcgAKP6JiALd+bPdcIey1VBqa8gSv1PAp2gwA2SgxQ0cTRJJAAu9j2dHh9RpqXq+RJpFw3eVCtf0NTAw6+sRt8TEtCdGOn0PJGdH6bvTF1sEYFVysbGfWNcZpcjQ/+vPiqsP5sTbl1cpHzqQPRr7yqUtP1RjrTW49P7RN9SCPqCeD4Qvky85fZHPCEZOm9VniZl/ABchGsFxY=" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="connected to ap-gue1.spotify.com:443" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="completed challenge" Aug 27 13:15:27 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=debug msg="new websocket client" Aug 27 13:15:27 volumio volumio[1107]: info: Connection to go-librespot Websocket established Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:27 volumio go-librespot[6696]: time="2026-08-27T13:15:27-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:27 volumio volumio[1107]: info: Connection to go-librespot Websocket closed Aug 27 13:15:27 volumio volumio[1107]: info: Enabling plugin backup_restore Aug 27 13:15:27 volumio volumio[1107]: info: Loading plugin "backup_restore"... Aug 27 13:15:27 volumio volumio[1107]: info: PLUGIN START: backup_restore Aug 27 13:15:27 volumio volumio[1107]: info: Done. Aug 27 13:15:30 volumio volumio[1107]: info: Getting Spotify volume Aug 27 13:15:30 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:30 volumio volumio[1107]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 13:15:30 volumio volumio[1107]: (node:1107) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Aug 27 13:15:30 volumio volumio[1107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 27 13:15:30 volumio volumio[1107]: info: CoreCommandRouter::volumioGetState Aug 27 13:15:30 volumio volumio[1107]: info: CorePlayQueue::getTrack 0 Aug 27 13:15:30 volumio volumio[1107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Aug 27 13:15:30 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:30 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Aug 27 13:15:30 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:30 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:30 volumio go-librespot[6706]: go-librespot daemon starting... Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=debug msg="app state loaded" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=info msg="zeroconf server listening on port 41279" Aug 27 13:15:30 volumio go-librespot[6706]: time="2026-08-27T13:15:30-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:31 volumio go-librespot[6706]: time="2026-08-27T13:15:31-04:00" level=debug msg="obtained new client token: AAHKNyh3HmxJK8d81eyVaDXGX9WlZ+vPfs0Q94OiLLIDnyMfIbhcuv/Sppm66fFvvPy7qsM0F9Ap2HuuRYUtxBbkNxZzO45FqNY4sX4ItS7/j0ifTGu3DrgbCtgnEcmSxdtLZQBZMiiIeynNx2g1k3XNSCpaUOVO7nmuitxxluFNCQyL+uxZiDzrnzsfoU6qAMAcXrB+erM3f8OnxO8fXnFH/O5Q1omDNiqyv2b4uEe1vrh7Gphd" Aug 27 13:15:31 volumio go-librespot[6706]: time="2026-08-27T13:15:31-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:15:31 volumio go-librespot[6706]: time="2026-08-27T13:15:31-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:31 volumio go-librespot[6706]: time="2026-08-27T13:15:31-04:00" level=debug msg="completed challenge" Aug 27 13:15:31 volumio go-librespot[6706]: time="2026-08-27T13:15:31-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:31 volumio go-librespot[6706]: time="2026-08-27T13:15:31-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:31 volumio volumio[1107]: info: Enabling plugin backup_restore Aug 27 13:15:31 volumio volumio[1107]: info: Loading plugin "backup_restore"... Aug 27 13:15:31 volumio volumio[1107]: info: Applying required configuration parameters for plugin backup_restore Aug 27 13:15:31 volumio volumio[1107]: info: PLUGIN START: backup_restore Aug 27 13:15:31 volumio volumio[1107]: info: Done. Aug 27 13:15:33 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:33 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Aug 27 13:15:34 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:34 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:34 volumio go-librespot[6716]: go-librespot daemon starting... Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="app state loaded" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=info msg="zeroconf server listening on port 44625" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="obtained new client token: AAH4r1U44h30ZUyemrGisEGYu4PMPnPumD55rscjGNBtg8uaDREcJ1RG3omPvyWmEBPMnX+pi6Ix6LjO7+Vx/+lBgPYLwH4Hmqg67vlqMOb1Pm6w+tnfZsrXubNd1Yvr6NfphapYHxQ7RSzKxqJhkv0VHDaRTo4/DAI4vhSIjo8sIMu+k/EHgHkZCn6ZOutBB0J1eBOm3wKyzbTrC6iA8SVqZJ5LJDYIdJt6P0ioYejXHltk8AaQuFg=" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=debug msg="completed challenge" Aug 27 13:15:34 volumio go-librespot[6716]: time="2026-08-27T13:15:34-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:35 volumio go-librespot[6716]: time="2026-08-27T13:15:35-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:36 volumio volumio[1107]: info: Initializing connection to go-librespot Websocket Aug 27 13:15:36 volumio volumio[1107]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 13:15:37 volumio volumio[1107]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Aug 27 13:15:37 volumio volumio5-onboarding[1366]: time=2026-08-27T13:15:37.663-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 27 13:15:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 27 13:15:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Aug 27 13:15:38 volumio systemd[1]: Stopped go-librespot Daemon. Aug 27 13:15:38 volumio systemd[1]: Started go-librespot Daemon. Aug 27 13:15:38 volumio go-librespot[6742]: go-librespot daemon starting... Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=info msg="running go-librespot 0.7.1" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="app state loaded" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=info msg="zeroconf server listening on port 39931" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="obtained new client token: AAGkhp8bB2sllPKNhrCCcohZLL3yVUaUEDBujYcbDHZ+sJI7UW3lsiWvuWaendnnnnneheU0ay0DRuaFNjgzKEvAuS3K+Bt/p/r5YsVrW0R3yOPpAAQTPQGJJg5uW276wo+robS6f3446Zt4xjogxEEe03YTsY6cNc7Ku/Z9Rr6ahYmB5U088QBjyE1vahy2vBS11aYmkb2TceCACwgbhBkSvGBzx6kdQFeSmVj+/zrDFo4xD0Vfdec=" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="completed keyexchange" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=debug msg="completed challenge" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=info msg="authenticated AP" username="31************************b4" Aug 27 13:15:38 volumio go-librespot[6742]: time="2026-08-27T13:15:38-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 13:15:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 13:15:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 13:15:39 volumio volumio[1107]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Aug 27 13:15:39 volumio volumio[1107]: info: FusionDsp - Stopping FusionDsp service Aug 27 13:15:39 volumio volumio[1107]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 13:15:39 volumio volumio[1107]: TypeError: Cannot read property 'stop' of null Aug 27 13:15:39 volumio volumio[1107]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Aug 27 13:15:39 volumio volumio[1107]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 27 13:15:39 volumio volumio[1107]: at /volumio/app/pluginmanager.js:956:12 Aug 27 13:15:39 volumio volumio[1107]: at ChildProcess.exithandler (child_process.js:299:7) Aug 27 13:15:39 volumio volumio[1107]: at ChildProcess.emit (events.js:315:20) Aug 27 13:15:39 volumio volumio[1107]: at maybeClose (internal/child_process.js:1048:16) Aug 27 13:15:39 volumio volumio[1107]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Aug 27 13:15:39 volumio volumio[1107]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 13:15:40 volumio sudo[6765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-27 13:14 Aug 27 13:15:40 volumio sudo[6765]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"