-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2026-06-02 15:22:28 CEST. -- Jun 02 15:22:17 musiquebar ntpd[783]: receive: Unexpected origin timestamp 0xeda43fed.50f3c626 does not match aorg 0000000000.00000000 from server@82.64.55.167 xmt 0xedc95789.1dc40e7e Jun 02 15:22:17 musiquebar ntpd[783]: receive: Unexpected origin timestamp 0xeda43fed.50f1c274 does not match aorg 0000000000.00000000 from server@82.65.248.56 xmt 0xedc95789.1ba07362 Jun 02 15:22:17 musiquebar systemd[1]: Starting Daily apt download activities... Jun 02 15:22:17 musiquebar systemd[1]: Starting Daily man-db regeneration... Jun 02 15:22:17 musiquebar volumio[895]: info: Discovery: A device disappeared from network Jun 02 15:22:17 musiquebar volumio[895]: info: Discovery: Device musiquebar disappeared from network Jun 02 15:22:17 musiquebar volumio[895]: info: Discovery: A device disappeared from network Jun 02 15:22:17 musiquebar volumio[895]: info: Discovery: Device rasptouchboutique disappeared from network Jun 02 15:22:17 musiquebar volumio[895]: info: Discovery: Disconnected from remote: 10.151.1.58 Jun 02 15:22:17 musiquebar volumio[895]: info: Discovery: A device disappeared from network Jun 02 15:22:18 musiquebar ntpd[783]: Soliciting pool server 2a01:cb00:129e:a803::278 Jun 02 15:22:18 musiquebar volumio[895]: info: Discovery: adding 3405f84c-6377-4865-8e54-5594711cef5d Jun 02 15:22:18 musiquebar volumio[895]: info: Discovery: Found device MusiqueBar Jun 02 15:22:18 musiquebar volumio[895]: info: CoreCommandRouter::volumioGetState Jun 02 15:22:18 musiquebar volumio[895]: info: CorePlayQueue::getTrack 0 Jun 02 15:22:18 musiquebar volumio[895]: info: Discovery: this is already registered, 3405f84c-6377-4865-8e54-5594711cef5d Jun 02 15:22:18 musiquebar volumio[895]: info: Discovery: Found device MusiqueBar Jun 02 15:22:18 musiquebar volumio[895]: info: CoreCommandRouter::volumioGetState Jun 02 15:22:18 musiquebar volumio[895]: info: CorePlayQueue::getTrack 0 Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin bluetooth to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin multiroom to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin metavolumio to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin cd_controller to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 02 15:22:18 musiquebar volumio[895]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 02 15:22:19 musiquebar systemd[1]: man-db.service: Succeeded. Jun 02 15:22:19 musiquebar systemd[1]: Started Daily man-db regeneration. Jun 02 15:22:19 musiquebar ntpd[783]: Soliciting pool server 5.196.76.84 Jun 02 15:22:19 musiquebar volumio[895]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 02 15:22:19 musiquebar volumio[895]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 02 15:22:19 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:19 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:19 musiquebar volumio[895]: info: Starting MyVolumio Remote Streaming Endpoints Jun 02 15:22:19 musiquebar volumio[895]: info: MyVolumio login type: Token Jun 02 15:22:19 musiquebar volumio[895]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 02 15:22:19 musiquebar volumio[895]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 02 15:22:20 musiquebar systemd[1]: apt-daily.service: Succeeded. Jun 02 15:22:20 musiquebar systemd[1]: Started Daily apt download activities. Jun 02 15:22:20 musiquebar ntpd[783]: Soliciting pool server 62.210.129.171 Jun 02 15:22:20 musiquebar systemd[1]: Starting Daily apt upgrade and clean activities... Jun 02 15:22:20 musiquebar volumio[895]: info: Starting Streaming Service Transparent Proxy Jun 02 15:22:20 musiquebar volumio[895]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 02 15:22:20 musiquebar volumio[895]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 02 15:22:20 musiquebar volumio[895]: info: Streaming services startup Jun 02 15:22:20 musiquebar volumio[895]: info: Starting Streaming Daemon Jun 02 15:22:20 musiquebar sudo[2063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 02 15:22:20 musiquebar sudo[2063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 15:22:20 musiquebar volumio[895]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 02 15:22:20 musiquebar sudo[2063]: pam_unix(sudo:session): session closed for user root Jun 02 15:22:20 musiquebar volumio[895]: info: Discovery: adding 663665d4-dbaf-46b8-b685-ab5daee653cb Jun 02 15:22:20 musiquebar volumio[895]: info: Discovery: Found device RasptouchBoutique Jun 02 15:22:20 musiquebar volumio[895]: info: Discovery: Connecting to remote: 10.151.1.58 Jun 02 15:22:20 musiquebar volumio[895]: error: Cannot start Volumio Streaming Daemon Jun 02 15:22:20 musiquebar volumio[895]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 02 15:22:20 musiquebar volumio[895]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 02 15:22:20 musiquebar volumio[895]: info: Discovery: Connected to remote: 10.151.1.58 Jun 02 15:22:21 musiquebar ntpd[783]: Soliciting pool server 82.66.2.248 Jun 02 15:22:21 musiquebar volumio[895]: error: MyVolumio Custom Token format not valid, refreshing it Jun 02 15:22:21 musiquebar systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 02 15:22:21 musiquebar systemd[1]: Started Daily apt upgrade and clean activities. Jun 02 15:22:21 musiquebar volumio[895]: STREAMING PROXY: Starting server on port 3245 Jun 02 15:22:21 musiquebar volumio[895]: Node JS runtime: 14 Jun 02 15:22:21 musiquebar volumio[895]: info: MyVolumio login type: Token Jun 02 15:22:22 musiquebar ntpd[783]: Soliciting pool server 172.232.63.219 Jun 02 15:22:22 musiquebar volumio[895]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 02 15:22:22 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 15:22:22 musiquebar volumio[895]: info: Received Get System Info Jun 02 15:22:22 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 15:22:22 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 15:22:22 musiquebar volumio[895]: info: Discovery: Getting this device information Jun 02 15:22:22 musiquebar volumio[895]: info: CoreCommandRouter::volumioGetState Jun 02 15:22:22 musiquebar volumio[895]: info: CorePlayQueue::getTrack 0 Jun 02 15:22:22 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 15:22:22 musiquebar volumio[895]: info: MyVolumio token set successfully Jun 02 15:22:22 musiquebar volumio[895]: info: MYVOLUMIO: Adding device Jun 02 15:22:22 musiquebar volumio[895]: info: MYVOLUMIO: Evaluating Server Jun 02 15:22:23 musiquebar volumio-time-update[655]: volumio-time-update-util: Date not found in response Jun 02 15:22:23 musiquebar volumio-time-update[655]: volumio-time-update-util: Retrying in 5 seconds... Jun 02 15:22:23 musiquebar volumio[895]: info: MyVolumio status changed Jun 02 15:22:23 musiquebar volumio[895]: info: Streaming services startup Jun 02 15:22:23 musiquebar volumio[895]: info: Starting Streaming Daemon Jun 02 15:22:23 musiquebar volumio[895]: info: Removing browser output: myVolumio user plan is not superstar Jun 02 15:22:23 musiquebar volumio[895]: info: Removing audio output: Jun 02 15:22:23 musiquebar volumio[895]: info: Stoppping Tunnel 1 Jun 02 15:22:23 musiquebar sudo[2136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 02 15:22:23 musiquebar sudo[2136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 15:22:23 musiquebar sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 02 15:22:23 musiquebar sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 15:22:23 musiquebar sudo[2136]: pam_unix(sudo:session): session closed for user root Jun 02 15:22:23 musiquebar volumio[895]: error: Cannot start Volumio Streaming Daemon Jun 02 15:22:23 musiquebar volumio[895]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 02 15:22:23 musiquebar volumio[895]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 02 15:22:23 musiquebar sudo[2138]: pam_unix(sudo:session): session closed for user root Jun 02 15:22:23 musiquebar volumio[895]: info: Remote SSH Stopped Jun 02 15:22:23 musiquebar volumio[895]: info: Setting Geolocation for MyVolumio to eu6 Jun 02 15:22:23 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:23 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:23 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:24 musiquebar volumio[895]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 02 15:22:24 musiquebar ntpd[783]: Soliciting pool server 51.91.83.168 Jun 02 15:22:24 musiquebar ntpd[783]: Soliciting pool server 31.58.102.164 Jun 02 15:22:24 musiquebar volumio[895]: info: Updating MyVolumio device info Jun 02 15:22:24 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:24 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:24 musiquebar volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 15:22:24 musiquebar volumio[895]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 02 15:22:25 musiquebar ntpd[783]: Soliciting pool server 37.59.63.125 Jun 02 15:22:25 musiquebar ntpd[783]: Soliciting pool server 54.38.114.34 Jun 02 15:22:28 musiquebar volumio-time-update[655]: volumio-time-update-util: Fetching time from Volumio... Jun 02 15:22:28 musiquebar volumio[895]: info: MYVOLUMIO: Adding device Jun 02 15:22:28 musiquebar volumio[895]: info: MYVOLUMIO: Evaluating Server Jun 02 15:22:28 musiquebar volumio-time-update[655]: volumio-time-update-util: Setting system time to: 2026-06-02 15:22:28 Jun 02 15:22:28 musiquebar sudo[2147]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-06-02 15:22:28 Jun 02 15:22:28 musiquebar sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 15:22:28 musiquebar dbus-daemon[672]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.24' (uid=0 pid=2148 comm="timedatectl set-time 2026-06-02 15:22:28 ") Jun 02 15:22:28 musiquebar systemd[1]: Starting Time & Date Service... Jun 02 15:22:28 musiquebar dbus-daemon[672]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 02 15:22:28 musiquebar systemd[1]: Started Time & Date Service. Jun 02 15:22:28 musiquebar systemd-timedated[2149]: Changed local time to Tue Jun 2 15:22:28 2026 Jun 02 15:22:28 musiquebar sudo[2147]: pam_unix(sudo:session): session closed for user root Jun 02 15:22:28 musiquebar volumio-time-update[655]: volumio-time-update-util: System time updated successfully. Jun 02 15:22:28 musiquebar systemd[1]: Started Volumio Time Update Utility. Jun 02 15:22:28 musiquebar systemd[1]: Reached target Multi-User System. Jun 02 15:22:28 musiquebar systemd[1]: Reached target Graphical Interface. Jun 02 15:22:28 musiquebar systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 02 15:22:28 musiquebar volumio[895]: error: Failed to ping endpoint eu8.myvolumio.org : unknown error Jun 02 15:22:28 musiquebar volumio[895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 15:22:28 musiquebar volumio[895]: Error: Unable to resolve or reject the same promise twice Jun 02 15:22:28 musiquebar volumio[895]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 02 15:22:28 musiquebar volumio[895]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Jun 02 15:22:28 musiquebar volumio[895]: at Socket.emit (events.js:315:20) Jun 02 15:22:28 musiquebar volumio[895]: at addChunk (internal/streams/readable.js:309:12) Jun 02 15:22:28 musiquebar volumio[895]: at readableAddChunk (internal/streams/readable.js:284:9) Jun 02 15:22:28 musiquebar volumio[895]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jun 02 15:22:28 musiquebar volumio[895]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jun 02 15:22:28 musiquebar volumio[895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 15:22:28 musiquebar systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 02 15:22:28 musiquebar systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 02 15:22:28 musiquebar sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 15:21 Jun 02 15:22:28 musiquebar sudo[2179]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"