-- Logs begin at Sun 2026-05-24 21:16:28 +03, end at Sun 2026-05-31 06:17:16 +03. -- May 31 06:17:08 volumio systemd[1]: Starting Daily man-db regeneration... May 31 06:17:08 volumio systemd[1]: Starting Daily apt download activities... May 31 06:17:08 volumio volumio[3196]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 06:17:08 volumio volumio[3196]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 06:17:08 volumio systemd[1]: man-db.service: Succeeded. May 31 06:17:08 volumio systemd[1]: Started Daily man-db regeneration. May 31 06:17:08 volumio volumio[3196]: info: Discovery: A device disappeared from network May 31 06:17:08 volumio volumio[3196]: info: Discovery: A device disappeared from network May 31 06:17:08 volumio volumio[3196]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 06:17:08 volumio volumio[3196]: info: Discovery: adding 2ae06383-302e-4348-9d0d-ab8afbd1febc May 31 06:17:08 volumio volumio[3196]: info: Discovery: Found device Volumio May 31 06:17:08 volumio volumio[3196]: info: CoreCommandRouter::volumioGetState May 31 06:17:08 volumio volumio[3196]: info: CorePlayQueue::getTrack 0 May 31 06:17:08 volumio volumio[3196]: info: Discovery: this is already registered, 2ae06383-302e-4348-9d0d-ab8afbd1febc May 31 06:17:08 volumio volumio[3196]: info: Discovery: Found device Volumio May 31 06:17:08 volumio volumio[3196]: info: CoreCommandRouter::volumioGetState May 31 06:17:08 volumio volumio[3196]: info: CorePlayQueue::getTrack 0 May 31 06:17:09 volumio volumio-time-update[2866]: volumio-time-update-util: Date not found in response May 31 06:17:09 volumio volumio-time-update[2866]: volumio-time-update-util: Retrying in 5 seconds... May 31 06:17:09 volumio systemd[1]: apt-daily.service: Succeeded. May 31 06:17:09 volumio systemd[1]: Started Daily apt download activities. May 31 06:17:09 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 31 06:17:09 volumio volumio[3196]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 06:17:09 volumio volumio[3196]: info: MyVolumio token set successfully May 31 06:17:09 volumio volumio[3196]: info: MYVOLUMIO: Adding device May 31 06:17:09 volumio volumio[3196]: info: MYVOLUMIO: Evaluating Server May 31 06:17:10 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 31 06:17:10 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 31 06:17:10 volumio volumio[3196]: info: MyVolumio status changed May 31 06:17:10 volumio volumio[3196]: info: Streaming services startup May 31 06:17:10 volumio volumio[3196]: info: Starting Streaming Daemon May 31 06:17:10 volumio volumio[3196]: info: Removing browser output: myVolumio user plan is not superstar May 31 06:17:10 volumio volumio[3196]: info: Removing audio output: May 31 06:17:10 volumio volumio[3196]: info: Stoppping Tunnel 1 May 31 06:17:10 volumio sudo[5208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 06:17:10 volumio sudo[5208]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:17:10 volumio volumio[3196]: info: Received Get System Info May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:17:10 volumio volumio[3196]: info: Discovery: Getting this device information May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::volumioGetState May 31 06:17:10 volumio sudo[5208]: pam_unix(sudo:session): session closed for user root May 31 06:17:10 volumio volumio[3196]: info: CorePlayQueue::getTrack 0 May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:17:10 volumio sudo[5211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 31 06:17:10 volumio sudo[5211]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:17:10 volumio volumio[3196]: error: Cannot start Volumio Streaming Daemon May 31 06:17:10 volumio volumio[3196]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 06:17:10 volumio volumio[3196]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 06:17:10 volumio sudo[5211]: pam_unix(sudo:session): session closed for user root May 31 06:17:10 volumio volumio[3196]: info: Remote SSH Stopped May 31 06:17:10 volumio volumio[3196]: info: Setting Geolocation for MyVolumio to eu11 May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:10 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:10 volumio hostapd[3192]: wlan0: STA 56:8c:c5:af:0d:6b IEEE 802.11: disassociated May 31 06:17:10 volumio kernel: wl_notify_connect_status_ap: event WLC_E_DISASSOC_IND(12) status 0 reason 8 May 31 06:17:10 volumio kernel: wl_notify_connect_status_ap: disassociated device 56:8c:c5:af:0d:6b May 31 06:17:10 volumio kernel: wl_cfg80211_del_station: Disconnect STA : 56:8c:c5:af:0d:6b scb_val.val 3 May 31 06:17:11 volumio volumio[3196]: info: Received Get System Info May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:17:11 volumio volumio[3196]: info: Discovery: Getting this device information May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::volumioGetState May 31 06:17:11 volumio volumio[3196]: info: CorePlayQueue::getTrack 0 May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 06:17:11 volumio volumio[3196]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 06:17:11 volumio volumio[3196]: info: Updating MyVolumio device info May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:11 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:12 volumio volumio[3196]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 06:17:12 volumio volumio5-onboarding[3715]: time=2026-05-31T06:17:12.203+03:00 level=INFO msg="service successfully established" component=discovery/localnet May 31 06:17:14 volumio volumio-time-update[2866]: volumio-time-update-util: Fetching time from Volumio... May 31 06:17:14 volumio volumio-time-update[2866]: volumio-time-update-util: Setting system time to: 2026-05-31 06:17:14 May 31 06:17:14 volumio sudo[5231]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-05-31 06:17:14 May 31 06:17:14 volumio sudo[5231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:17:14 volumio dbus-daemon[2849]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.47' (uid=0 pid=5238 comm="timedatectl set-time 2026-05-31 06:17:14 " label="kernel") May 31 06:17:14 volumio systemd[1]: Starting Time & Date Service... May 31 06:17:14 volumio volumio[3196]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 06:17:15 volumio volumio[3196]: info: MYVOLUMIO: Adding device May 31 06:17:15 volumio volumio[3196]: info: MYVOLUMIO: Evaluating Server May 31 06:17:16 volumio dbus-daemon[2849]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 06:17:16 volumio systemd[1]: Started Time & Date Service. May 31 06:17:14 volumio systemd-timedated[5247]: Changed local time to Sun May 31 06:17:14 2026 May 31 06:17:14 volumio sudo[5231]: pam_unix(sudo:session): session closed for user root May 31 06:17:14 volumio volumio-time-update[2866]: volumio-time-update-util: System time updated successfully. May 31 06:17:14 volumio systemd[1]: Started Volumio Time Update Utility. May 31 06:17:14 volumio systemd[1]: Reached target Multi-User System. May 31 06:17:14 volumio systemd[1]: Reached target Graphical Interface. May 31 06:17:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 31 06:17:14 volumio volumio[3196]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 06:17:14 volumio volumio[3196]: error: Failed to ping endpoint au1.myvolumio.org : unknown error May 31 06:17:14 volumio volumio[3196]: info: Setting Geolocation for MyVolumio to eu4 May 31 06:17:14 volumio volumio[3196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:17:14 volumio volumio[3196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 06:17:14 volumio volumio[3196]: Error: Unable to resolve or reject the same promise twice May 31 06:17:14 volumio volumio[3196]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 31 06:17:14 volumio volumio[3196]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) May 31 06:17:14 volumio volumio[3196]: at Socket.emit (events.js:412:35) May 31 06:17:14 volumio volumio[3196]: at endReadableNT (internal/streams/readable.js:1333:12) May 31 06:17:14 volumio volumio[3196]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 31 06:17:14 volumio volumio[3196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 06:17:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 31 06:17:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 31 06:17:14 volumio systemd[1]: Startup finished in 13.011s (kernel) + 4min 12.836s (userspace) = 4min 25.847s. May 31 06:17:14 volumio sudo[5294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 06:16 May 31 06:17:14 volumio sudo[5294]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 12:16:07 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="88686576587e39571d6cf25f3920fbc0"