-- Logs begin at Thu 2025-12-11 09:47:33 PST, end at Tue 2026-01-13 12:56:52 PST. -- Jan 13 12:56:46 rivoplus ntpd[5011]: receive: Unexpected origin timestamp 0xece58373.c0f7bf40 does not match aorg 0000000000.00000000 from server@100.16.142.51 xmt 0xed11300e.df7a743f Jan 13 12:56:46 rivoplus systemd[1]: Starting Daily man-db regeneration... Jan 13 12:56:46 rivoplus systemd[1]: Starting Daily apt download activities... Jan 13 12:56:46 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 12:56:46 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 12:56:47 rivoplus systemd[1]: man-db.service: Succeeded. Jan 13 12:56:47 rivoplus systemd[1]: Started Daily man-db regeneration. Jan 13 12:56:47 rivoplus volumio-time-update[2842]: volumio-time-update-util: Date not found in response Jan 13 12:56:47 rivoplus volumio-time-update[2842]: volumio-time-update-util: Retrying in 5 seconds... Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 13 12:56:47 rivoplus volumio[3177]: info: Discovery: A device disappeared from network Jan 13 12:56:47 rivoplus volumio[3177]: info: Discovery: Device rivoplus disappeared from network Jan 13 12:56:47 rivoplus volumio[3177]: info: Discovery: A device disappeared from network Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jan 13 12:56:47 rivoplus volumio[3177]: info: Discovery: adding 9811be6b-16e9-493a-826c-cb735ca10964 Jan 13 12:56:47 rivoplus volumio[3177]: info: Discovery: Found device Rivoplus Jan 13 12:56:47 rivoplus volumio[3177]: info: CoreCommandRouter::volumioGetState Jan 13 12:56:47 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 Jan 13 12:56:47 rivoplus volumio[3177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jan 13 12:56:48 rivoplus volumio[3177]: info: Discovery: this is already registered, 9811be6b-16e9-493a-826c-cb735ca10964 Jan 13 12:56:48 rivoplus volumio[3177]: info: Discovery: Found device Rivoplus Jan 13 12:56:48 rivoplus volumio[3177]: info: CoreCommandRouter::volumioGetState Jan 13 12:56:48 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 Jan 13 12:56:48 rivoplus systemd[1]: apt-daily.service: Succeeded. Jan 13 12:56:48 rivoplus systemd[1]: Started Daily apt download activities. Jan 13 12:56:48 rivoplus systemd[1]: Starting Daily apt upgrade and clean activities... Jan 13 12:56:48 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 12:56:48 rivoplus volumio[3177]: info: Discovery: this is already registered, 9811be6b-16e9-493a-826c-cb735ca10964 Jan 13 12:56:48 rivoplus volumio[3177]: info: Discovery: Found device Rivoplus Jan 13 12:56:48 rivoplus volumio[3177]: info: CoreCommandRouter::volumioGetState Jan 13 12:56:48 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 Jan 13 12:56:48 rivoplus volumio[3177]: info: CoreCommandRouter::volumioGetState Jan 13 12:56:48 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 Jan 13 12:56:48 rivoplus volumio[3177]: info: MCU Signalled Playback Inactive Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 13 12:56:48 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 13 12:56:48 rivoplus systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 13 12:56:48 rivoplus systemd[1]: Started Daily apt upgrade and clean activities. Jan 13 12:56:49 rivoplus volumio5-onboarding[3717]: time=2026-01-13T12:56:49.345-08:00 level=INFO msg="set device name" component=server type=REQUEST_TYPE_SET_DEVICE_NAME peer="00:00:00:00:00:00%01,192.168.12.251:50447 @ 0x22fe690" latency=-19.535291ms timeout=10s name=Rivoplus Jan 13 12:56:49 rivoplus volumio[3177]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 13 12:56:49 rivoplus volumio[3177]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 13 12:56:49 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 12:56:49 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 12:56:49 rivoplus volumio[3177]: info: Starting MyVolumio Remote Streaming Endpoints Jan 13 12:56:49 rivoplus volumio[3177]: info: MyVolumio login type: Token Jan 13 12:56:50 rivoplus volumio[3177]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 13 12:56:50 rivoplus volumio[3177]: 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' Jan 13 12:56:50 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jan 13 12:56:50 rivoplus volumio[3177]: info: Received Get System Info Jan 13 12:56:50 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 12:56:50 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 12:56:50 rivoplus volumio[3177]: info: Discovery: Getting this device information Jan 13 12:56:50 rivoplus volumio[3177]: info: CoreCommandRouter::volumioGetState Jan 13 12:56:50 rivoplus volumio[3177]: info: CorePlayQueue::getTrack 0 Jan 13 12:56:50 rivoplus volumio[3177]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 12:56:50 rivoplus volumio5-onboarding[3717]: time=2026-01-13T12:56:50.151-08:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%01,192.168.12.251:50447 @ 0x22fe690" latency=-31.393041ms timeout=10s from=APP_PAGE_SETUP_V1_NAME Jan 13 12:56:50 rivoplus volumio5-onboarding[3717]: time=2026-01-13T12:56:50.188-08:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="00:00:00:00:00:00%01,192.168.12.251:50447 @ 0x22fe690" latency=-30.745124ms timeout=1m0s Jan 13 12:56:50 rivoplus volumio[3177]: error: MyVolumio Custom Token format not valid, refreshing it Jan 13 12:56:51 rivoplus volumio[3177]: info: MyVolumio login type: Token Jan 13 12:56:51 rivoplus volumio[3177]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 13 12:56:52 rivoplus volumio[3177]: info: MyVolumio token set successfully Jan 13 12:56:52 rivoplus volumio[3177]: info: MYVOLUMIO: Adding device Jan 13 12:56:52 rivoplus volumio[3177]: info: MYVOLUMIO: Evaluating Server Jan 13 12:56:52 rivoplus volumio-time-update[2842]: volumio-time-update-util: Fetching time from Volumio... Jan 13 12:56:52 rivoplus volumio[3177]: info: MyVolumio status changed Jan 13 12:56:52 rivoplus volumio[3177]: info: Streaming services startup Jan 13 12:56:52 rivoplus volumio[3177]: info: Starting Streaming Daemon Jan 13 12:56:52 rivoplus volumio[3177]: info: Removing browser output: myVolumio user plan is not superstar Jan 13 12:56:52 rivoplus volumio[3177]: info: Removing audio output: Jan 13 12:56:52 rivoplus volumio[3177]: info: Stoppping Tunnel 1 Jan 13 12:56:52 rivoplus sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 13 12:56:52 rivoplus sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 12:56:52 rivoplus sudo[5225]: pam_unix(sudo:session): session closed for user root Jan 13 12:56:52 rivoplus sudo[5228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 13 12:56:52 rivoplus sudo[5228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 12:56:52 rivoplus volumio[3177]: error: Cannot start Volumio Streaming Daemon Jan 13 12:56:52 rivoplus volumio[3177]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 13 12:56:52 rivoplus volumio[3177]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 13 12:56:52 rivoplus sudo[5228]: pam_unix(sudo:session): session closed for user root Jan 13 12:56:52 rivoplus volumio[3177]: info: Remote SSH Stopped Jan 13 12:56:52 rivoplus volumio-time-update[2842]: volumio-time-update-util: Setting system time to: 2026-01-13 12:56:52 Jan 13 12:56:52 rivoplus sudo[5231]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-01-13 12:56:52 Jan 13 12:56:52 rivoplus sudo[5231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 12:56:52 rivoplus systemd-timedated[4947]: Changed local time to Tue Jan 13 12:56:52 2026 Jan 13 12:56:52 rivoplus sudo[5231]: pam_unix(sudo:session): session closed for user root Jan 13 12:56:52 rivoplus volumio-time-update[2842]: volumio-time-update-util: System time updated successfully. Jan 13 12:56:52 rivoplus systemd[1]: Started Volumio Time Update Utility. Jan 13 12:56:52 rivoplus systemd[1]: Reached target Multi-User System. Jan 13 12:56:52 rivoplus systemd[1]: Reached target Graphical Interface. Jan 13 12:56:52 rivoplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 13 12:56:52 rivoplus volumio[3177]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 12:56:52 rivoplus volumio[3177]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Jan 13 12:56:52 rivoplus volumio[3177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 12:56:52 rivoplus volumio[3177]: Error: Unable to resolve or reject the same promise twice Jan 13 12:56:52 rivoplus volumio[3177]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 13 12:56:52 rivoplus volumio[3177]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jan 13 12:56:52 rivoplus volumio[3177]: at Socket.emit (events.js:412:35) Jan 13 12:56:52 rivoplus volumio[3177]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 13 12:56:52 rivoplus volumio[3177]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 13 12:56:52 rivoplus volumio[3177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 12:56:52 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 13 12:56:52 rivoplus systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 13 12:56:52 rivoplus systemd[1]: Startup finished in 12.993s (kernel) + 5min 25.652s (userspace) = 5min 38.646s. Jan 13 12:56:52 rivoplus sudo[5254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 12:55 Jan 13 12:56:52 rivoplus sudo[5254]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"