Jul 05 13:49:09 volumio4 ntpd[1106]: CLOCK: time stepped by 196136.385810 Jul 05 13:49:09 volumio4 ntpd[1106]: CLOCK: time changed from 2025-07-03 to 2025-07-05 Jul 05 13:49:09 volumio4 ntpd[1106]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 05 13:49:09 volumio4 volumio[1210]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: A device disappeared from network Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: Device volumio4 disappeared from network Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: A device disappeared from network Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: adding 780789ea-ad8e-4918-a408-20cc5178153d Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: Found device Volumio4 Jul 05 13:49:09 volumio4 volumio[1210]: info: CoreCommandRouter::volumioGetState Jul 05 13:49:09 volumio4 volumio[1210]: info: CorePlayQueue::getTrack 0 Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: this is already registered, 780789ea-ad8e-4918-a408-20cc5178153d Jul 05 13:49:09 volumio4 volumio[1210]: info: Discovery: Found device Volumio4 Jul 05 13:49:09 volumio4 volumio[1210]: info: CoreCommandRouter::volumioGetState Jul 05 13:49:09 volumio4 volumio[1210]: info: CorePlayQueue::getTrack 0 Jul 05 13:49:09 volumio4 volumio[1210]: info: MyVolumio token set successfully Jul 05 13:49:09 volumio4 volumio[1210]: info: MYVOLUMIO: Adding device Jul 05 13:49:09 volumio4 volumio[1210]: info: MYVOLUMIO: Evaluating Server Jul 05 13:49:10 volumio4 volumio[1210]: info: MyVolumio Plan changed: superstar Jul 05 13:49:10 volumio4 volumio[1210]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jul 05 13:49:10 volumio4 volumio[1210]: info: Adding audio output: Jul 05 13:49:10 volumio4 volumio[1210]: info: MYVOLUMIO: Adding device Jul 05 13:49:10 volumio4 volumio[1210]: info: MYVOLUMIO: Evaluating Server Jul 05 13:49:10 volumio4 volumio[1210]: info: Remote config written successfully Jul 05 13:49:10 volumio4 volumio[1210]: info: Starting Tunnel 1 Jul 05 13:49:10 volumio4 volumio[1210]: info: Starting Tunnel Connection Checker Jul 05 13:49:10 volumio4 volumio[1210]: info: Completed starting MyVolumio Plugin Jul 05 13:49:10 volumio4 volumio[1210]: info: MYVolumio Device enabled Jul 05 13:49:10 volumio4 volumio[1210]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 05 13:49:10 volumio4 volumio[1210]: info: MyVolumio status changed Jul 05 13:49:10 volumio4 volumio[1210]: info: Streaming services startup Jul 05 13:49:10 volumio4 volumio[1210]: info: Starting Streaming Daemon Jul 05 13:49:10 volumio4 sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 05 13:49:10 volumio4 sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 05 13:49:10 volumio4 volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 05 13:49:10 volumio4 volumio[1210]: error: [MyVolumio PluginManager] Cache data is invalid! Jul 05 13:49:10 volumio4 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jul 05 13:49:10 volumio4 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jul 05 13:49:10 volumio4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 05 13:49:10 volumio4 sudo[1832]: pam_unix(sudo:session): session closed for user root Jul 05 13:49:10 volumio4 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jul 05 13:49:10 volumio4 volumio[1210]: error: Cannot start Volumio Streaming Daemon Jul 05 13:49:10 volumio4 volumio[1210]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 05 13:49:10 volumio4 volumio[1210]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 05 13:49:10 volumio4 setdatetime-helper.sh[1838]: Time is not synchronized. Attempting to sync... Jul 05 13:49:10 volumio4 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jul 05 13:49:10 volumio4 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jul 05 13:49:10 volumio4 volumio[1210]: info: Setting Geolocation for MyVolumio to eu2 Jul 05 13:49:10 volumio4 volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 13:49:10 volumio4 volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 13:49:10 volumio4 volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 13:49:10 volumio4 volumio[1210]: info: Cleared local playlist file: /data/playlist/undefined Jul 05 13:49:10 volumio4 sudo[1853]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 05 Jul 2025 11:49:10 GMT#015' Jul 05 13:49:10 volumio4 sudo[1853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 05 13:49:10 volumio4 setdatetime-helper.sh[1854]: Sat Jul 5 13:49:10 CEST 2025 Jul 05 13:49:10 volumio4 sudo[1853]: pam_unix(sudo:session): session closed for user root Jul 05 13:49:10 volumio4 setdatetime-helper.sh[1838]: Time synchronized successfully. Jul 05 13:49:10 volumio4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 05 13:49:10 volumio4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 05 13:49:10 volumio4 systemd-journald[428]: Time jumped backwards, rotating. Jul 05 13:49:10 volumio4 volumio[1210]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Jul 05 13:49:10 volumio4 volumio[1210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 13:49:10 volumio4 volumio[1210]: Error: Unable to resolve or reject the same promise twice Jul 05 13:49:10 volumio4 volumio[1210]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 05 13:49:10 volumio4 volumio[1210]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jul 05 13:49:10 volumio4 volumio[1210]: at Socket.emit (node:events:514:28) Jul 05 13:49:10 volumio4 volumio[1210]: at addChunk (node:internal/streams/readable:343:12) Jul 05 13:49:10 volumio4 volumio[1210]: at readableAddChunk (node:internal/streams/readable:316:9) Jul 05 13:49:10 volumio4 volumio[1210]: at Readable.push (node:internal/streams/readable:253:10) Jul 05 13:49:10 volumio4 volumio[1210]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jul 05 13:49:10 volumio4 volumio[1210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 13:49:10 volumio4 sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-05 13:48' Jul 05 13:49:10 volumio4 sudo[1870]: 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="f9454895cc8e2f1971c705a815a7226e021e37d7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 28 16:48:46 UTC 2025" VOLUMIO_VERSION="4.014" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1b557e4869cd2eea14f4113140ecea1c"