Aug 26 18:56:57 musicpi ntpd[997]: CLOCK: time stepped by 12405543.225383 Aug 26 18:56:57 musicpi ntpd[997]: CLOCK: time changed from 2026-04-05 to 2026-08-26 Aug 26 18:56:57 musicpi ntpd[997]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 26 18:56:57 musicpi go-librespot[2197]: time="2026-08-26T18:56:57-04:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-04-05T04:57:54-04:00 is before 2026-07-09T00:00:00Z" Aug 26 18:56:57 musicpi systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 26 18:56:57 musicpi systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 26 18:56:57 musicpi systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 26 18:56:57 musicpi systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 26 18:56:57 musicpi systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 26 18:56:57 musicpi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:56:57 musicpi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 18:56:57 musicpi systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 26 18:56:57 musicpi systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 26 18:56:57 musicpi systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 26 18:56:58 musicpi volumio[1187]: info: Connection to go-librespot Websocket closed Aug 26 18:56:59 musicpi volumio[1187]: info: Getting Spotify volume Aug 26 18:57:00 musicpi volumio[1187]: info: Discovery: adding 63918cf3-cd16-4800-bdd9-b150b2831964 Aug 26 18:57:00 musicpi volumio[1187]: info: Discovery: Found device musicpi Aug 26 18:57:00 musicpi volumio[1187]: info: CoreCommandRouter::volumioGetState Aug 26 18:57:00 musicpi volumio[1187]: info: CorePlayQueue::getTrack 0 Aug 26 18:57:00 musicpi volumio[1187]: info: Discovery: this is already registered, 63918cf3-cd16-4800-bdd9-b150b2831964 Aug 26 18:57:00 musicpi volumio[1187]: info: Discovery: Found device musicpi Aug 26 18:57:00 musicpi volumio[1187]: info: CoreCommandRouter::volumioGetState Aug 26 18:57:00 musicpi volumio[1187]: info: CorePlayQueue::getTrack 0 Aug 26 18:57:00 musicpi volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 18:57:00 musicpi volumio[1187]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 18:57:00 musicpi volumio[1187]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 26 18:57:00 musicpi volumio[1187]: errno: -111, Aug 26 18:57:00 musicpi volumio[1187]: code: 'ECONNREFUSED', Aug 26 18:57:00 musicpi volumio[1187]: syscall: 'connect', Aug 26 18:57:00 musicpi volumio[1187]: address: '127.0.0.1', Aug 26 18:57:00 musicpi volumio[1187]: port: 9879, Aug 26 18:57:00 musicpi volumio[1187]: response: undefined Aug 26 18:57:00 musicpi volumio[1187]: } Aug 26 18:57:00 musicpi volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 18:57:00 musicpi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 26 18:57:00 musicpi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 18:57:00 musicpi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 18:57:00 musicpi go-librespot[2242]: go-librespot daemon starting... Aug 26 18:57:00 musicpi go-librespot[2243]: time="2026-08-26T18:57:00-04:00" level=info msg="running go-librespot 0.4.0" Aug 26 18:57:00 musicpi go-librespot[2243]: time="2026-08-26T18:57:00-04:00" level=debug msg="app state loaded" Aug 26 18:57:00 musicpi go-librespot[2243]: time="2026-08-26T18:57:00-04:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=info msg="zeroconf server listening on port 34433" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=debug msg="obtained new client token: AAGK6VZJ1KFocd/S1efpVFdSOPDzOe8qBSCJzeltAtB/eWl8BmLJKa6a3cxM+qgPbVyIPR43JJyzyLphk2pr0pm94grWrUZn0gE8lKRHmLs1R3O/x1wBTnQflJNFsWXbsCFWkmxIwP5iG5heqGIhwM5W5sNLg9IJTlkb7BwlK09laF8MPZOqe8VUG+92qwS5MZmdvtDLQWQnsPab9D38hMeO+xVq+F+yQvc+NDK8xBpdIVukG0EdQ0058Q==" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Aug 26 18:57:01 musicpi go-librespot[2243]: time="2026-08-26T18:57:01-04:00" level=debug msg="connected to ap-guc3.spotify.com:443" Aug 26 18:57:02 musicpi go-librespot[2243]: time="2026-08-26T18:57:02-04:00" level=debug msg="completed keyexchange" Aug 26 18:57:02 musicpi go-librespot[2243]: time="2026-08-26T18:57:02-04:00" level=debug msg="completed challenge" Aug 26 18:57:02 musicpi go-librespot[2243]: time="2026-08-26T18:57:02-04:00" level=info msg="authenticated AP" username="go****en" Aug 26 18:57:02 musicpi go-librespot[2243]: time="2026-08-26T18:57:02-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 18:57:02 musicpi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 18:57:02 musicpi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 18:57:03 musicpi sudo[2254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 18:56' Aug 26 18:57:03 musicpi sudo[2254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"