-- Logs begin at Sat 2025-05-10 03:42:18 JST, end at Thu 2025-05-15 21:18:53 JST. -- May 15 21:18:44 rivo ntpd[5259]: receive: Unexpected origin timestamp 0xebc8cc60.46f950bf does not match aorg 0000000000.00000000 from server@172.237.12.44 xmt 0xebd05a24.4bcbe8ba May 15 21:18:44 rivo ntpd[5259]: receive: Unexpected origin timestamp 0xebc8cc60.46fe3858 does not match aorg 0000000000.00000000 from server@139.162.81.45 xmt 0xebd05a24.4badeb4a May 15 21:18:44 rivo ntpd[5259]: receive: Unexpected origin timestamp 0xebc8cc60.46f17a94 does not match aorg 0000000000.00000000 from server@45.76.211.39 xmt 0xebd05a24.4bfa523e May 15 21:18:44 rivo ntpd[5259]: receive: Unexpected origin timestamp 0xebc8cc60.46fbe7af does not match aorg 0000000000.00000000 from server@45.76.221.157 xmt 0xebd05a24.4bbfde47 May 15 21:18:44 rivo systemd[1]: Starting Daily apt download activities... May 15 21:18:44 rivo systemd[1]: Starting Daily man-db regeneration... May 15 21:18:44 rivo volumio[3168]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 21:18:44 rivo volumio[3168]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 21:18:44 rivo volumio[3168]: info: Discovery: A device disappeared from network May 15 21:18:44 rivo volumio[3168]: info: Discovery: Device rivo disappeared from network May 15 21:18:44 rivo volumio[3168]: info: Discovery: A device disappeared from network May 15 21:18:44 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 May 15 21:18:44 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:44 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:44 rivo systemd[1]: man-db.service: Succeeded. May 15 21:18:44 rivo systemd[1]: Started Daily man-db regeneration. May 15 21:18:44 rivo volumio[3168]: info: MRS: Found cast device: AQUOS-4KTVJ22-47f1a1d5a9cfc52be51f482770f5b739 May 15 21:18:44 rivo volumio[3168]: info: Discovery: adding 428834cc-9592-4626-9aa4-89a66583ca69 May 15 21:18:44 rivo volumio[3168]: info: Discovery: Found device Rivo May 15 21:18:44 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:44 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:44 rivo volumio[3168]: info: Discovery: this is already registered, 428834cc-9592-4626-9aa4-89a66583ca69 May 15 21:18:44 rivo volumio[3168]: info: Discovery: Found device Rivo May 15 21:18:44 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:44 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:45 rivo systemd[1]: apt-daily.service: Succeeded. May 15 21:18:45 rivo systemd[1]: Started Daily apt download activities. May 15 21:18:45 rivo systemd[1]: Starting Daily apt upgrade and clean activities... May 15 21:18:45 rivo volumio[3168]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 21:18:45 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 May 15 21:18:45 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:45 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:45 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 May 15 21:18:46 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 15 21:18:46 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:46 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:46 rivo systemd[1]: apt-daily-upgrade.service: Succeeded. May 15 21:18:46 rivo systemd[1]: Started Daily apt upgrade and clean activities. May 15 21:18:46 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 15 21:18:46 rivo volumio-time-update[2850]: volumio-time-update-util: Date not found in response May 15 21:18:46 rivo volumio-time-update[2850]: volumio-time-update-util: Retrying in 5 seconds... May 15 21:18:46 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:46 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 15 21:18:47 rivo volumio[3168]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 15 21:18:49 rivo volumio[3168]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 15 21:18:49 rivo volumio[3168]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 15 21:18:49 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 21:18:49 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 21:18:49 rivo volumio[3168]: info: Starting MyVolumio Remote Streaming Endpoints May 15 21:18:49 rivo volumio[3168]: info: MyVolumio login type: Token May 15 21:18:49 rivo volumio[3168]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 15 21:18:49 rivo volumio[3168]: 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' May 15 21:18:49 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 15 21:18:49 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:49 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:49 rivo volumio[3168]: error: MyVolumio Custom Token format not valid, refreshing it May 15 21:18:50 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 15 21:18:50 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:50 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:50 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 21:18:50 rivo volumio[3168]: info: Received Get System Info May 15 21:18:50 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:18:50 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:18:50 rivo volumio[3168]: info: Discovery: Getting this device information May 15 21:18:50 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:50 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:50 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:18:50 rivo volumio[3168]: info: MyVolumio login type: Token May 15 21:18:51 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 15 21:18:51 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:51 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:51 rivo volumio[3168]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 15 21:18:51 rivo volumio-time-update[2850]: volumio-time-update-util: Fetching time from Volumio... May 15 21:18:51 rivo volumio[3168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 15 21:18:51 rivo volumio[3168]: info: CoreCommandRouter::volumioGetState May 15 21:18:51 rivo volumio[3168]: info: CorePlayQueue::getTrack 0 May 15 21:18:52 rivo volumio[3168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 15 21:18:52 rivo volumio[3168]: info: MyVolumio login type: Token May 15 21:18:52 rivo volumio[3168]: info: MyVolumio token set successfully May 15 21:18:52 rivo volumio[3168]: info: MYVOLUMIO: Adding device May 15 21:18:52 rivo volumio[3168]: info: MYVOLUMIO: Evaluating Server May 15 21:18:52 rivo volumio-time-update[2850]: volumio-time-update-util: Setting system time to: 2025-05-15 21:18:52 May 15 21:18:52 rivo sudo[5449]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-15 21:18:52 May 15 21:18:52 rivo sudo[5449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:18:52 rivo dbus-daemon[2828]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.50' (uid=0 pid=5451 comm="timedatectl set-time 2025-05-15 21:18:52 " label="kernel") May 15 21:18:52 rivo systemd[1]: Starting Time & Date Service... May 15 21:18:52 rivo dbus-daemon[2828]: [system] Successfully activated service 'org.freedesktop.timedate1' May 15 21:18:52 rivo systemd[1]: Started Time & Date Service. May 15 21:18:52 rivo systemd-timedated[5452]: Changed local time to Thu May 15 21:18:52 2025 May 15 21:18:52 rivo sudo[5449]: pam_unix(sudo:session): session closed for user root May 15 21:18:52 rivo volumio-time-update[2850]: volumio-time-update-util: System time updated successfully. May 15 21:18:52 rivo systemd[1]: Started Volumio Time Update Utility. May 15 21:18:52 rivo systemd[1]: Reached target Multi-User System. May 15 21:18:52 rivo systemd[1]: Reached target Graphical Interface. May 15 21:18:52 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... May 15 21:18:52 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 15 21:18:52 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. May 15 21:18:52 rivo systemd[1]: Startup finished in 12.883s (kernel) + 5min 51.516s (userspace) = 6min 4.400s. May 15 21:18:52 rivo volumio[3168]: info: MyVolumio status changed May 15 21:18:52 rivo volumio[3168]: info: Streaming services startup May 15 21:18:52 rivo volumio[3168]: info: Starting Streaming Daemon May 15 21:18:52 rivo volumio[3168]: info: Removing browser output: myVolumio user plan is not superstar May 15 21:18:52 rivo volumio[3168]: info: Removing audio output: May 15 21:18:52 rivo volumio[3168]: info: Stoppping Tunnel 1 May 15 21:18:52 rivo sudo[5482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 21:18:52 rivo sudo[5482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:18:52 rivo sudo[5482]: pam_unix(sudo:session): session closed for user root May 15 21:18:52 rivo volumio[3168]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 21:18:52 rivo sudo[5498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 15 21:18:52 rivo sudo[5498]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:18:52 rivo volumio[3168]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 21:18:52 rivo volumio[3168]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error May 15 21:18:52 rivo sudo[5498]: pam_unix(sudo:session): session closed for user root May 15 21:18:52 rivo volumio[3168]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 21:18:52 rivo volumio[3168]: Error: Unable to resolve or reject the same promise twice May 15 21:18:52 rivo volumio[3168]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 15 21:18:52 rivo volumio[3168]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) May 15 21:18:52 rivo volumio[3168]: at Socket.emit (events.js:412:35) May 15 21:18:52 rivo volumio[3168]: at endReadableNT (internal/streams/readable.js:1333:12) May 15 21:18:52 rivo volumio[3168]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 15 21:18:52 rivo volumio[3168]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 21:18:53 rivo sudo[5509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 21:17 May 15 21:18:53 rivo sudo[5509]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:35:56 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ea561adf1e965df757744ffb8ecc9b8a"