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"