-- Logs begin at Sat 2025-03-01 15:54:35 EST, end at Sat 2025-03-01 16:25:17 EST. -- Mar 01 16:25:09 rivoplus ntpd[7610]: receive: Unexpected origin timestamp 0xeb6df6c3.943d07d5 does not match aorg 0000000000.00000000 from server@216.232.132.102 xmt 0xeb6df9b5.a0b9e469 Mar 01 16:25:10 rivoplus volumio[3392]: info: Reporting MCU Network Status: 1 Mar 01 16:25:10 rivoplus volumio[3392]: info: Volumio Network Manager: Network status updated: 1 Mar 01 16:25:11 rivoplus volumio-time-update[2834]: volumio-time-update-util: Date not found in response Mar 01 16:25:11 rivoplus volumio-time-update[2834]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 16:25:13 rivoplus volumio[3392]: info: Discovery: adding e53b0bca-01ec-435c-957b-92ae6032823f Mar 01 16:25:13 rivoplus volumio[3392]: info: Discovery: Found device Rivoplus Mar 01 16:25:13 rivoplus volumio[3392]: info: CoreCommandRouter::volumioGetState Mar 01 16:25:13 rivoplus volumio[3392]: info: CorePlayQueue::getTrack 0 Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 01 16:25:13 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 01 16:25:15 rivoplus volumio[3392]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 01 16:25:15 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 01 16:25:15 rivoplus volumio[3392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 16:25:15 rivoplus volumio[3392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 16:25:15 rivoplus volumio[3392]: info: Starting MyVolumio Remote Streaming Endpoints Mar 01 16:25:15 rivoplus volumio[3392]: info: MyVolumio login type: Token Mar 01 16:25:15 rivoplus volumio[3392]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 01 16:25:15 rivoplus volumio[3392]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Mar 01 16:25:15 rivoplus volumio[3392]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 01 16:25:15 rivoplus volumio[3392]: info: MyVolumio token set successfully Mar 01 16:25:15 rivoplus volumio[3392]: info: MYVOLUMIO: Adding device Mar 01 16:25:15 rivoplus volumio[3392]: info: MYVOLUMIO: Evaluating Server Mar 01 16:25:16 rivoplus volumio[3392]: info: MyVolumio Plan changed: premium Mar 01 16:25:16 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 01 16:25:16 rivoplus volumio[3392]: info: Removing browser output: myVolumio user plan is not superstar Mar 01 16:25:16 rivoplus volumio[3392]: info: Removing audio output: Mar 01 16:25:16 rivoplus volumio[3392]: info: MYVOLUMIO: Adding device Mar 01 16:25:16 rivoplus volumio[3392]: info: MYVOLUMIO: Evaluating Server Mar 01 16:25:16 rivoplus volumio[3392]: info: Remote config written successfully Mar 01 16:25:16 rivoplus volumio[3392]: info: Starting Tunnel 1 Mar 01 16:25:16 rivoplus volumio[3392]: info: Starting Tunnel Connection Checker Mar 01 16:25:16 rivoplus volumio-time-update[2834]: volumio-time-update-util: Fetching time from Volumio... Mar 01 16:25:16 rivoplus volumio-time-update[2834]: volumio-time-update-util: Setting system time to: 2025-03-01 16:25:16 Mar 01 16:25:16 rivoplus sudo[7719]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-01 16:25:16 Mar 01 16:25:16 rivoplus sudo[7719]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 16:25:16 rivoplus dbus-daemon[2850]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.48' (uid=0 pid=7723 comm="timedatectl set-time 2025-03-01 16:25:16 " label="kernel") Mar 01 16:25:17 rivoplus volumio[3392]: info: MYVolumio Device enabled Mar 01 16:25:17 rivoplus volumio[3392]: info: MyVolumio status changed Mar 01 16:25:17 rivoplus volumio[3392]: info: Streaming services startup Mar 01 16:25:17 rivoplus volumio[3392]: info: Starting Streaming Daemon Mar 01 16:25:17 rivoplus systemd[1]: Starting Time & Date Service... Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 01 16:25:17 rivoplus sudo[7733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 01 16:25:17 rivoplus sudo[7733]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 16:25:17 rivoplus volumio[3392]: info: Setting Geolocation for MyVolumio to us3 Mar 01 16:25:17 rivoplus volumio[3392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 16:25:17 rivoplus volumio[3392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 16:25:17 rivoplus volumio[3392]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 16:25:17 rivoplus volumio[3392]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 16:25:17 rivoplus sudo[7733]: pam_unix(sudo:session): session closed for user root Mar 01 16:25:17 rivoplus volumio[3392]: error: Cannot start Volumio Streaming Daemon Mar 01 16:25:17 rivoplus volumio[3392]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 01 16:25:17 rivoplus volumio[3392]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 01 16:25:17 rivoplus volumio[3392]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus volumio[3392]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Mar 01 16:25:17 rivoplus dbus-daemon[2850]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 01 16:25:17 rivoplus systemd[1]: Started Time & Date Service. Mar 01 16:25:16 rivoplus systemd-timedated[7727]: Changed local time to Sat Mar 1 16:25:16 2025 Mar 01 16:25:16 rivoplus sudo[7719]: pam_unix(sudo:session): session closed for user root Mar 01 16:25:16 rivoplus volumio-time-update[2834]: volumio-time-update-util: System time updated successfully. Mar 01 16:25:16 rivoplus systemd[1]: Started Volumio Time Update Utility. Mar 01 16:25:16 rivoplus systemd[1]: Reached target Multi-User System. Mar 01 16:25:16 rivoplus systemd[1]: Reached target Graphical Interface. Mar 01 16:25:16 rivoplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 01 16:25:16 rivoplus volumio[3392]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 01 16:25:16 rivoplus volumio[3392]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Mar 01 16:25:16 rivoplus volumio[3392]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 16:25:16 rivoplus volumio[3392]: Error: Unable to resolve or reject the same promise twice Mar 01 16:25:16 rivoplus volumio[3392]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 01 16:25:16 rivoplus volumio[3392]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Mar 01 16:25:16 rivoplus volumio[3392]: at Socket.emit (events.js:412:35) Mar 01 16:25:16 rivoplus volumio[3392]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 01 16:25:16 rivoplus volumio[3392]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 01 16:25:16 rivoplus volumio[3392]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 16:25:16 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 01 16:25:16 rivoplus systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 01 16:25:16 rivoplus systemd[1]: Startup finished in 12.861s (kernel) + 18min 8.928s (userspace) = 18min 21.790s. Mar 01 16:25:16 rivoplus sudo[7748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 16:24 Mar 01 16:25:16 rivoplus sudo[7748]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="378b0020ff563aa5ad5d39e2c3d02d91ccdbc9d2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 20 Feb 2025 04:24:23 PM CET" VOLUMIO_VERSION="3.794" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="a628a67b018b65c57bb8507dccb5ebd5"