Sep 12 13:23:46 volumio ntpd[1173]: CLOCK: time stepped by 337708.989259 Sep 12 13:23:46 volumio ntpd[1173]: CLOCK: time changed from 2025-09-08 to 2025-09-12 Sep 12 13:23:46 volumio ntpd[1173]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Sep 12 13:23:46 volumio volumio[1236]: info: Discovery: A device disappeared from network Sep 12 13:23:46 volumio volumio[1236]: info: Discovery: Device volumio disappeared from network Sep 12 13:23:46 volumio volumio[1236]: info: Discovery: A device disappeared from network Sep 12 13:23:47 volumio volumio[1236]: info: Discovery: adding 9b1d02b1-dd9b-460d-bafa-231f3718700b Sep 12 13:23:47 volumio volumio[1236]: info: Discovery: Found device Volumio Sep 12 13:23:47 volumio volumio[1236]: info: CoreCommandRouter::volumioGetState Sep 12 13:23:47 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 Sep 12 13:23:47 volumio volumio[1236]: info: Discovery: this is already registered, 9b1d02b1-dd9b-460d-bafa-231f3718700b Sep 12 13:23:47 volumio volumio[1236]: info: Discovery: Found device Volumio Sep 12 13:23:47 volumio volumio[1236]: info: CoreCommandRouter::volumioGetState Sep 12 13:23:47 volumio volumio[1236]: info: CorePlayQueue::getTrack 0 Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 12 13:23:47 volumio volumio[1236]: info: Adding plugin bluetooth to MyMusic Plugins Sep 12 13:23:47 volumio volumio[1236]: info: Adding plugin multiroom to MyMusic Plugins Sep 12 13:23:47 volumio volumio[1236]: info: Adding plugin metavolumio to MyMusic Plugins Sep 12 13:23:47 volumio volumio[1236]: info: Adding plugin cd_controller to MyMusic Plugins Sep 12 13:23:47 volumio volumio[1236]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 12 13:23:47 volumio volumio[1236]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 12 13:23:47 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:23:47 volumio volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 12 13:23:47 volumio volumio[1236]: info: Starting MyVolumio Remote Streaming Endpoints Sep 12 13:23:47 volumio volumio[1236]: info: MyVolumio login type: Token Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 12 13:23:47 volumio volumio[1236]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 12 13:23:48 volumio volumio[1236]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 12 13:23:48 volumio volumio[1236]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 12 13:23:48 volumio volumio[1236]: info: Streaming services startup Sep 12 13:23:48 volumio volumio[1236]: info: Starting Streaming Daemon Sep 12 13:23:48 volumio sudo[1886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 12 13:23:48 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 12 13:23:48 volumio volumio[1236]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 12 13:23:48 volumio volumio[1236]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.005&uuid=0c1c5cf10cb246e0a75d136f833d1410" http://updates.volumio.org/downloader-v1/track-device Sep 12 13:23:48 volumio volumio[1236]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 13:23:48 volumio volumio[1236]: Dload Upload Total Spent Left Speed Sep 12 13:23:48 volumio volumio[1236]: [132B blob data] Sep 12 13:23:48 volumio volumio[1236]: retrying in 5 seconds, trial 0 Sep 12 13:23:48 volumio volumio[1236]: info: Volumio Calling Home Sep 12 13:23:48 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Sep 12 13:23:48 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Sep 12 13:23:48 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Sep 12 13:23:48 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Sep 12 13:23:48 volumio volumio[1236]: error: Cannot start Volumio Streaming Daemon Sep 12 13:23:48 volumio volumio[1236]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 12 13:23:48 volumio volumio[1236]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 12 13:23:48 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Sep 12 13:23:48 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Sep 12 13:23:48 volumio volumio[1236]: error: MyVolumio Custom Token format not valid, refreshing it Sep 12 13:23:48 volumio volumio[1236]: info: Volumio called home Sep 12 13:23:49 volumio volumio[1236]: info: MyVolumio login type: Token Sep 12 13:23:50 volumio volumio[1236]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 12 13:23:50 volumio volumio[1236]: info: MyVolumio token set successfully Sep 12 13:23:50 volumio volumio[1236]: info: MYVOLUMIO: Adding device Sep 12 13:23:50 volumio volumio[1236]: info: MYVOLUMIO: Evaluating Server Sep 12 13:23:50 volumio volumio[1236]: info: MyVolumio status changed Sep 12 13:23:50 volumio volumio[1236]: info: Streaming services startup Sep 12 13:23:50 volumio volumio[1236]: info: Starting Streaming Daemon Sep 12 13:23:50 volumio volumio[1236]: info: Removing browser output: myVolumio user plan is not superstar Sep 12 13:23:50 volumio volumio[1236]: info: Removing audio output: Sep 12 13:23:50 volumio volumio[1236]: info: Stoppping Tunnel 1 Sep 12 13:23:50 volumio sudo[1924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 12 13:23:50 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 12 13:23:50 volumio sudo[1928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 12 13:23:50 volumio sudo[1928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 12 13:23:50 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 12 13:23:50 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Sep 12 13:23:50 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 12 13:23:50 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 12 13:23:50 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 12 13:23:50 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 12 13:23:50 volumio setdatetime-helper.sh[1926]: Time is not synchronized. Attempting to sync... Sep 12 13:23:50 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 12 13:23:50 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 12 13:23:50 volumio volumio[1236]: error: Cannot start Volumio Streaming Daemon Sep 12 13:23:50 volumio volumio[1236]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 12 13:23:50 volumio volumio[1236]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 12 13:23:50 volumio sudo[1928]: pam_unix(sudo:session): session closed for user root Sep 12 13:23:50 volumio volumio[1236]: info: Remote SSH Stopped Sep 12 13:23:50 volumio sudo[1937]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 12 Sep 2025 04:23:50 GMT#015' Sep 12 13:23:50 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 12 13:23:50 volumio setdatetime-helper.sh[1938]: Fri Sep 12 13:23:50 JST 2025 Sep 12 13:23:50 volumio systemd-journald[435]: Time jumped backwards, rotating. Sep 12 13:23:50 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root Sep 12 13:23:50 volumio setdatetime-helper.sh[1926]: Time synchronized successfully. Sep 12 13:23:50 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 12 13:23:50 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 12 13:23:50 volumio volumio[1236]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Sep 12 13:23:50 volumio volumio[1236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 13:23:50 volumio volumio[1236]: Error: Unable to resolve or reject the same promise twice Sep 12 13:23:50 volumio volumio[1236]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 12 13:23:50 volumio volumio[1236]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Sep 12 13:23:50 volumio volumio[1236]: at Socket.emit (node:events:514:28) Sep 12 13:23:50 volumio volumio[1236]: at addChunk (node:internal/streams/readable:343:12) Sep 12 13:23:50 volumio volumio[1236]: at readableAddChunk (node:internal/streams/readable:316:9) Sep 12 13:23:50 volumio volumio[1236]: at Readable.push (node:internal/streams/readable:253:10) Sep 12 13:23:50 volumio volumio[1236]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Sep 12 13:23:50 volumio volumio[1236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 12 13:23:50 volumio sudo[1954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-12 13:22' Sep 12 13:23:50 volumio sudo[1954]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" 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 May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"