-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Tue 2025-06-24 15:25:35 CDT. -- Jun 24 15:25:31 volumio systemd[1]: Starting Daily apt download activities... Jun 24 15:25:31 volumio go-librespot[1573]: time="2025-06-24T15:25:31-05:00" level=debug msg="completed challenge" Jun 24 15:25:31 volumio volumio[994]: info: MyVolumio login type: Token Jun 24 15:25:31 volumio go-librespot[1573]: time="2025-06-24T15:25:31-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jun 24 15:25:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 24 15:25:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 24 15:25:31 volumio volumio[994]: SPOTIFY: SETTING SPOTIFY VOLUME 53 Jun 24 15:25:31 volumio volumio[994]: info: Sending Spotify command with payload to local API: /player/volume Jun 24 15:25:31 volumio volumio[994]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 24 15:25:33 volumio systemd[1]: apt-daily.service: Succeeded. Jun 24 15:25:33 volumio systemd[1]: Started Daily apt download activities. Jun 24 15:25:33 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 24 15:25:33 volumio volumio[994]: info: Initializing connection to go-librespot Websocket Jun 24 15:25:33 volumio volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 24 15:25:34 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 24 15:25:34 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 24 15:25:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 24 15:25:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jun 24 15:25:34 volumio systemd[1]: Stopped go-librespot Daemon. Jun 24 15:25:34 volumio systemd[1]: Started go-librespot Daemon. Jun 24 15:25:34 volumio go-librespot[1696]: Librespot-go daemon starting... Jun 24 15:25:34 volumio go-librespot[1696]: time="2025-06-24T15:25:34-05:00" level=info msg="generated new device id: 812ff8a6859e32c0b711bdee0dce612aaa23ad07" Jun 24 15:25:34 volumio go-librespot[1696]: time="2025-06-24T15:25:34-05:00" level=debug msg="stored credentials found for ryanprimeaux" Jun 24 15:25:34 volumio volumio[994]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 24 15:25:35 volumio volumio[994]: info: Discovery: A device disappeared from network Jun 24 15:25:35 volumio volumio[994]: info: Discovery: Device volumio disappeared from network Jun 24 15:25:35 volumio go-librespot[1696]: time="2025-06-24T15:25:35-05:00" level=debug msg="obtained new client token: AAApK+bbSEb3B53Nq9yF0ECd34dnL/TYXrmRAHSHlNl5Mx8svNKTP3d4zFq7U+pLIUEunlWpliCpn5f7QV+gzcy51t8O+2IQGmFc63h2RkRmrcP4+0SVlxMts6ZNYulJZbZJi4q1Ej99oGD9fUuH37Eqjuoz5TY6/0SpQrGfjarRjkavQWJa7RcmCUDqmhYImsxIaABfKd8ab41Vne/BjXyyXNXM+PdcfqWGeNjzZ/t4CHcu766P4/FPINgW" Jun 24 15:25:35 volumio volumio[994]: info: MyVolumio token set successfully Jun 24 15:25:35 volumio volumio[994]: info: MYVOLUMIO: Adding device Jun 24 15:25:35 volumio volumio[994]: info: MYVOLUMIO: Evaluating Server Jun 24 15:25:35 volumio volumio-time-update[605]: volumio-time-update-util: Setting system time to: 2025-06-24 15:25:35 Jun 24 15:25:35 volumio sudo[1712]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-24 15:25:35 Jun 24 15:25:35 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 15:25:35 volumio go-librespot[1696]: time="2025-06-24T15:25:35-05:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 24 15:25:35 volumio dbus-daemon[606]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=1714 comm="timedatectl set-time 2025-06-24 15:25:35 ") Jun 24 15:25:35 volumio systemd[1]: Starting Time & Date Service... Jun 24 15:25:35 volumio go-librespot[1696]: time="2025-06-24T15:25:35-05:00" level=debug msg="completed keyexchange" Jun 24 15:25:35 volumio volumio[994]: info: MyVolumio status changed Jun 24 15:25:35 volumio volumio[994]: info: Streaming services startup Jun 24 15:25:35 volumio volumio[994]: info: Starting Streaming Daemon Jun 24 15:25:35 volumio dbus-daemon[606]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 24 15:25:35 volumio systemd[1]: Started Time & Date Service. Jun 24 15:25:35 volumio systemd-timedated[1717]: Changed local time to Tue Jun 24 15:25:35 2025 Jun 24 15:25:35 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root Jun 24 15:25:35 volumio volumio-time-update[605]: volumio-time-update-util: System time updated successfully. Jun 24 15:25:35 volumio volumio[994]: info: Removing browser output: myVolumio user plan is not superstar Jun 24 15:25:35 volumio systemd[1]: Started Volumio Time Update Utility. Jun 24 15:25:35 volumio volumio[994]: info: Removing audio output: Jun 24 15:25:35 volumio volumio[994]: info: Stoppping Tunnel 1 Jun 24 15:25:35 volumio systemd[1]: Reached target Multi-User System. Jun 24 15:25:35 volumio systemd[1]: Reached target Graphical Interface. Jun 24 15:25:35 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 24 15:25:35 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 15:25:35 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 24 15:25:35 volumio volumio[994]: error: Failed to ping endpoint us3.myvolumio.org : unknown error Jun 24 15:25:35 volumio volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 15:25:35 volumio volumio[994]: Error: Unable to resolve or reject the same promise twice Jun 24 15:25:35 volumio volumio[994]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 24 15:25:35 volumio volumio[994]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jun 24 15:25:35 volumio volumio[994]: at Socket.emit (events.js:327:22) Jun 24 15:25:35 volumio volumio[994]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 24 15:25:35 volumio volumio[994]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 24 15:25:35 volumio volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 15:25:35 volumio sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 24 15:25:35 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 15:25:35 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Jun 24 15:25:35 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root Jun 24 15:25:35 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 24 15:25:35 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 24 15:25:35 volumio systemd[1]: Startup finished in 11.712s (kernel) + 1min 27.769s (userspace) = 1min 39.482s. Jun 24 15:25:35 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 15:24 Jun 24 15:25:35 volumio sudo[1744]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"