-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Fri 2026-10-16 17:25:29 GMT. -- Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a08d9006 does not match aorg 0000000000.00000000 from server@172.234.25.10 xmt 0xee7cdc78.1b4d2648 Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a0925c9c does not match aorg 0000000000.00000000 from server@45.45.184.13 xmt 0xee7cdc78.1b0039b4 Oct 16 17:25:12 volumio systemd[1]: Starting Daily apt download activities... Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a086fbb9 does not match aorg 0000000000.00000000 from server@23.157.160.168 xmt 0xee7cdc78.1baef4ae Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a096332c does not match aorg 0000000000.00000000 from server@50.43.156.177 xmt 0xee7cdc78.1d77b255 Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a0954e0b does not match aorg 0000000000.00000000 from server@149.28.200.179 xmt 0xee7cdc78.1dcf3ac4 Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a0906b51 does not match aorg 0000000000.00000000 from server@23.186.168.126 xmt 0xee7cdc78.1e1d9214 Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a0944936 does not match aorg 0000000000.00000000 from server@23.186.168.131 xmt 0xee7cdc78.1e0fd900 Oct 16 17:25:12 volumio ntpd[770]: receive: Unexpected origin timestamp 0xedc482a8.a08f573b does not match aorg 0000000000.00000000 from server@44.190.5.123 xmt 0xee7cdc78.1d6be998 Oct 16 17:25:13 volumio systemd[1]: apt-daily.service: Succeeded. Oct 16 17:25:13 volumio systemd[1]: Started Daily apt download activities. Oct 16 17:25:13 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 16 17:25:13 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 16 17:25:13 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 16 17:25:15 volumio volumio[1038]: info: Discovery: A device disappeared from network Oct 16 17:25:15 volumio volumio[1038]: info: Discovery: A device disappeared from network Oct 16 17:25:15 volumio volumio[1038]: info: Discovery: adding 3d6dd591-c4a5-43a2-a847-3a683f958a32 Oct 16 17:25:15 volumio volumio[1038]: info: Discovery: Found device Volumio Oct 16 17:25:15 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:15 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 17:25:15 volumio volumio[1038]: info: Discovery: this is already registered, 3d6dd591-c4a5-43a2-a847-3a683f958a32 Oct 16 17:25:15 volumio volumio[1038]: info: Discovery: Found device Volumio Oct 16 17:25:15 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 16 17:25:15 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 17:25:19 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 17:25:21 volumio volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 17:25:21 volumio volumio[1038]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 17:25:21 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 17:25:21 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 17:25:21 volumio volumio[1038]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 17:25:21 volumio volumio[1038]: info: MyVolumio login type: Token Oct 16 17:25:21 volumio volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 17:25:21 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 17:25:21 volumio volumio-time-update[576]: volumio-time-update-util: Date not found in response Oct 16 17:25:21 volumio volumio-time-update[576]: volumio-time-update-util: Retrying in 5 seconds... Oct 16 17:25:22 volumio volumio[1038]: info: Starting Streaming Service Transparent Proxy Oct 16 17:25:22 volumio volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 17:25:22 volumio volumio[1038]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 17:25:22 volumio volumio[1038]: info: Streaming services startup Oct 16 17:25:22 volumio volumio[1038]: info: Starting Streaming Daemon Oct 16 17:25:22 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 17:25:22 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:22 volumio volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 17:25:22 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:22 volumio volumio[1038]: error: Cannot start Volumio Streaming Daemon Oct 16 17:25:22 volumio volumio[1038]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 17:25:22 volumio volumio[1038]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 17:25:22 volumio volumio[1038]: error: MyVolumio Custom Token format not valid, refreshing it Oct 16 17:25:23 volumio volumio[1038]: STREAMING PROXY: Starting server on port 3245 Oct 16 17:25:23 volumio volumio[1038]: Node JS runtime: 14 Oct 16 17:25:24 volumio volumio[1038]: info: MyVolumio login type: Token Oct 16 17:25:24 volumio volumio[1038]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 16 17:25:26 volumio volumio[1038]: info: MyVolumio token set successfully Oct 16 17:25:26 volumio volumio[1038]: info: MYVOLUMIO: Adding device Oct 16 17:25:26 volumio volumio[1038]: info: MYVOLUMIO: Evaluating Server Oct 16 17:25:26 volumio volumio-time-update[576]: volumio-time-update-util: Fetching time from Volumio... Oct 16 17:25:26 volumio volumio[1038]: info: MyVolumio Plan changed: superstar Oct 16 17:25:26 volumio volumio[1038]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Oct 16 17:25:26 volumio volumio[1038]: info: Adding audio output: Oct 16 17:25:26 volumio volumio[1038]: info: MYVOLUMIO: Adding device Oct 16 17:25:26 volumio volumio[1038]: info: MYVOLUMIO: Evaluating Server Oct 16 17:25:26 volumio volumio[1038]: info: Remote config written successfully Oct 16 17:25:26 volumio volumio[1038]: info: Starting Tunnel 1 Oct 16 17:25:26 volumio volumio[1038]: info: Starting Tunnel Connection Checker Oct 16 17:25:26 volumio volumio-time-update[576]: volumio-time-update-util: Setting system time to: 2026-10-16 17:25:26 Oct 16 17:25:26 volumio sudo[2137]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-10-16 17:25:26 Oct 16 17:25:26 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:26 volumio dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2139 comm="timedatectl set-time 2026-10-16 17:25:26 ") Oct 16 17:25:26 volumio systemd[1]: Starting Time & Date Service... Oct 16 17:25:26 volumio volumio[1038]: info: MYVolumio Device enabled Oct 16 17:25:26 volumio volumio[1038]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 16 17:25:26 volumio volumio[1038]: info: MyVolumio status changed Oct 16 17:25:26 volumio volumio[1038]: info: Streaming services startup Oct 16 17:25:26 volumio volumio[1038]: info: Starting Streaming Daemon Oct 16 17:25:26 volumio sudo[2154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 17:25:26 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:26 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:26 volumio volumio[1038]: error: Cannot start Volumio Streaming Daemon Oct 16 17:25:26 volumio volumio[1038]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 17:25:26 volumio volumio[1038]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 17:25:26 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 16 17:25:26 volumio dbus-daemon[584]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 16 17:25:26 volumio systemd[1]: Started Time & Date Service. Oct 16 17:25:26 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 16 17:25:26 volumio systemd-timedated[2144]: Changed local time to Fri Oct 16 17:25:26 2026 Oct 16 17:25:26 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:26 volumio volumio-time-update[576]: volumio-time-update-util: System time updated successfully. Oct 16 17:25:26 volumio systemd[1]: Started Volumio Time Update Utility. Oct 16 17:25:26 volumio systemd[1]: Reached target Multi-User System. Oct 16 17:25:26 volumio systemd[1]: Reached target Graphical Interface. Oct 16 17:25:26 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 16 17:25:26 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 16 17:25:26 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 16 17:25:26 volumio systemd[1]: Startup finished in 14.443s (kernel) + 3min 49.872s (userspace) = 4min 4.315s. Oct 16 17:25:26 volumio volumio[1038]: info: [1792171526220] Starting BluetoothController Oct 16 17:25:26 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 16 17:25:28 volumio sudo[2173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 16 17:25:28 volumio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:28 volumio sudo[2173]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:28 volumio volumio[1038]: info: MRS: MultiRoom plugin initialized Oct 16 17:25:28 volumio volumio[1038]: info: MRS: STOPPING SNAPCLIENT Oct 16 17:25:28 volumio volumio[1038]: info: MRS: Snap server stop Oct 16 17:25:28 volumio sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 16 17:25:28 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:28 volumio volumio[1038]: info: MRS: STOPPING volumioStreaming Oct 16 17:25:28 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 16 17:25:28 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:28 volumio sudo[2194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 16 17:25:28 volumio sudo[2194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:28 volumio sudo[2197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 16 17:25:28 volumio sudo[2189]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:28 volumio sudo[2197]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 17:25:28 volumio sudo[2197]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:28 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 16 17:25:28 volumio sudo[2194]: pam_unix(sudo:session): session closed for user root Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 16 17:25:28 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 16 17:25:28 volumio volumio[1038]: info: Preparing to generate the ALSA configuration file Oct 16 17:25:28 volumio volumio[1038]: error: Failed to ping endpoint eu12.myvolumio.org : unknown error Oct 16 17:25:28 volumio volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:25:28 volumio volumio[1038]: Error: Unable to resolve or reject the same promise twice Oct 16 17:25:28 volumio volumio[1038]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 16 17:25:28 volumio volumio[1038]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 16 17:25:28 volumio volumio[1038]: at Socket.emit (events.js:327:22) Oct 16 17:25:28 volumio volumio[1038]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 16 17:25:28 volumio volumio[1038]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 16 17:25:28 volumio volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 17:25:29 volumio sudo[2209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 17:24 Oct 16 17:25:29 volumio sudo[2209]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"