Jun 17 15:29:41 volumio1920x480 ntpd[1015]: CLOCK: time stepped by 281108.383909 Jun 17 15:29:41 volumio1920x480 ntpd[1015]: CLOCK: time changed from 2025-06-14 to 2025-06-17 Jun 17 15:29:41 volumio1920x480 ntpd[1015]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Streaming services startup Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Starting Streaming Daemon Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.012&uuid=36a8b578a97570273d1c372dffcabb2e" http://updates.volumio.org/downloader-v1/track-device Jun 17 15:29:41 volumio1920x480 volumio[1208]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 17 15:29:41 volumio1920x480 volumio[1208]: Dload Upload Total Spent Left Speed Jun 17 15:29:41 volumio1920x480 volumio[1208]: [132B blob data] Jun 17 15:29:41 volumio1920x480 volumio[1208]: retrying in 5 seconds, trial 0 Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Volumio Calling Home Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: A device disappeared from network Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: A device disappeared from network Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: A device disappeared from network Jun 17 15:29:41 volumio1920x480 sudo[1645]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:41 volumio1920x480 sudo[1645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 15:29:41 volumio1920x480 sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: adding 50342ea3-a597-4fa8-921c-81ec365516be Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: Found device Volumio_1920x480 Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: CorePlayQueue::getTrack 0 Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: this is already registered, 50342ea3-a597-4fa8-921c-81ec365516be Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: Found device Volumio_1920x480 Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: CorePlayQueue::getTrack 0 Jun 17 15:29:41 volumio1920x480 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jun 17 15:29:41 volumio1920x480 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jun 17 15:29:41 volumio1920x480 sudo[1645]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:41 volumio1920x480 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jun 17 15:29:41 volumio1920x480 volumio[1208]: error: Cannot start Volumio Streaming Daemon Jun 17 15:29:41 volumio1920x480 volumio[1208]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 17 15:29:41 volumio1920x480 volumio[1208]: sudo: unable to resolve host volumio1920x480: System error Jun 17 15:29:41 volumio1920x480 volumio[1208]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 17 15:29:41 volumio1920x480 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jun 17 15:29:41 volumio1920x480 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: this is already registered, 50342ea3-a597-4fa8-921c-81ec365516be Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Discovery: Found device Volumio_1920x480 Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: CoreCommandRouter::volumioGetState Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: CorePlayQueue::getTrack 0 Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: Volumio called home Jun 17 15:29:41 volumio1920x480 volumio[1208]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 17 15:29:42 volumio1920x480 ntpd[1015]: IO: Listen normally on 5 wlan0 169.254.201.160:123 Jun 17 15:29:42 volumio1920x480 ntpd[1015]: IO: new interface(s) found: waking up resolver Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MyVolumio token set successfully Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MYVOLUMIO: Adding device Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MYVOLUMIO: Evaluating Server Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MyVolumio Plan changed: premium Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Removing browser output: myVolumio user plan is not superstar Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Removing audio output: Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MYVOLUMIO: Adding device Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MYVOLUMIO: Evaluating Server Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Remote config written successfully Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Starting Tunnel 1 Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Starting Tunnel Connection Checker Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Completed starting MyVolumio Plugin Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MYVolumio Device enabled Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MyVolumio status changed Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Streaming services startup Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Starting Streaming Daemon Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 17 15:29:42 volumio1920x480 sudo[1701]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 sudo[1701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 15:29:42 volumio1920x480 sudo[1701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:42 volumio1920x480 volumio[1208]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 17 15:29:42 volumio1920x480 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 17 15:29:42 volumio1920x480 sudo[1701]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 setdatetime-helper.sh[1705]: Time is not synchronized. Attempting to sync... Jun 17 15:29:43 volumio1920x480 sudo[1713]: root : unable to resolve host volumio1920x480: System error Jun 17 15:29:43 volumio1920x480 setdatetime-helper.sh[1713]: sudo: unable to resolve host volumio1920x480: System error Jun 17 15:29:43 volumio1920x480 sudo[1713]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 17 Jun 2025 13:29:42 GMT#015' Jun 17 15:29:43 volumio1920x480 sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 17 15:29:42 volumio1920x480 setdatetime-helper.sh[1714]: Tue Jun 17 15:29:42 CEST 2025 Jun 17 15:29:42 volumio1920x480 sudo[1713]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 systemd-journald[424]: Time jumped backwards, rotating. Jun 17 15:29:42 volumio1920x480 setdatetime-helper.sh[1705]: Time synchronized successfully. Jun 17 15:29:42 volumio1920x480 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 17 15:29:42 volumio1920x480 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 17 15:29:42 volumio1920x480 sudo[1718]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 sudo[1718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 17 15:29:42 volumio1920x480 sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:42 volumio1920x480 sudo[1718]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 volumio[1208]: sudo: unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MRS: MultiRoom plugin initialized Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MRS: STOPPING SNAPCLIENT Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MRS: Snap server stop Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: MRS: STOPPING volumioStreaming Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 17 15:29:42 volumio1920x480 sudo[1736]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 17 15:29:42 volumio1920x480 sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:42 volumio1920x480 sudo[1738]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 17 15:29:42 volumio1920x480 sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 17 15:29:42 volumio1920x480 sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:42 volumio1920x480 sudo[1740]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 17 15:29:42 volumio1920x480 sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 17 15:29:42 volumio1920x480 sudo[1743]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 sudo[1743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 17 15:29:42 volumio1920x480 sudo[1736]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 17 15:29:42 volumio1920x480 sudo[1743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 17 15:29:42 volumio1920x480 sudo[1743]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Preparing to generate the ALSA configuration file Jun 17 15:29:42 volumio1920x480 sudo[1738]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Reading ALSA contributions from plugins. Jun 17 15:29:42 volumio1920x480 sudo[1740]: pam_unix(sudo:session): session closed for user root Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: Setting Geolocation for MyVolumio to eu2 Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:29:42 volumio1920x480 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 15:29:42 volumio1920x480 volumio[1208]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Jun 17 15:29:42 volumio1920x480 volumio[1208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:29:42 volumio1920x480 volumio[1208]: Error: Unable to resolve or reject the same promise twice Jun 17 15:29:42 volumio1920x480 volumio[1208]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 17 15:29:42 volumio1920x480 volumio[1208]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 17 15:29:42 volumio1920x480 volumio[1208]: at Socket.emit (node:events:514:28) Jun 17 15:29:42 volumio1920x480 volumio[1208]: at addChunk (node:internal/streams/readable:343:12) Jun 17 15:29:42 volumio1920x480 volumio[1208]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 17 15:29:42 volumio1920x480 volumio[1208]: at Readable.push (node:internal/streams/readable:253:10) Jun 17 15:29:42 volumio1920x480 volumio[1208]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 17 15:29:42 volumio1920x480 volumio[1208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 15:29:42 volumio1920x480 sudo[1762]: volumio : unable to resolve host volumio1920x480: System error Jun 17 15:29:42 volumio1920x480 sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-17 15:28' Jun 17 15:29:42 volumio1920x480 sudo[1762]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"