Oct 26 15:45:36 volumio ntpd[1046]: CLOCK: time stepped by 1897509.256224 Oct 26 15:45:36 volumio ntpd[1046]: CLOCK: time changed from 2025-10-04 to 2025-10-26 Oct 26 15:45:36 volumio ntpd[1046]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 26 15:45:36 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 26 15:45:36 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 26 15:45:36 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 26 15:45:36 volumio volumio[1129]: info: Discovery: A device disappeared from network Oct 26 15:45:36 volumio volumio[1129]: info: Discovery: Device volumio disappeared from network Oct 26 15:45:36 volumio volumio[1129]: info: Discovery: A device disappeared from network Oct 26 15:45:36 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 26 15:45:36 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 26 15:45:37 volumio volumio[1129]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.061&uuid=ffef1f0bb94930fcd4d9448e61404e5a" http://updates.volumio.org/downloader-v1/track-device Oct 26 15:45:37 volumio volumio[1129]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 26 15:45:37 volumio volumio[1129]: Dload Upload Total Spent Left Speed Oct 26 15:45:37 volumio volumio[1129]: [132B blob data] Oct 26 15:45:37 volumio volumio[1129]: retrying in 5 seconds, trial 0 Oct 26 15:45:37 volumio volumio[1129]: info: Volumio Calling Home Oct 26 15:45:38 volumio volumio[1129]: info: Discovery: adding 70853f74-8210-4683-a3fa-e1217517bd40 Oct 26 15:45:38 volumio volumio[1129]: info: Discovery: Found device Volumio Oct 26 15:45:38 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 26 15:45:38 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 26 15:45:39 volumio volumio[1129]: info: Discovery: this is already registered, 70853f74-8210-4683-a3fa-e1217517bd40 Oct 26 15:45:39 volumio volumio[1129]: info: Discovery: Found device Volumio Oct 26 15:45:39 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 26 15:45:39 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 26 15:45:39 volumio go-librespot[1602]: time="2025-10-26T15:45:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 15:45:39 volumio go-librespot[1602]: time="2025-10-26T15:45:39Z" level=debug msg="completed keyexchange" Oct 26 15:45:39 volumio go-librespot[1602]: time="2025-10-26T15:45:39Z" level=debug msg="completed challenge" Oct 26 15:45:39 volumio go-librespot[1602]: time="2025-10-26T15:45:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 15:45:39 volumio volumio[1129]: info: Connection to go-librespot Websocket closed Oct 26 15:45:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 15:45:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 15:45:39 volumio volumio[1129]: info: Volumio called home Oct 26 15:45:39 volumio volumio[1129]: info: Getting Spotify volume Oct 26 15:45:39 volumio volumio[1129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:45:39 volumio volumio[1129]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 15:45:39 volumio volumio[1129]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 26 15:45:39 volumio volumio[1129]: errno: -111, Oct 26 15:45:39 volumio volumio[1129]: code: 'ECONNREFUSED', Oct 26 15:45:39 volumio volumio[1129]: syscall: 'connect', Oct 26 15:45:39 volumio volumio[1129]: address: '127.0.0.1', Oct 26 15:45:39 volumio volumio[1129]: port: 9879, Oct 26 15:45:39 volumio volumio[1129]: response: undefined Oct 26 15:45:39 volumio volumio[1129]: } Oct 26 15:45:39 volumio volumio[1129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:45:39 volumio sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-26 15:44' Oct 26 15:45:39 volumio sudo[1641]: 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="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"