-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2025-04-19 09:09:27 CEST. -- Apr 19 09:09:24 volumio ntpd[786]: receive: Unexpected origin timestamp 0xebaa9614.50ac336a does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebadcaa4.7df261b6 Apr 19 09:09:24 volumio ntpd[786]: receive: Unexpected origin timestamp 0xebaa9614.5090c688 does not match aorg 0000000000.00000000 from server@5.196.160.139 xmt 0xebadcaa4.7e6f9ffa Apr 19 09:09:24 volumio ntpd[786]: receive: Unexpected origin timestamp 0xebaa9614.50a5f6af does not match aorg 0000000000.00000000 from server@51.255.95.80 xmt 0xebadcaa4.7e83f0ef Apr 19 09:09:24 volumio ntpd[786]: receive: Unexpected origin timestamp 0xebaa9614.509f394a does not match aorg 0000000000.00000000 from server@51.68.44.27 xmt 0xebadcaa4.7e927090 Apr 19 09:09:24 volumio ntpd[786]: receive: Unexpected origin timestamp 0xebaa9614.50a2c79f does not match aorg 0000000000.00000000 from server@82.64.247.11 xmt 0xebadcaa4.7e74ee47 Apr 19 09:09:24 volumio go-librespot[1532]: time="2025-04-19T09:09:24+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 19 09:09:24 volumio go-librespot[1532]: time="2025-04-19T09:09:24+02:00" level=trace msg="emitting websocket event: volume" Apr 19 09:09:24 volumio systemd[1]: Starting Daily apt download activities... Apr 19 09:09:24 volumio volumio[1070]: SPOTIFY: received: {"type":"volume","data":{"value":49,"max":100}} Apr 19 09:09:24 volumio volumio[1070]: SPOTIFY: RECEIVED SPOTIFY VOLUME 49 Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: A device disappeared from network Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: Device volumio disappeared from network Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: A device disappeared from network Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: adding 3b03c0a7-396e-42cb-9aa4-4875d9b5fc68 Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: Found device Volumio Apr 19 09:09:24 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Apr 19 09:09:24 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: this is already registered, 3b03c0a7-396e-42cb-9aa4-4875d9b5fc68 Apr 19 09:09:24 volumio volumio[1070]: info: Discovery: Found device Volumio Apr 19 09:09:24 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Apr 19 09:09:24 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Apr 19 09:09:25 volumio volumio[1070]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 19 09:09:25 volumio volumio[1070]: STREAMING PROXY: Starting server on port 3245 Apr 19 09:09:25 volumio volumio[1070]: Node JS runtime: 14 Apr 19 09:09:25 volumio volumio[1070]: info: MyVolumio token set successfully Apr 19 09:09:25 volumio volumio[1070]: info: MYVOLUMIO: Adding device Apr 19 09:09:25 volumio volumio[1070]: info: MYVOLUMIO: Evaluating Server Apr 19 09:09:26 volumio volumio[1070]: info: MyVolumio Plan changed: premium Apr 19 09:09:26 volumio volumio[1070]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 19 09:09:26 volumio volumio[1070]: info: Removing browser output: myVolumio user plan is not superstar Apr 19 09:09:26 volumio volumio[1070]: info: Removing audio output: Apr 19 09:09:26 volumio volumio[1070]: info: MYVOLUMIO: Adding device Apr 19 09:09:26 volumio volumio[1070]: info: MYVOLUMIO: Evaluating Server Apr 19 09:09:26 volumio volumio[1070]: info: Remote config written successfully Apr 19 09:09:26 volumio volumio[1070]: info: Starting Tunnel 1 Apr 19 09:09:26 volumio volumio[1070]: info: Starting Tunnel Connection Checker Apr 19 09:09:26 volumio volumio[1070]: info: Completed starting MyVolumio Plugin Apr 19 09:09:26 volumio volumio-time-update[636]: volumio-time-update-util: Setting system time to: 2025-04-19 09:08:43 Apr 19 09:09:26 volumio sudo[1712]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-19 09:08:43 Apr 19 09:09:26 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 09:09:26 volumio dbus-daemon[643]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=1714 comm="timedatectl set-time 2025-04-19 09:08:43 ") Apr 19 09:09:27 volumio systemd[1]: Starting Time & Date Service... Apr 19 09:09:27 volumio dbus-daemon[643]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 19 09:09:27 volumio systemd[1]: Started Time & Date Service. Apr 19 09:09:27 volumio volumio[1070]: info: MYVolumio Device enabled Apr 19 09:09:27 volumio volumio[1070]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 19 09:09:27 volumio volumio[1070]: info: MyVolumio status changed Apr 19 09:09:27 volumio volumio[1070]: info: Streaming services startup Apr 19 09:09:27 volumio volumio[1070]: info: Starting Streaming Daemon Apr 19 09:08:43 volumio systemd-timedated[1717]: Changed local time to Sat Apr 19 09:08:43 2025 Apr 19 09:08:43 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root Apr 19 09:08:43 volumio volumio-time-update[636]: volumio-time-update-util: System time updated successfully. Apr 19 09:08:43 volumio systemd[1]: Started Volumio Time Update Utility. Apr 19 09:08:43 volumio systemd[1]: Reached target Multi-User System. Apr 19 09:08:43 volumio systemd[1]: Reached target Graphical Interface. Apr 19 09:08:43 volumio volumio[1070]: info: Setting Geolocation for MyVolumio to eu4 Apr 19 09:08:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 09:08:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 09:08:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 09:08:43 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 19 09:08:43 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 09:08:43 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 09:08:43 volumio volumio[1070]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Apr 19 09:08:43 volumio volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 09:08:43 volumio volumio[1070]: Error: Unable to resolve or reject the same promise twice Apr 19 09:08:43 volumio volumio[1070]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 19 09:08:43 volumio volumio[1070]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Apr 19 09:08:43 volumio volumio[1070]: at Socket.emit (events.js:327:22) Apr 19 09:08:43 volumio volumio[1070]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 19 09:08:43 volumio volumio[1070]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 19 09:08:43 volumio volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 09:08:43 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Apr 19 09:08:43 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 19 09:08:43 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 19 09:08:44 volumio sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 09:07 Apr 19 09:08:44 volumio sudo[1741]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"