May 24 07:34:19 volumiobw ntpd[1075]: CLOCK: time stepped by 81313.438341 May 24 07:34:19 volumiobw ntpd[1075]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 24 07:34:19 volumiobw systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 24 07:34:19 volumiobw systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 24 07:34:19 volumiobw systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 24 07:34:19 volumiobw systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 24 07:34:19 volumiobw systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 24 07:34:19 volumiobw volumio[1278]: info: MyVolumio login type: Token May 24 07:34:19 volumiobw volumio[1278]: SPOTIFY: SETTING SPOTIFY VOLUME 71 May 24 07:34:19 volumiobw volumio[1278]: info: Sending Spotify command with payload to local API: /player/volume May 24 07:34:19 volumiobw volumio[1278]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request May 24 07:34:19 volumiobw volumio[1278]: info: Discovery: A device disappeared from network May 24 07:34:19 volumiobw volumio[1278]: info: Discovery: Device volumiobw disappeared from network May 24 07:34:20 volumiobw volumio[1278]: info: Discovery: A device disappeared from network May 24 07:34:20 volumiobw volumio[1278]: info: Discovery: A device disappeared from network May 24 07:34:20 volumiobw volumio[1278]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 24 07:34:20 volumiobw volumio[1278]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 May 24 07:34:20 volumiobw volumio[1278]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 May 24 07:34:20 volumiobw volumio[1278]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 May 24 07:34:20 volumiobw volumio[1278]: info: MyVolumio token set successfully May 24 07:34:20 volumiobw volumio[1278]: info: MYVOLUMIO: Adding device May 24 07:34:20 volumiobw volumio[1278]: info: MYVOLUMIO: Evaluating Server May 24 07:34:21 volumiobw volumio[1278]: info: MyVolumio Plan changed: premium May 24 07:34:21 volumiobw volumio[1278]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 24 07:34:21 volumiobw volumio[1278]: info: Removing browser output: myVolumio user plan is not superstar May 24 07:34:21 volumiobw volumio[1278]: info: Removing audio output: May 24 07:34:21 volumiobw volumio[1278]: info: MYVOLUMIO: Adding device May 24 07:34:21 volumiobw volumio[1278]: info: MYVOLUMIO: Evaluating Server May 24 07:34:21 volumiobw volumio[1278]: info: Remote config written successfully May 24 07:34:21 volumiobw volumio[1278]: info: Starting Tunnel 1 May 24 07:34:21 volumiobw volumio[1278]: info: Starting Tunnel Connection Checker May 24 07:34:21 volumiobw volumio[1278]: info: Completed starting MyVolumio Plugin May 24 07:34:21 volumiobw volumio[1278]: info: MYVolumio Device enabled May 24 07:34:21 volumiobw volumio[1278]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 24 07:34:21 volumiobw volumio[1278]: info: MyVolumio status changed May 24 07:34:21 volumiobw volumio[1278]: info: Streaming services startup May 24 07:34:21 volumiobw volumio[1278]: info: Starting Streaming Daemon May 24 07:34:21 volumiobw sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 24 07:34:21 volumiobw sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 07:34:21 volumiobw volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 24 07:34:21 volumiobw volumio[1278]: error: [MyVolumio PluginManager] Cache data is invalid! May 24 07:34:21 volumiobw volumio[1278]: info: Setting Geolocation for MyVolumio to as1 May 24 07:34:21 volumiobw volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 07:34:21 volumiobw volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 07:34:21 volumiobw volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 07:34:21 volumiobw systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 24 07:34:21 volumiobw sudo[2106]: pam_unix(sudo:session): session closed for user root May 24 07:34:21 volumiobw volumio[1278]: error: Cannot start Volumio Streaming Daemon May 24 07:34:21 volumiobw volumio[1278]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 24 07:34:21 volumiobw volumio[1278]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 24 07:34:21 volumiobw setdatetime-helper.sh[2109]: Time is not synchronized. Attempting to sync... May 24 07:34:21 volumiobw sudo[2117]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 24 May 2025 00:34:21 GMT#015' May 24 07:34:21 volumiobw sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 24 07:34:21 volumiobw setdatetime-helper.sh[2118]: Sat May 24 07:34:21 +07 2025 May 24 07:34:21 volumiobw sudo[2117]: pam_unix(sudo:session): session closed for user root May 24 07:34:21 volumiobw setdatetime-helper.sh[2109]: Time synchronized successfully. May 24 07:34:21 volumiobw systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 24 07:34:21 volumiobw systemd-journald[417]: Time jumped backwards, rotating. May 24 07:34:21 volumiobw systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 24 07:34:21 volumiobw volumio[1278]: error: Failed to ping endpoint au1.myvolumio.org : unknown error May 24 07:34:21 volumiobw volumio[1278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 07:34:21 volumiobw volumio[1278]: Error: Unable to resolve or reject the same promise twice May 24 07:34:21 volumiobw volumio[1278]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 24 07:34:21 volumiobw volumio[1278]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) May 24 07:34:21 volumiobw volumio[1278]: at Socket.emit (node:events:514:28) May 24 07:34:21 volumiobw volumio[1278]: at addChunk (node:internal/streams/readable:343:12) May 24 07:34:21 volumiobw volumio[1278]: at readableAddChunk (node:internal/streams/readable:316:9) May 24 07:34:21 volumiobw volumio[1278]: at Readable.push (node:internal/streams/readable:253:10) May 24 07:34:21 volumiobw volumio[1278]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 24 07:34:21 volumiobw volumio[1278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 07:34:21 volumiobw sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-24 07:33' May 24 07:34:21 volumiobw sudo[2134]: 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="26d819930af572ef184e636a18ef1127de78f391" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"