Apr 11 19:02:08 volumiostreamer volumio5-onboarding[2064]: time=2026-04-11T19:02:08.472+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=164 chunks=1 index=0 tries=0 Apr 11 19:02:08 volumiostreamer volumio5-onboarding[2064]: time=2026-04-11T19:02:08.584+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=a400 addr=164 Apr 11 19:03:09 volumiostreamer volumio5-onboarding[2064]: time=2026-04-11T19:03:09.398+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=164 chunks=1 index=0 tries=0 Apr 11 19:03:09 volumiostreamer volumio5-onboarding[2064]: time=2026-04-11T19:03:09.698+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=164 chunks=1 index=0 tries=1 Apr 11 19:03:09 volumiostreamer volumio5-onboarding[2064]: time=2026-04-11T19:03:09.936+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=a400 addr=164 Apr 11 19:03:10 volumiostreamer volumio5-onboarding[2064]: time=2026-04-11T19:03:10.056+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=a400 addr=164 Apr 11 19:03:17 volumiostreamer volumio[1463]: info: [now-playing] Fetching API key... Apr 11 19:03:17 volumiostreamer volumio[1463]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Could not obtain API key Apr 11 19:03:17 volumiostreamer volumio[1463]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Could not obtain API key Apr 11 19:03:17 volumiostreamer volumio[1463]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:103:19) Apr 11 19:03:17 volumiostreamer volumio[1463]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Apr 11 19:03:17 volumiostreamer volumio[1463]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:28) Apr 11 19:03:27 volumiostreamer volumio[1463]: info: getStreamUrl took 1018814 milliseconds Apr 11 19:03:27 volumiostreamer volumio[1463]: error: Could not get TIDAL Stream URL: Could not get TIDAL Stream URL Apr 11 19:03:54 volumiostreamer thd[1069]: Executing trigger action: volumio toggle Apr 11 19:03:54 volumiostreamer thd[1069]: Executing trigger action: volumio toggle Apr 11 19:03:54 volumiostreamer systemd-logind[1067]: Power key pressed short. Apr 11 19:03:54 volumiostreamer systemd-logind[1067]: Powering off... Apr 11 19:03:54 volumiostreamer thd[7833]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 11 19:03:54 volumiostreamer thd[7833]: Dload Upload Total Spent Left Speed Apr 11 19:03:54 volumiostreamer systemd-logind[1067]: System is powering down. Apr 11 19:03:54 volumiostreamer thd[7833]: [79B blob data] Apr 11 19:03:54 volumiostreamer thd[7834]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 11 19:03:54 volumiostreamer thd[7834]: Dload Upload Total Spent Left Speed Apr 11 19:03:54 volumiostreamer volumio[1463]: info: Apr 11 19:03:54 volumiostreamer volumio[1463]: ---------------------------- Client requests Volumio toggle Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreCommandRouter::volumioToggle Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CorePlayQueue::getTrack 12 Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreStateMachine::play index undefined Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CorePlayQueue::getTrack 12 Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreStateMachine::startPlaybackTimer Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CorePlayQueue::getTrack 12 Apr 11 19:03:54 volumiostreamer volumio[1463]: info: [1775927034519] ControllerTidal::clearAddPlayTrack Apr 11 19:03:54 volumiostreamer volumio[1463]: info: Getting stream with soundQuality LOSSLESS Apr 11 19:03:54 volumiostreamer thd[7834]: [79B blob data] Apr 11 19:03:54 volumiostreamer volumio[1463]: info: Apr 11 19:03:54 volumiostreamer volumio[1463]: ---------------------------- Client requests Volumio toggle Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreCommandRouter::volumioToggle Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CorePlayQueue::getTrack 12 Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreStateMachine::play index undefined Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CorePlayQueue::getTrack 12 Apr 11 19:03:54 volumiostreamer thd[7833]: [79B blob data] Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CoreStateMachine::startPlaybackTimer Apr 11 19:03:54 volumiostreamer volumio[1463]: info: CorePlayQueue::getTrack 12 Apr 11 19:03:54 volumiostreamer volumio[1463]: info: [1775927034525] ControllerTidal::clearAddPlayTrack Apr 11 19:03:54 volumiostreamer volumio[1463]: info: Getting stream with soundQuality LOSSLESS Apr 11 19:03:54 volumiostreamer thd[7833]: {"time":1775927034519,"response":"toggle Success"} Apr 11 19:03:54 volumiostreamer thd[7834]: [79B blob data] Apr 11 19:03:54 volumiostreamer systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped target graphical.target - Graphical Interface. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped target rpc_pipefs.target. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped target timers.target - Timer Units. Apr 11 19:03:54 volumiostreamer systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Apr 11 19:03:54 volumiostreamer systemd[1]: apt-daily.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Apr 11 19:03:54 volumiostreamer systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Apr 11 19:03:54 volumiostreamer systemd[1]: e2scrub_all.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Apr 11 19:03:54 volumiostreamer systemd[1]: fstrim.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Apr 11 19:03:54 volumiostreamer systemd[1]: man-db.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped man-db.timer - Daily man-db regeneration. Apr 11 19:03:54 volumiostreamer systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Apr 11 19:03:54 volumiostreamer systemd[1]: setdatetime-helper.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Apr 11 19:03:54 volumiostreamer systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 11 19:03:54 volumiostreamer systemd[1]: systemd-rfkill.socket: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 11 19:03:54 volumiostreamer systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping bluealsa.service - BlueALSA service... Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 11 19:03:54 volumiostreamer bluealsa[1157]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 11 19:03:54 volumiostreamer systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Apr 11 19:03:54 volumiostreamer volumio[1463]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:03:54 volumiostreamer qobuz-connect[2430]: 20260411 19:03:54.591 [2430.2430] INFO SampleApp: Stopping Local configuration server Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Apr 11 19:03:54 volumiostreamer autossh[2934]: received signal to exit (15) Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping upower.service - Daemon for power management... Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Apr 11 19:03:54 volumiostreamer volumio[1463]: Error: socket hang up Apr 11 19:03:54 volumiostreamer volumio[1463]: at connResetException (node:internal/errors:720:14) Apr 11 19:03:54 volumiostreamer volumio[1463]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 11 19:03:54 volumiostreamer volumio[1463]: at Socket.emit (node:events:526:35) Apr 11 19:03:54 volumiostreamer volumio[1463]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 11 19:03:54 volumiostreamer volumio[1463]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 11 19:03:54 volumiostreamer volumio[1463]: code: 'ECONNRESET', Apr 11 19:03:54 volumiostreamer volumio[1463]: response: undefined Apr 11 19:03:54 volumiostreamer volumio[1463]: } Apr 11 19:03:54 volumiostreamer volumio[1463]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Apr 11 19:03:54 volumiostreamer systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 11 19:03:54 volumiostreamer volumiobt[7845]: INFO [BTSTART] Disconnecting all Bluetooth devices... Apr 11 19:03:54 volumiostreamer systemd[1]: bluealsa.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped bluealsa.service - BlueALSA service. Apr 11 19:03:54 volumiostreamer systemd[1]: upower.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped upower.service - Daemon for power management. Apr 11 19:03:54 volumiostreamer systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 11 19:03:54 volumiostreamer systemd[1]: volumio5-onboarding.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 11 19:03:54 volumiostreamer systemd[1]: volumio5-onboarding.service: Consumed 2.407s CPU time. Apr 11 19:03:54 volumiostreamer systemd[1]: vtcs.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 11 19:03:54 volumiostreamer systemd[1]: sshtunnel.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Apr 11 19:03:54 volumiostreamer systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Apr 11 19:03:54 volumiostreamer systemd[1]: systemd-random-seed.service: Deactivated successfully. Apr 11 19:03:54 volumiostreamer systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Path / reserved for Adv Monitor app :1.58 Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Adv Monitor app :1.58 disconnected from D-Bus Apr 11 19:03:54 volumiostreamer volumiobt[7863]: Attempting to disconnect from 74:A1:32:F4:CD:19 Apr 11 19:03:54 volumiostreamer volumiobt[7863]: [NEW] Media /org/bluez/hci0 Apr 11 19:03:54 volumiostreamer volumiobt[7863]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 11 19:03:54 volumiostreamer volumiobt[7863]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 11 19:03:54 volumiostreamer volumiobt[7863]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Apr 11 19:03:54 volumiostreamer bluetoothd[1059]: Path / reserved for Adv Monitor app :1.59 Apr 11 19:03:54 volumiostreamer volumiobt[7863]: AdvertisementMonitor path registered Apr 11 19:03:54 volumiostreamer sudo[7866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 19:02' Apr 11 19:03:54 volumiostreamer sudo[7866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="78457f783d63b89c4d83aa18ac27544b9e82ea5a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jan 17 12:50:56 UTC 2026" VOLUMIO_VERSION="4.088" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e6591c42f2c96d67eeb9dd31105c420"