-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Thu 2026-04-02 21:19:13 BST. -- Apr 02 21:18:00 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 21:18:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 21:18:00 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 02 21:18:00 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 02 21:18:00 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 02 21:18:02 volumio kernel: hwmon hwmon4: Voltage normalised Apr 02 21:18:04 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:04.225+01:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Apr 02 21:18:04 volumio dbus-daemon[808]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.58" (uid=0 pid=1789 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=926 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Apr 02 21:18:05 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:05.876+01:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Apr 02 21:18:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 21:18:08 volumio volumio[1142]: info: Received Get System Info Apr 02 21:18:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 21:18:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 21:18:08 volumio volumio[1142]: info: Discovery: Getting this device information Apr 02 21:18:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:08 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 21:18:10 volumio volumio[1142]: info: Retrieving Cloud Streaming UI Apr 02 21:18:10 volumio volumio[1142]: info: Getting Tidal Cloud Configuration Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 21:18:10 volumio volumio[1142]: info: Getting Qobuz Cloud Configuration Apr 02 21:18:10 volumio volumio[1142]: info: Asking plugin for UI Config Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 21:18:10 volumio volumio[1142]: info: Getting Spotify Cloud Configuration Apr 02 21:18:10 volumio volumio[1142]: info: Asking plugin for UI Config Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 21:18:10 volumio volumio[1142]: info: Saving Spotify Acccount Apr 02 21:18:10 volumio volumio[1142]: info: Got it Apr 02 21:18:10 volumio volumio[1142]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 02 21:18:10 volumio volumio[1142]: info: Got it Apr 02 21:18:10 volumio volumio[1142]: info: Got Tidal Cloud Configuration Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 21:18:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 02 21:18:11 volumio dbus-daemon[808]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.58" (uid=0 pid=1789 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=926 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 02 21:18:14 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand update Apr 02 21:18:14 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:18:14 volumio volumio[1142]: info: Apr 02 21:18:14 volumio volumio[1142]: ---------------------------- MPD announces state update: update Apr 02 21:18:14 volumio volumio[1142]: info: sendMpdCommand update took 5 milliseconds Apr 02 21:18:14 volumio volumio[1142]: info: ControllerMpd::getState Apr 02 21:18:14 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:18:14 volumio volumio[1142]: info: sendMpdCommand status took 7 milliseconds Apr 02 21:18:14 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Apr 02 21:18:14 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 02 21:18:14 volumio volumio[1142]: info: Command Router : Notfying DB Updatetrue Apr 02 21:18:14 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 02 21:18:14 volumio volumio[1142]: info: ControllerMpd::pushState Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 02 21:18:14 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:14 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:14 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 02 21:18:14 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 02 21:18:14 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 02 21:18:14 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 02 21:18:14 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:14 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:14 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio[1142]: info: No code Apr 02 21:18:14 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:14 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:14 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:14 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:14 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:14 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:14.767+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:14 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:14.767+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:14 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:14.768+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:14 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:14.768+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:14 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:14.768+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:14 volumio volumio[1142]: info: ------------------------------ 55ms Apr 02 21:18:15 volumio kernel: hwmon hwmon4: Undervoltage detected! Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 21:18:15 volumio volumio[1142]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 02 21:18:15 volumio volumio[1142]: info: Received Get System Version Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 21:18:15 volumio volumio[1142]: info: Received Get System Info Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 21:18:15 volumio volumio[1142]: info: Discovery: Getting this device information Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 21:18:15 volumio volumio[1142]: info: MPD Database updated - AlbumList cache refreshed Apr 02 21:18:15 volumio volumio[1142]: info: Apr 02 21:18:15 volumio volumio[1142]: ---------------------------- MPD announces state update: database Apr 02 21:18:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:18:15 volumio volumio[1142]: info: Apr 02 21:18:15 volumio volumio[1142]: ---------------------------- MPD announces state update: update Apr 02 21:18:15 volumio volumio[1142]: info: ControllerMpd::getState Apr 02 21:18:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:18:15 volumio volumio[1142]: info: ControllerMpd::getState Apr 02 21:18:15 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Apr 02 21:18:16 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent Apr 02 21:18:17 volumio volumio[1142]: info: sendMpdCommand status took 1164 milliseconds Apr 02 21:18:17 volumio volumio[1142]: info: sendMpdCommand status took 1165 milliseconds Apr 02 21:18:17 volumio volumio[1142]: info: sendMpdCommand status took 1165 milliseconds Apr 02 21:18:17 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 02 21:18:17 volumio volumio[1142]: info: Command Router : Notfying DB Updatefalse Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent Apr 02 21:18:17 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 02 21:18:17 volumio volumio[1142]: verbose: ControllerMpd::parseState Apr 02 21:18:17 volumio volumio[1142]: info: ControllerMpd::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 02 21:18:17 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: No code Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: ControllerMpd::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 02 21:18:17 volumio volumio[1142]: verbose: CURRENT POSITION 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: No code Apr 02 21:18:17 volumio volumio[1142]: info: CoreStateMachine::pushState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output update for this device Apr 02 21:18:17 volumio volumio[1142]: info: MRS: Pushing multiroomSync output Apr 02 21:18:17 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:17 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.204+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.204+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.205+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.205+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.206+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.206+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.206+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.206+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.207+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.207+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.207+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" state=STATUS_STOPPED positionMs=0 volume=39 Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.207+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.118:34580 @ 0x17bc5a0" id="music-library/INTERNAL/FLAC/Alicia Keys/Girl on Fire/06. Girl On Fire [Inferno Version].flac" title="6 - Girl On Fire [Inferno Version]" Apr 02 21:18:17 volumio volumio[1142]: info: ------------------------------ 1225ms Apr 02 21:18:17 volumio volumio[1142]: info: ------------------------------ 1225ms Apr 02 21:18:17 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:17.576+01:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Apr 02 21:18:17 volumio dbus-daemon[808]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.58" (uid=0 pid=1789 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=926 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Apr 02 21:18:18 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 21:18:21 volumio kernel: hwmon hwmon4: Voltage normalised Apr 02 21:18:22 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 02 21:18:22 volumio dbus-daemon[808]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.58" (uid=0 pid=1789 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=926 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Apr 02 21:18:27 volumio sudo[6434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 02 21:18:27 volumio sudo[6434]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 21:18:27 volumio sudo[6434]: pam_unix(sudo:session): session closed for user root Apr 02 21:18:27 volumio sudo[6437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 02 21:18:27 volumio sudo[6437]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 21:18:27 volumio sudo[6437]: pam_unix(sudo:session): session closed for user root Apr 02 21:18:27 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.4.222 from 192.168.4.118 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Pro Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 02 21:18:27 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:27 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:27 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 02 21:18:27 volumio volumio[1142]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 02 21:18:27 volumio volumio[1142]: info: Listing playlists Apr 02 21:18:27 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 21:18:27 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 21:18:27 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 02 21:18:27 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 02 21:18:27 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 02 21:18:29 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 21:18:29 volumio volumio[1142]: info: Received Get System Info Apr 02 21:18:29 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 21:18:29 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 21:18:29 volumio volumio[1142]: info: Discovery: Getting this device information Apr 02 21:18:29 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:29 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:29 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 21:18:33 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:33.576+01:00 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Apr 02 21:18:33 volumio dbus-daemon[808]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.58" (uid=0 pid=1789 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=926 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Apr 02 21:18:36 volumio volumio5-onboarding[1789]: time=2026-04-02T21:18:36.621+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.4.118:34580 @ 0x17bc5a0" latency=-1.615893294s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 02 21:18:36 volumio sudo[6455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 02 21:18:36 volumio sudo[6455]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 21:18:36 volumio sudo[6455]: pam_unix(sudo:session): session closed for user root Apr 02 21:18:36 volumio sudo[6458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 02 21:18:36 volumio sudo[6458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 21:18:36 volumio sudo[6458]: pam_unix(sudo:session): session closed for user root Apr 02 21:18:37 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.4.222 from 192.168.4.118 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Pro Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 02 21:18:37 volumio volumio[1142]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:37 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 21:18:37 volumio sudo[6461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 02 21:18:37 volumio sudo[6461]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 21:18:37 volumio sudo[6461]: pam_unix(sudo:session): session closed for user root Apr 02 21:18:37 volumio sudo[6463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 02 21:18:37 volumio sudo[6463]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 21:18:37 volumio sudo[6463]: pam_unix(sudo:session): session closed for user root Apr 02 21:18:37 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.4.222 from 192.168.4.118 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Pro Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:37 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 02 21:18:37 volumio volumio[1142]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 02 21:18:37 volumio volumio[1142]: info: Listing playlists Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 21:18:37 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 21:18:38 volumio dbus-daemon[808]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.58" (uid=0 pid=1789 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=926 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Apr 02 21:18:41 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 21:18:41 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 21:18:41 volumio volumio[1142]: info: CoreCommandRouter::volumioGetQueue Apr 02 21:18:41 volumio volumio[1142]: info: CoreStateMachine::getQueue Apr 02 21:18:41 volumio volumio[1142]: info: CorePlayQueue::getQueue Apr 02 21:18:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 02 21:18:44 volumio volumio[1142]: info: CURURI: music-library Apr 02 21:18:44 volumio volumio[1142]: info: Preload queue cleared Apr 02 21:18:52 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 02 21:18:52 volumio volumio[1142]: info: CURURI: albums:// Apr 02 21:18:52 volumio volumio[1142]: info: listAlbums - loading Albums from cache Apr 02 21:18:52 volumio volumio[1142]: info: Preload queue cleared Apr 02 21:18:53 volumio kernel: hwmon hwmon4: Undervoltage detected! Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 21:18:55 volumio volumio[1142]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 02 21:18:55 volumio volumio[1142]: info: Received Get System Version Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 21:18:55 volumio volumio[1142]: info: Received Get System Info Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 21:18:55 volumio volumio[1142]: info: Discovery: Getting this device information Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Apr 02 21:18:55 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Apr 02 21:18:55 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 21:18:55 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bjork/Debut/73d272ab-fbec-454a-a70e-3417d87b14a9.jpg' Apr 02 21:18:57 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dave%20Stewart%20and%20The%20Spiritual%20Cowboys/Dave%20Stewart%20and%20The%20Spiritual%20Cowboys/11eb81ed-3be2-4597-83af-f542f258dbc3.jpg' Apr 02 21:19:08 volumio volumio[1142]: Searching all installed plugins Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 21:19:08 volumio volumio[1142]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 21:19:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 02 21:19:09 volumio volumio[1142]: Searching all installed plugins Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 21:19:09 volumio volumio[1142]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 21:19:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 02 21:19:09 volumio kernel: hwmon hwmon4: Voltage normalised Apr 02 21:19:09 volumio volumio[1142]: info: An error occurred while querying SHOUTCAST Apr 02 21:19:11 volumio volumio[1142]: info: An error occurred while querying SHOUTCAST Apr 02 21:19:11 volumio volumio[1142]: info: searchTIDALUri took 3374 milliseconds Apr 02 21:19:11 volumio volumio[1142]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Renaissance/The%20Other%20Woman/729d81d1-4048-4a34-8404-930562243231.jpg' Apr 02 21:19:12 volumio volumio[1142]: info: searchTIDALUri took 3115 milliseconds Apr 02 21:19:12 volumio volumio[1142]: info: search took 3116 milliseconds Apr 02 21:19:12 volumio volumio[1142]: info: All search sources collected, pushing search results Apr 02 21:19:13 volumio volumio[1142]: error: Search in plugin tidal timed out Apr 02 21:19:13 volumio volumio[1142]: info: All search sources collected, pushing search results Apr 02 21:19:13 volumio volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 21:19:13 volumio volumio[1142]: TypeError: Cannot read property 'slice' of null Apr 02 21:19:13 volumio volumio[1142]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 02 21:19:13 volumio volumio[1142]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 02 21:19:13 volumio volumio[1142]: at Object.onceWrapper (events.js:422:26) Apr 02 21:19:13 volumio volumio[1142]: at ClientRequest.emit (events.js:315:20) Apr 02 21:19:13 volumio volumio[1142]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 02 21:19:13 volumio volumio[1142]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 02 21:19:13 volumio volumio[1142]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 02 21:19:13 volumio volumio[1142]: at TLSSocket.emit (events.js:315:20) Apr 02 21:19:13 volumio volumio[1142]: at addChunk (internal/streams/readable.js:309:12) Apr 02 21:19:13 volumio volumio[1142]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 02 21:19:13 volumio volumio[1142]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Apr 02 21:19:13 volumio volumio[1142]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 02 21:19:13 volumio volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 21:19:13 volumio sudo[6574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 21:18 Apr 02 21:19:13 volumio sudo[6574]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"