-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-10-24 22:31:34 CEST. -- Oct 24 22:31:23 volumio ntpd[1036]: receive: Unexpected origin timestamp 0xec2f10e9.24eaafd5 does not match aorg 0000000000.00000000 from server@148.251.5.46 xmt 0xeca6609b.5972bc2c Oct 24 22:31:23 volumio ntpd[1036]: receive: Unexpected origin timestamp 0xec2f10e9.24e9bd5c does not match aorg 0000000000.00000000 from server@185.104.163.42 xmt 0xeca6609b.59c38178 Oct 24 22:31:23 volumio ntpd[1036]: receive: Unexpected origin timestamp 0xec2f10e9.24e8c689 does not match aorg 0000000000.00000000 from server@213.202.247.29 xmt 0xeca6609b.5a3c038e Oct 24 22:31:23 volumio ntpd[1036]: receive: Unexpected origin timestamp 0xec2f10e9.24e52f32 does not match aorg 0000000000.00000000 from server@80.153.195.191 xmt 0xeca6609b.5a553698 Oct 24 22:31:23 volumio volumio[787]: info: Volumio called home Oct 24 22:31:23 volumio systemd[1]: Starting Daily apt download activities... Oct 24 22:31:24 volumio systemd[1]: apt-daily.service: Succeeded. Oct 24 22:31:24 volumio systemd[1]: Started Daily apt download activities. Oct 24 22:31:24 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 24 22:31:24 volumio volumio[787]: info: Discovery: A device disappeared from network Oct 24 22:31:24 volumio volumio[787]: info: Discovery: A device disappeared from network Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Device volumio-markus disappeared from network Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Disconnected from remote: 192.168.0.2 Oct 24 22:31:24 volumio volumio[787]: info: Discovery: A device disappeared from network Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Device volumio disappeared from network Oct 24 22:31:24 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 24 22:31:24 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 24 22:31:24 volumio volumio[787]: info: Discovery: adding 1ffc1625-2bc3-4977-8d07-1320e1402bcf Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Found device Volumio Klipsch Oct 24 22:31:24 volumio volumio[787]: info: Discovery: adding a4e940c9-0f20-4827-b8e4-f4755042cd96 Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Found device volumio-markus Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Connecting to remote: 192.168.0.2 Oct 24 22:31:24 volumio volumio[787]: info: Discovery: adding 085f01dc-a4d2-4a2d-8f37-f87d6804947e Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Found device Volumio Oct 24 22:31:24 volumio volumio[787]: info: CoreCommandRouter::volumioGetState Oct 24 22:31:24 volumio volumio[787]: info: CorePlayQueue::getTrack 0 Oct 24 22:31:24 volumio volumio[1156]: ..........................................++++ Oct 24 22:31:24 volumio volumio[787]: info: Discovery: Connected to remote: 192.168.0.2 Oct 24 22:31:24 volumio nmbd[745]: [2025/10/24 22:31:24.712064, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 24 22:31:24 volumio nmbd[745]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.101 for name WORKGROUP<1d>. Oct 24 22:31:24 volumio nmbd[745]: This response was from IP 192.168.0.102, reporting an IP address of 192.168.0.102. Oct 24 22:31:24 volumio volumio[1156]: ..++++ Oct 24 22:31:24 volumio volumio[1156]: e is 65537 (0x010001) Oct 24 22:31:24 volumio volumio[1156]: writing RSA key Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin bluetooth to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin multiroom to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin metavolumio to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin cd_controller to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 24 22:31:30 volumio volumio[787]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 24 22:31:31 volumio volumio[787]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 24 22:31:31 volumio volumio[787]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 24 22:31:31 volumio volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 22:31:31 volumio volumio[787]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 22:31:31 volumio volumio[787]: info: Starting MyVolumio Remote Streaming Endpoints Oct 24 22:31:31 volumio volumio[787]: info: MyVolumio login type: Token Oct 24 22:31:31 volumio volumio[787]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 24 22:31:31 volumio volumio[787]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 24 22:31:32 volumio volumio[787]: info: Starting Streaming Service Transparent Proxy Oct 24 22:31:32 volumio volumio[787]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 24 22:31:32 volumio volumio[787]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 24 22:31:32 volumio volumio[787]: info: Streaming services startup Oct 24 22:31:32 volumio volumio[787]: info: Starting Streaming Daemon Oct 24 22:31:32 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 24 22:31:32 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 22:31:32 volumio volumio[787]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 24 22:31:32 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Oct 24 22:31:32 volumio volumio[787]: error: Cannot start Volumio Streaming Daemon Oct 24 22:31:32 volumio volumio[787]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 24 22:31:32 volumio volumio[787]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 24 22:31:32 volumio volumio[787]: error: MyVolumio Custom Token format not valid, refreshing it Oct 24 22:31:32 volumio volumio[787]: STREAMING PROXY: Starting server on port 3245 Oct 24 22:31:32 volumio volumio[787]: Node JS runtime: 14 Oct 24 22:31:32 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 24 22:31:33 volumio volumio[787]: info: MyVolumio login type: Token Oct 24 22:31:33 volumio volumio[787]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 24 22:31:34 volumio volumio[787]: info: MyVolumio token set successfully Oct 24 22:31:34 volumio volumio[787]: info: MYVOLUMIO: Adding device Oct 24 22:31:34 volumio volumio[787]: info: MYVOLUMIO: Evaluating Server Oct 24 22:31:34 volumio volumio-time-update[539]: volumio-time-update-util: Setting system time to: 2025-10-24 22:31:34 Oct 24 22:31:34 volumio sudo[1331]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-10-24 22:31:34 Oct 24 22:31:34 volumio sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 22:31:34 volumio dbus-daemon[540]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1332 comm="timedatectl set-time 2025-10-24 22:31:34 ") Oct 24 22:31:34 volumio systemd[1]: Starting Time & Date Service... Oct 24 22:31:34 volumio dbus-daemon[540]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 24 22:31:34 volumio systemd[1]: Started Time & Date Service. Oct 24 22:31:34 volumio systemd-timedated[1333]: Changed local time to Fri Oct 24 22:31:34 2025 Oct 24 22:31:34 volumio sudo[1331]: pam_unix(sudo:session): session closed for user root Oct 24 22:31:34 volumio volumio-time-update[539]: volumio-time-update-util: System time updated successfully. Oct 24 22:31:34 volumio systemd[1]: Started Volumio Time Update Utility. Oct 24 22:31:34 volumio systemd[1]: Reached target Multi-User System. Oct 24 22:31:34 volumio systemd[1]: Reached target Graphical Interface. Oct 24 22:31:34 volumio volumio[787]: info: MyVolumio Plan changed: premium Oct 24 22:31:34 volumio volumio[787]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 24 22:31:34 volumio volumio[787]: info: Removing browser output: myVolumio user plan is not superstar Oct 24 22:31:34 volumio volumio[787]: info: Removing audio output: Oct 24 22:31:34 volumio volumio[787]: info: MYVOLUMIO: Adding device Oct 24 22:31:34 volumio volumio[787]: info: MYVOLUMIO: Evaluating Server Oct 24 22:31:34 volumio volumio[787]: info: Remote config written successfully Oct 24 22:31:34 volumio volumio[787]: info: Starting Tunnel 1 Oct 24 22:31:34 volumio volumio[787]: info: Starting Tunnel Connection Checker Oct 24 22:31:34 volumio volumio[787]: info: Completed starting MyVolumio Plugin Oct 24 22:31:34 volumio volumio[787]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Oct 24 22:31:34 volumio volumio[787]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 22:31:34 volumio volumio[787]: Error: Unable to resolve or reject the same promise twice Oct 24 22:31:34 volumio volumio[787]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 24 22:31:34 volumio volumio[787]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 24 22:31:34 volumio volumio[787]: at Socket.emit (events.js:327:22) Oct 24 22:31:34 volumio volumio[787]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 24 22:31:34 volumio volumio[787]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 24 22:31:34 volumio volumio[787]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 22:31:34 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 24 22:31:34 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 24 22:31:34 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 24 22:31:34 volumio systemd[1]: Startup finished in 12.121s (kernel) + 34.249s (userspace) = 46.371s. Oct 24 22:31:34 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 22:30 Oct 24 22:31:34 volumio sudo[1360]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"